You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/08/15 15:26:00 UTC
[JENKINS] Lucene-Solr-4.x-Linux
(64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0) - Build # 6939 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/6939/
Java: 64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0 -XnoOpt
1 tests failed.
REGRESSION: org.apache.solr.update.SolrCmdDistributorTest.testDistribSearch
Error Message:
SOLR-3428: We only did adds - there should be no deletes expected:<548> but was:<549>
Stack Trace:
java.lang.AssertionError: SOLR-3428: We only did adds - there should be no deletes expected:<548> but was:<549>
at __randomizedtesting.SeedInfo.seed([F16A515E53E6C331:708CDF4624B9A30D]: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.apache.solr.update.SolrCmdDistributorTest.doTest(SolrCmdDistributorTest.java:277)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:774)
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:683)
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:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:44)
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:56)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:662)
Build Log:
[...truncated 9426 lines...]
[junit4] Suite: org.apache.solr.update.SolrCmdDistributorTest
[junit4] 2> 1238369 T3704 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /sx_xr/e
[junit4] 2> 1238437 T3704 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 1238437 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=10000&connTimeout=10000
[junit4] 2> 1238444 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1238452 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:47882
[junit4] 2> 1238453 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1238453 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1238453 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 1238454 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 1238474 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 1238535 T3704 oasc.CoreContainer.<init> New CoreContainer 474080358
[junit4] 2> 1238536 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 1238538 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1238539 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1238539 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1238540 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1238540 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1238541 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1238541 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1238542 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1238542 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1238543 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1238554 T3714 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 1238554 T3714 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 1238555 T3714 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1238555 T3714 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1238600 T3714 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1238656 T3714 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1238657 T3714 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1238667 T3714 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1239178 T3714 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1239178 T3714 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1239179 T3714 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1239186 T3714 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1239189 T3714 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1239194 T3714 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1239197 T3714 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1239200 T3714 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1239200 T3714 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1239200 T3714 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1239200 T3714 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1239201 T3714 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1239201 T3714 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1239201 T3714 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1239201 T3714 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data/
[junit4] 2> 1239202 T3714 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
[junit4] 2> 1239202 T3714 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data
[junit4] 2> 1239202 T3714 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data/index/
[junit4] 2> 1239203 T3714 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 1239203 T3714 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data/index
[junit4] 2> 1239204 T3714 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1239204 T3714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1239207 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1239207 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1239207 T3714 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1239207 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1239208 T3714 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1239208 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1239208 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1239208 T3714 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1239209 T3714 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1239209 T3714 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1239210 T3714 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1239210 T3714 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1239210 T3714 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1239211 T3714 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1239211 T3714 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1239213 T3714 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1239216 T3714 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1239216 T3714 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1239217 T3714 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1239217 T3714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1239218 T3714 oass.SolrIndexSearcher.<init> Opening Searcher@1ca48390 main
[junit4] 2> 1239221 T3715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ca48390 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1239222 T3714 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1239223 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1239223 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1239224 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1239227 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1239228 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:47436
[junit4] 2> 1239229 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1239229 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1239229 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 1239230 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 1239241 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 1239287 T3704 oasc.CoreContainer.<init> New CoreContainer 487304869
[junit4] 2> 1239288 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 1239289 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1239290 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1239290 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1239290 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1239291 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1239291 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1239291 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1239291 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1239292 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1239292 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1239299 T3726 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 1239299 T3726 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 1239299 T3726 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1239300 T3726 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1239350 T3726 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1239402 T3726 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1239402 T3726 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1239414 T3726 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1239913 T3726 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1239914 T3726 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1239914 T3726 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1239921 T3726 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1239925 T3726 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1239930 T3726 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1239932 T3726 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1239935 T3726 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1239935 T3726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1239935 T3726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1239935 T3726 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1239936 T3726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1239936 T3726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1239936 T3726 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1239936 T3726 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data/
[junit4] 2> 1239937 T3726 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
[junit4] 2> 1239937 T3726 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data
[junit4] 2> 1239937 T3726 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data/index/
[junit4] 2> 1239938 T3726 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data/index' doesn't exist. Creating new index...
[junit4] 2> 1239938 T3726 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data/index
[junit4] 2> 1239939 T3726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1239939 T3726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1239942 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1239942 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1239942 T3726 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1239943 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1239943 T3726 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1239943 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1239943 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1239944 T3726 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1239944 T3726 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1239945 T3726 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1239945 T3726 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1239945 T3726 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1239946 T3726 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1239946 T3726 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1239947 T3726 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1239948 T3726 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1239951 T3726 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1239951 T3726 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1239952 T3726 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1239953 T3726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1239953 T3726 oass.SolrIndexSearcher.<init> Opening Searcher@1c7384d4 main
[junit4] 2> 1239959 T3727 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c7384d4 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1239961 T3726 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1239961 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1239962 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1239962 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1239965 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1239967 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49644
[junit4] 2> 1239967 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1239968 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1239968 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 1239968 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 1239979 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 1240023 T3704 oasc.CoreContainer.<init> New CoreContainer 478110690
[junit4] 2> 1240024 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 1240025 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1240025 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1240025 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1240026 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1240026 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1240026 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1240027 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1240027 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1240027 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1240028 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1240034 T3738 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 1240034 T3738 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 1240035 T3738 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1240035 T3738 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1240083 T3738 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1240131 T3738 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1240131 T3738 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1240141 T3738 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1240635 T3738 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1240635 T3738 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1240636 T3738 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1240643 T3738 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1240646 T3738 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1240651 T3738 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1240654 T3738 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1240660 T3738 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1240661 T3738 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1240661 T3738 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1240661 T3738 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1240662 T3738 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1240662 T3738 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1240662 T3738 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1240662 T3738 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/
[junit4] 2> 1240662 T3738 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
[junit4] 2> 1240663 T3738 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data
[junit4] 2> 1240663 T3738 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index/
[junit4] 2> 1240663 T3738 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1240664 T3738 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index
[junit4] 2> 1240665 T3738 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c3f660a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c3f6eec; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1240665 T3738 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1240668 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1240668 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1240668 T3738 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1240668 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1240669 T3738 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1240669 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1240669 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1240669 T3738 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1240670 T3738 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1240670 T3738 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1240671 T3738 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1240671 T3738 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1240671 T3738 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1240672 T3738 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1240673 T3738 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1240674 T3738 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1240677 T3738 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1240677 T3738 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1240678 T3738 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c3f660a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c3f6eec; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1240678 T3738 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1240679 T3738 oass.SolrIndexSearcher.<init> Opening Searcher@1c483675 main
[junit4] 2> 1240684 T3739 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c483675 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1240687 T3738 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1240687 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1240688 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1240688 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1240693 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1240696 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54278
[junit4] 2> 1240696 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1240697 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1240697 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 1240698 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 1240715 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 1240784 T3704 oasc.CoreContainer.<init> New CoreContainer 474106985
[junit4] 2> 1240784 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 1240786 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1240786 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1240786 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1240787 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1240787 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1240787 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1240788 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1240788 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1240788 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1240789 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1240795 T3750 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 1240795 T3750 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 1240795 T3750 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1240795 T3750 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1240835 T3750 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1240883 T3750 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1240883 T3750 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1240893 T3750 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1241387 T3750 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1241388 T3750 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1241388 T3750 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1241395 T3750 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1241400 T3750 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1241405 T3750 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1241407 T3750 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1241410 T3750 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1241410 T3750 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1241411 T3750 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1241411 T3750 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1241411 T3750 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1241411 T3750 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1241412 T3750 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1241412 T3750 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/
[junit4] 2> 1241412 T3750 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
[junit4] 2> 1241413 T3750 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data
[junit4] 2> 1241413 T3750 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index/
[junit4] 2> 1241413 T3750 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index' doesn't exist. Creating new index...
[junit4] 2> 1241413 T3750 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index
[junit4] 2> 1241414 T3750 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1d18973f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d18a020; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1241414 T3750 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1241417 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1241418 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1241418 T3750 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1241419 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1241419 T3750 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1241419 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1241419 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1241420 T3750 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1241420 T3750 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1241421 T3750 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1241421 T3750 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1241422 T3750 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1241422 T3750 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1241423 T3750 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1241423 T3750 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1241424 T3750 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1241427 T3750 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1241428 T3750 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1241429 T3750 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1d18973f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d18a020; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1241429 T3750 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1241429 T3750 oass.SolrIndexSearcher.<init> Opening Searcher@1d1e4a78 main
[junit4] 2> 1241435 T3751 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d1e4a78 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1241437 T3750 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1241438 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1241438 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1241439 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1241442 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1241443 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:40898
[junit4] 2> 1241444 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1241444 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1241445 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 1241445 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 1241457 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 1241505 T3704 oasc.CoreContainer.<init> New CoreContainer 488841172
[junit4] 2> 1241506 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 1241507 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1241507 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1241508 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1241508 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1241508 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1241509 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1241509 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1241509 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1241510 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1241510 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1241516 T3762 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 1241516 T3762 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 1241517 T3762 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1241517 T3762 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1241557 T3762 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1241604 T3762 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1241604 T3762 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1241614 T3762 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1242120 T3762 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1242120 T3762 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1242121 T3762 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1242128 T3762 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1242132 T3762 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1242137 T3762 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1242139 T3762 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1242142 T3762 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1242142 T3762 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1242143 T3762 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1242143 T3762 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1242143 T3762 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1242143 T3762 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1242144 T3762 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1242144 T3762 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/
[junit4] 2> 1242144 T3762 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
[junit4] 2> 1242145 T3762 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data
[junit4] 2> 1242145 T3762 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index/
[junit4] 2> 1242145 T3762 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index' doesn't exist. Creating new index...
[junit4] 2> 1242146 T3762 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index
[junit4] 2> 1242146 T3762 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c98dc5d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c98e540; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1242147 T3762 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1242150 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1242150 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1242150 T3762 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1242151 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1242151 T3762 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1242151 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1242151 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1242152 T3762 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1242152 T3762 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1242153 T3762 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1242153 T3762 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1242154 T3762 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1242154 T3762 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1242155 T3762 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1242155 T3762 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1242156 T3762 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1242160 T3762 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1242160 T3762 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1242161 T3762 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c98dc5d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c98e540; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> 1242161 T3762 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1242162 T3762 oass.SolrIndexSearcher.<init> Opening Searcher@1ca1eb8f main
[junit4] 2> 1242168 T3763 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ca1eb8f main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1242170 T3762 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1242171 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1242171 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1242171 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C1071 name=collection1 org.apache.solr.core.SolrCore@1ca08f5b
[junit4] 2> 1242186 T3713 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132571721728)} 0 1
[junit4] 2> ASYNC NEW_CORE C1072 name=collection1 org.apache.solr.core.SolrCore@1c6a667b
[junit4] 2> 1242196 T3722 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132583256064)} 0 1
[junit4] 2> ASYNC NEW_CORE C1073 name=collection1 org.apache.solr.core.SolrCore@1c3ed7bf
[junit4] 2> 1242204 T3736 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132590596096)} 0 1
[junit4] 2> ASYNC NEW_CORE C1074 name=collection1 org.apache.solr.core.SolrCore@1cefa7fb
[junit4] 2> 1242211 T3746 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132598984704)} 0 1
[junit4] 2> ASYNC NEW_CORE C1075 name=collection1 org.apache.solr.core.SolrCore@1c984e29
[junit4] 2> 1242218 T3760 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132606324736)} 0 1
[junit4] 2> 1242220 T3710 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[1 (1443441132608421888)]} 0 0
[junit4] 2> 1242221 T3704 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:47882/sx_xr/e/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4] 2> 1242223 T3711 C1071 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1242229 T3711 C1071 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_2,generation=2}
[junit4] 2> 1242230 T3711 C1071 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1242231 T3711 C1071 oass.SolrIndexSearcher.<init> Opening Searcher@1cc84872 main
[junit4] 2> 1242232 T3711 C1071 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1242233 T3715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cc84872 main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):c1)}
[junit4] 2> 1242234 T3711 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 11
[junit4] 2> 1242237 T3713 C1071 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 2> 1242239 T3724 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&commit_end_point=true&version=2} {add=[2 (1443441132627296256), 3 (1443441132628344832), 4 (1443441132628344833)]} 0 1
[junit4] 2> 1242240 T3712 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&commit_end_point=true&version=2} {add=[2 (1443441132628344832), 3 (1443441132629393408)]} 0 1
[junit4] 2> 1242241 T3704 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:47882/sx_xr/e/, StdNode: http://127.0.0.1:47436/sx_xr/e/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4] 2> 1242243 T3710 C1071 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1242243 T3725 C1072 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1242248 T3710 C1071 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_3,generation=3}
[junit4] 2> 1242249 T3710 C1071 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1242250 T3725 C1072 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_2,generation=2}
[junit4] 2> 1242250 T3725 C1072 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1242251 T3710 C1071 oass.SolrIndexSearcher.<init> Opening Searcher@1ccc7101 main
[junit4] 2> 1242251 T3710 C1071 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1242252 T3725 C1072 oass.SolrIndexSearcher.<init> Opening Searcher@1ccf3e11 main
[junit4] 2> 1242252 T3715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ccc7101 main{StandardDirectoryReader(segments_3:5:nrt _0(4.5):c1 _1(4.5):c2)}
[junit4] 2> 1242253 T3725 C1072 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1242253 T3710 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 10
[junit4] 2> 1242255 T3727 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ccf3e11 main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):c3)}
[junit4] 2> 1242255 T3725 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 12
[junit4] 2> 1242257 T3711 C1071 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 2> 1242260 T3722 C1072 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 2> 1242263 T3723 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&commit_end_point=true&version=2} {delete=[2 (-1443441132653510656)]} 0 0
[junit4] 2> 1242263 T3713 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&commit_end_point=true&version=2} {delete=[2 (-1443441132653510656)]} 0 0
[junit4] 2> 1242264 T3704 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:47882/sx_xr/e/, StdNode: http://127.0.0.1:47436/sx_xr/e/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4] 2> 1242265 T3724 C1072 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1242265 T3712 C1071 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1242266 T3724 C1072 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1242266 T3712 C1071 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1242268 T3724 C1072 oass.SolrIndexSearcher.<init> Opening Searcher@1cb0e1d3 main
[junit4] 2> 1242268 T3724 C1072 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1242269 T3727 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cb0e1d3 main{StandardDirectoryReader(segments_2:4:nrt _0(4.5):c3/1)}
[junit4] 2> 1242270 T3712 C1071 oass.SolrIndexSearcher.<init> Opening Searcher@1cc068ae main
[junit4] 2> 1242270 T3724 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 5
[junit4] 2> 1242270 T3712 C1071 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1242271 T3715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cc068ae main{StandardDirectoryReader(segments_3:6:nrt _0(4.5):c1 _1(4.5):c2/1)}
[junit4] 2> 1242272 T3712 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 7
[junit4] 2> 1242274 T3710 C1071 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 1242277 T3725 C1072 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 2> 1242279 T3722 C1072 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1242285 T3722 C1072 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_3,generation=3}
[junit4] 2> 1242286 T3722 C1072 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1242288 T3722 C1072 oass.SolrIndexSearcher.<init> Opening Searcher@1ca7ea79 main
[junit4] 2> 1242288 T3722 C1072 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1242289 T3727 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ca7ea79 main{StandardDirectoryReader(segments_2:4:nrt _0(4.5):c3/1)}
[junit4] 2> 1242290 T3722 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 11
[junit4] 2> 1242292 T3737 C1073 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1242292 T3737 C1073 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1242293 T3737 C1073 oass.SolrIndexSearcher.<init> Opening Searcher@1cab2023 main
[junit4] 2> 1242293 T3737 C1073 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1242294 T3739 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cab2023 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1242295 T3737 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 3
[junit4] 2> 1242297 T3747 C1074 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1242298 T3747 C1074 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1242298 T3747 C1074 oass.SolrIndexSearcher.<init> Opening Searcher@1cdc43b8 main
[junit4] 2> 1242298 T3747 C1074 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1242300 T3751 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cdc43b8 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1242300 T3747 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 3
[junit4] 2> 1242302 T3761 C1075 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1242303 T3761 C1075 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1242303 T3761 C1075 oass.SolrIndexSearcher.<init> Opening Searcher@1cda9151 main
[junit4] 2> 1242303 T3761 C1075 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1242305 T3763 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cda9151 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1242306 T3761 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 4
[junit4] 2> 1242311 T3758 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[5 (1443441132699648000), 6 (1443441132700696576), 9 (1443441132701745152), 12 (1443441132701745153), 13 (1443441132701745154), 16 (1443441132702793728), 17 (1443441132702793729), 19 (1443441132702793730), 20 (1443441132702793732), 21 (1443441132703842305)]} 0 4
[junit4] 2> 1242311 T3749 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[5 (1443441132700696576), 6 (1443441132700696577), 8 (1443441132701745152), 10 (1443441132701745153), 11 (1443441132701745154), 12 (1443441132702793728), 15 (1443441132702793729), 18 (1443441132702793730), 19 (1443441132703842304), 22 (1443441132703842305)]} 0 3
[junit4] 2> 1242314 T3723 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[6 (1443441132701745152), 9 (1443441132702793728), 11 (1443441132703842304), 12 (1443441132703842305), 15 (1443441132703842306), 16 (1443441132704890880), 17 (1443441132704890882), 19 (1443441132704890884), 22 (1443441132705939456), 23 (1443441132705939458)]} 0 5
[junit4] 2> 1242314 T3761 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[26 (1443441132702793731), 28 (1443441132703842304), 32 (1443441132704890880), 33 (1443441132704890881), 34 (1443441132704890882), 38 (1443441132705939456), 39 (1443441132705939457), 40 (1443441132705939458), 42 (1443441132705939459), 43 (1443441132706988032)]} 0 4
[junit4] 2> 1242316 T3725 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[26 (1443441132704890881), 28 (1443441132704890883), 29 (1443441132705939457), 31 (1443441132705939459), 32 (1443441132706988032), 33 (1443441132706988033), 40 (1443441132706988034), 44 (1443441132708036608), 45 (1443441132708036609), 47 (1443441132708036610)]} 0 4
[junit4] 2> 1242317 T3734 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[5 (1443441132700696576), 6 (1443441132701745152), 8 (1443441132702793728), 9 (1443441132702793730), 13 (1443441132702793731), 14 (1443441132703842304), 15 (1443441132703842305), 21 (1443441132703842306), 22 (1443441132704890880), 23 (1443441132705939456)]} 0 9
[junit4] 2> 1242322 T3760 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[44 (1443441132710133760), 45 (1443441132710133761), 48 (1443441132711182336), 49 (1443441132711182337), 54 (1443441132711182338), 57 (1443441132712230912), 58 (1443441132712230913), 60 (1443441132714328064), 62 (1443441132714328065), 63 (1443441132714328066)]} 0 5
[junit4] 2> 1242319 T3747 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[49 (1443441132708036608), 50 (1443441132708036609), 51 (1443441132709085184), 52 (1443441132710133760), 53 (1443441132710133761), 54 (1443441132711182336), 55 (1443441132711182337), 58 (1443441132711182338), 63 (1443441132711182339), 65 (1443441132712230912)]} 0 4
[junit4] 2> 1242324 T3723 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[49 (1443441132713279489), 51 (1443441132714328066), 52 (1443441132714328068), 56 (1443441132715376640), 58 (1443441132715376642), 61 (1443441132715376643), 62 (1443441132716425216), 63 (1443441132716425217), 64 (1443441132716425218), 66 (1443441132717473792)]} 0 4
[junit4] 2> 1242324 T3735 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[24 (1443441132702793729), 25 (1443441132710133761), 27 (1443441132711182336), 28 (1443441132711182337), 29 (1443441132711182338), 31 (1443441132712230912), 32 (1443441132712230913), 34 (1443441132712230914), 36 (1443441132713279488), 37 (1443441132713279491)]} 0 14
[junit4] 2> 1242326 T3759 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[64 (1443441132712230914), 68 (1443441132715376640), 69 (1443441132716425216), 70 (1443441132716425217), 75 (1443441132716425218), 76 (1443441132717473792), 79 (1443441132717473793), 85 (1443441132717473794), 86 (1443441132718522368), 87 (1443441132718522369)]} 0 7
[junit4] 2> 1242325 T3748 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[24 (1443441132709085185), 25 (1443441132712230913), 26 (1443441132713279488), 31 (1443441132713279489), 34 (1443441132713279490), 39 (1443441132714328065), 45 (1443441132715376640), 46 (1443441132716425216), 47 (1443441132717473792), 48 (1443441132718522368)]} 0 9
[junit4] 2> 1242327 T3734 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[75 (1443441132714328065), 76 (1443441132715376640), 77 (1443441132715376642), 78 (1443441132716425216), 79 (1443441132716425218), 80 (1443441132717473792), 81 (1443441132717473795), 82 (1443441132717473797), 84 (1443441132719570945), 87 (1443441132720619520)]} 0 6
[junit4] 2> 1242329 T3722 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[67 (1443441132714328067), 68 (1443441132714328069), 70 (1443441132715376641), 73 (1443441132718522368), 74 (1443441132718522369), 75 (1443441132718522370), 76 (1443441132720619520), 77 (1443441132720619521), 78 (1443441132720619522), 81 (1443441132722716672)]} 0 8
[junit4] 2> 1242330 T3746 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[67 (1443441132714328064), 68 (1443441132714328066), 69 (1443441132715376641), 70 (1443441132718522369), 73 (1443441132718522370), 74 (1443441132719570944), 75 (1443441132719570945), 76 (1443441132719570946), 77 (1443441132719570947), 80 (1443441132720619520)]} 0 9
[junit4] 2> 1242325 T3737 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[39 (1443441132709085184), 43 (1443441132712230915), 44 (1443441132713279490), 47 (1443441132714328064), 49 (1443441132715376641), 50 (1443441132716425217), 52 (1443441132716425219), 53 (1443441132717473793), 54 (1443441132717473794), 55 (1443441132717473796)]} 0 9
[junit4] 2> 1242333 T3736 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[56 (1443441132709085185), 58 (1443441132710133760), 62 (1443441132713279489), 63 (1443441132719570944), 65 (1443441132719570946), 67 (1443441132721668096), 69 (1443441132721668097), 71 (1443441132721668099), 72 (1443441132725862400), 73 (1443441132725862403)]} 0 17
[junit4] 2> 1242336 T3758 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[91 (1443441132721668096), 92 (1443441132721668097), 93 (1443441132722716674), 95 (1443441132725862400), 97 (1443441132725862403), 99 (1443441132726910976), 100 (1443441132726910978), 103 (1443441132729008130), 106 (1443441132729008132), 110 (1443441132730056704)]} 0 8
[junit4] 2> 1242337 T3737 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[114 (1443441132724813824), 115 (1443441132725862401), 116 (1443441132725862405), 118 (1443441132726910976), 120 (1443441132727959553), 121 (1443441132727959555), 122 (1443441132727959557), 125 (1443441132729008128), 126 (1443441132729008131), 132 (1443441132731105280)]} 0 6
[junit4] 2> 1242338 T3748 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[113 (1443441132721668097), 114 (1443441132724813824), 115 (1443441132725862400), 116 (1443441132725862401), 117 (1443441132726910976), 118 (1443441132726910977), 119 (1443441132726910978), 120 (1443441132731105280), 121 (1443441132731105281), 122 (1443441132732153856)]} 0 10
[junit4] 2> 1242339 T3761 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[114 (1443441132722716672), 116 (1443441132725862401), 119 (1443441132725862404), 120 (1443441132726910977), 121 (1443441132726910979), 122 (1443441132731105280), 123 (1443441132732153856), 124 (1443441132732153857), 126 (1443441132732153858), 127 (1443441132733202432)]} 0 10
[junit4] 2> 1242340 T3781 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[136 (1443441132725862402), 138 (1443441132725862404), 142 (1443441132726910977), 144 (1443441132727959552), 147 (1443441132727959554), 148 (1443441132727959556), 149 (1443441132727959558), 150 (1443441132729008129), 152 (1443441132733202433), 153 (1443441132733202434)]} 0 8
[junit4] 2> 1242341 T3749 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[81 (1443441132721668096), 82 (1443441132733202432), 84 (1443441132733202433), 85 (1443441132733202434), 87 (1443441132734251008), 88 (1443441132734251009), 90 (1443441132734251010), 94 (1443441132735299584), 95 (1443441132735299585), 99 (1443441132735299586)]} 0 13
[junit4] 2> 1242343 T3725 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[106 (1443441132722716673), 110 (1443441132723765248), 112 (1443441132723765250), 117 (1443441132723765252), 125 (1443441132724813825), 128 (1443441132724813827), 129 (1443441132733202432), 130 (1443441132734251008), 133 (1443441132734251010), 134 (1443441132735299585)]} 0 14
[junit4] 2> 1242344 T3724 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[82 (1443441132713279488), 85 (1443441132714328064), 86 (1443441132714328065), 87 (1443441132733202433), 90 (1443441132734251009), 93 (1443441132735299584), 94 (1443441132735299586), 97 (1443441132737396737), 98 (1443441132737396739), 102 (1443441132738445312)]} 0 24
[junit4] 2> 1242347 T3758 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[154 (1443441132737396736), 157 (1443441132738445312), 161 (1443441132738445315), 162 (1443441132739493888), 163 (1443441132739493889), 167 (1443441132740542464), 169 (1443441132740542465), 173 (1443441132740542466), 176 (1443441132741591040), 177 (1443441132741591041)]} 0 4
[junit4] 2> 1242349 T3722 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[181 (1443441132737396736), 182 (1443441132737396738), 183 (1443441132739493888), 184 (1443441132739493889), 185 (1443441132739493890), 188 (1443441132740542464), 189 (1443441132740542465), 202 (1443441132742639618), 205 (1443441132742639619), 209 (1443441132743688192)]} 0 6
[junit4] 2> 1242351 T3734 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[89 (1443441132721668098), 90 (1443441132729008130), 91 (1443441132731105281), 94 (1443441132731105282), 99 (1443441132731105283), 100 (1443441132732153856), 101 (1443441132732153857), 103 (1443441132732153858), 107 (1443441132733202432), 110 (1443441132744736769)]} 0 22
[junit4] 2> 1242351 T3723 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[135 (1443441132722716674), 138 (1443441132722716675), 141 (1443441132723765249), 142 (1443441132723765251), 145 (1443441132724813824), 146 (1443441132724813826), 147 (1443441132725862400), 149 (1443441132742639616), 152 (1443441132742639617), 153 (1443441132744736768)]} 0 22
[junit4] 2> ASYNC NEW_CORE C1076 name=collection1 org.apache.solr.core.SolrCore@1c984e29
[junit4] 2> 1242340 T3760 C1076 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[130 (1443441132722716673), 131 (1443441132723765248), 134 (1443441132725862402), 135 (1443441132727959552), 140 (1443441132729008128), 141 (1443441132729008129), 142 (1443441132729008131), 145 (1443441132729008133), 150 (1443441132730056705), 152 (1443441132734251008)]} 0 11
[junit4] 2> ASYNC NEW_CORE C1077 name=collection1 org.apache.solr.core.SolrCore@1c6a667b
[junit4] 2> 1242366 T3724 C1077 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[211 (1443441132758368256), 214 (1443441132759416832), 217 (1443441132759416833), 220 (1443441132759416834), 224 (1443441132760465408), 225 (1443441132760465409), 229 (1443441132760465410), 230 (1443441132760465411), 231 (1443441132761513984), 232 (1443441132761513985)]} 0 3
[junit4] 2> ASYNC NEW_CORE C1078 name=collection1 org.apache.solr.core.SolrCore@1cefa7fb
[junit4] 2> 1242369 T3746 C1078 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[138 (1443441132736348160), 139 (1443441132736348161), 142 (1443441132737396739), 143 (1443441132738445312), 145 (1443441132738445313), 146 (1443441132738445314), 149 (1443441132739493888), 151 (1443441132762562560), 154 (1443441132762562562), 155 (1443441132763611138)]} 0 27
[junit4] 2> ASYNC NEW_CORE C1079 name=collection1 org.apache.solr.core.SolrCore@1c3ed7bf
[junit4] 2> 1242370 T3736 C1079 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[154 (1443441132736348160), 155 (1443441132736348161), 156 (1443441132739493888), 157 (1443441132744736770), 158 (1443441132744736772), 159 (1443441132745785344), 160 (1443441132745785345), 161 (1443441132745785346), 162 (1443441132762562560), 164 (1443441132764659715)]} 0 28
[junit4] 2> 1242363 T3761 C1076 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[212 (1443441132742639616), 215 (1443441132742639617), 216 (1443441132744736770), 218 (1443441132744736771), 221 (1443441132745785344), 223 (1443441132745785346), 224 (1443441132745785348), 225 (1443441132746833920), 226 (1443441132757319680), 227 (1443441132758368256)]} 0 15
[junit4] 2> 1242376 T3749 C1078 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[174 (1443441132740542464), 175 (1443441132762562563), 176 (1443441132763611139), 180 (1443441132763611141), 185 (1443441132764659712), 186 (1443441132764659714), 189 (1443441132769902592), 190 (1443441132770951169), 191 (1443441132770951172), 192 (1443441132770951174)]} 0 30
[junit4] 2> 1242375 T3787 C1078 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[208 (1443441132746833920), 210 (1443441132762562561), 211 (1443441132763611136), 212 (1443441132763611140), 213 (1443441132763611143), 218 (1443441132764659713), 221 (1443441132765708290), 222 (1443441132765708294), 223 (1443441132770951168), 225 (1443441132770951171)]} 0 23
[junit4] 2> 1242374 T3725 C1077 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[233 (1443441132762562561), 234 (1443441132762562563), 236 (1443441132763611138), 237 (1443441132764659712), 238 (1443441132765708290), 241 (1443441132766756865), 242 (1443441132767805440), 244 (1443441132767805442), 245 (1443441132768854016), 246 (1443441132769902592)]} 0 7
[junit4] 2> 1242373 T3737 C1079 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[188 (1443441132737396736), 194 (1443441132740542464), 195 (1443441132744736768), 199 (1443441132744736771), 202 (1443441132764659714), 204 (1443441132765708289), 206 (1443441132766756864), 207 (1443441132767805440), 209 (1443441132768854016), 211 (1443441132768854017)]} 0 30
[junit4] 2> 1242372 T3722 C1077 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[160 (1443441132762562560), 164 (1443441132762562562), 165 (1443441132763611136), 168 (1443441132763611137), 174 (1443441132763611139), 175 (1443441132765708288), 176 (1443441132765708289), 177 (1443441132766756864), 179 (1443441132766756866), 180 (1443441132767805441)]} 0 5
[junit4] 2> 1242377 T3734 C1079 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[251 (1443441132764659713), 253 (1443441132765708288), 254 (1443441132765708290), 255 (1443441132765708291), 256 (1443441132768854019), 257 (1443441132769902593), 258 (1443441132769902594), 259 (1443441132769902595), 260 (1443441132770951169), 261 (1443441132771999745)]} 0 8
[junit4] 2> 1242379 T3781 C1079 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[167 (1443441132742639616), 169 (1443441132763611136), 171 (1443441132764659712), 172 (1443441132764659716), 174 (1443441132768854018), 175 (1443441132769902592), 177 (1443441132770951168), 181 (1443441132770951170), 185 (1443441132771999746), 187 (1443441132774096897)]} 0 31
[junit4] 2> 1242367 T3759 C1076 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[178 (1443441132737396737), 180 (1443441132738445313), 183 (1443441132738445314), 185 (1443441132743688192), 186 (1443441132744736768), 187 (1443441132744736769), 188 (1443441132745785345), 189 (1443441132745785347), 190 (1443441132761513984), 193 (1443441132761513985)]} 0 24
[junit4] 2> 1242382 T
[...truncated too long message...]
solr.update.SolrCmdDistributorTest-1376572731630/shard1/data;done=false>>]
[junit4] 2> 1242954 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data
[junit4] 2> 1242954 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index;done=false>>]
[junit4] 2> 1242954 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index
[junit4] 2> 1242955 T3704 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/sx_xr/e,null}
[junit4] 2> 1243027 T3704 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=474106985
[junit4] 2> 1243027 T3704 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1cefa7fb
[junit4] 2> 1243034 T3704 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=547,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 1243035 T3704 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1243035 T3704 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1243036 T3704 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1243040 T3704 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1243041 T3704 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1243041 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index;done=false>>]
[junit4] 2> 1243041 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index
[junit4] 2> 1243042 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data;done=false>>]
[junit4] 2> 1243042 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data
[junit4] 2> 1243043 T3704 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/sx_xr/e,null}
[junit4] 2> 1243114 T3704 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=488841172
[junit4] 2> 1243115 T3704 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1c984e29
[junit4] 2> 1243131 T3704 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=542,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 1243132 T3704 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1243132 T3704 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1243133 T3704 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1243137 T3704 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1243138 T3704 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1243138 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data;done=false>>]
[junit4] 2> 1243138 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data
[junit4] 2> 1243139 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index;done=false>>]
[junit4] 2> 1243139 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index
[junit4] 2> 1243140 T3704 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/sx_xr/e,null}
[junit4] 2> 1243195 T3704 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrCmdDistributorTest -Dtests.method=testDistribSearch -Dtests.seed=F16A515E53E6C331 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=da_DK -Dtests.timezone=Australia/Sydney -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 4.84s J0 | SolrCmdDistributorTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: SOLR-3428: We only did adds - there should be no deletes expected:<548> but was:<549>
[junit4] > at __randomizedtesting.SeedInfo.seed([F16A515E53E6C331:708CDF4624B9A30D]:0)
[junit4] > at org.apache.solr.update.SolrCmdDistributorTest.doTest(SolrCmdDistributorTest.java:277)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:662)
[junit4] 2> 1243207 T3704 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=DefaultSimilarity, locale=da_DK, timezone=Australia/Sydney
[junit4] 2> NOTE: Linux 3.2.0-51-generic amd64/Oracle Corporation 1.6.0_45 (64-bit)/cpus=8,threads=53,free=100953528,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [DateMathParserTest, TestPseudoReturnFields, TestSolrIndexConfig, TestTrie, StatelessScriptUpdateProcessorFactoryTest, SearchHandlerTest, TestLuceneMatchVersion, UnloadDistributedZkTest, TestSolrXmlPersistence, TestCharFilters, TestElisionMultitermQuery, SliceStateTest, HdfsChaosMonkeySafeLeaderTest, TestFieldResource, LukeRequestHandlerTest, TestSurroundQueryParser, AnalysisAfterCoreReloadTest, TestCloudManagedSchemaAddField, TestReplicationHandler, DistributedQueryElevationComponentTest, TestRealTimeGet, SampleTest, FieldMutatingUpdateProcessorTest, DocumentBuilderTest, TestDefaultSearchFieldResource, TestDynamicFieldCollectionResource, TestPHPSerializedResponseWriter, SpellPossibilityIteratorTest, TestReversedWildcardFilterFactory, TestCoreContainer, TestFieldTypeResource, BadComponentTest, AliasIntegrationTest, SyncSliceTest, OverseerCollectionProcessorTest, UpdateParamsTest, HdfsBasicDistributedZkTest, ReturnFieldsTest, TestMergePolicyConfig, DistributedTermsComponentTest, TestDocSet, TermVectorComponentTest, TestSolrDeletionPolicy1, TestSolrQueryParserDefaultOperatorResource, CSVRequestHandlerTest, CircularListTest, SolrTestCaseJ4Test, TestFieldTypeCollectionResource, SOLR749Test, DOMUtilTest, SchemaVersionSpecificBehaviorTest, PeerSyncTest, UpdateRequestProcessorFactoryTest, TestValueSourceCache, TestRecoveryHdfs, TestExtendedDismaxParser, TestRemoteStreaming, ParsingFieldUpdateProcessorsTest, RegexBoostProcessorTest, TestGroupingSearch, FastVectorHighlighterTest, DirectUpdateHandlerOptimizeTest, AddBlockUpdateTest, TestUniqueKeyFieldResource, TestPostingsSolrHighlighter, SolrCoreTest, PolyFieldTest, BasicFunctionalityTest, UUIDFieldTest, BasicDistributedZkTest, CoreMergeIndexesAdminHandlerTest, JsonLoaderTest, SuggesterFSTTest, QueryEqualityTest, TestLRUCache, TestSystemIdResolver, LeaderElectionIntegrationTest, RecoveryZkTest, ClusterStateUpdateTest, LeaderElectionTest, ZkCLITest, ShardRoutingCustomTest, SliceStateUpdateTest, AutoCommitTest, TestFaceting, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestStressReorder, TestReload, TestStressVersions, TestRangeQuery, SimpleFacetsTest, TestSolr4Spatial, SolrCmdDistributorTest]
[junit4] Completed on J0 in 4.93s, 1 test, 1 failure <<< FAILURES!
[...truncated 421 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:395: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:375: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:450: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 321 suites, 1365 tests, 1 failure, 35 ignored (6 assumptions)
Total time: 64 minutes 45 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0 -XnoOpt
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
Re: [JENKINS] Lucene-Solr-4.x-Linux (64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0)
- Build # 6939 - Failure!
Posted by Chris Hostetter <ho...@fucit.org>.
Seed reproduces for me against 4x r1514347 ...
ant test -Dtestcase=SolrCmdDistributorTest -Dtests.method=testDistribSearch -Dtests.seed=F16A515E53E6C331 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=da_DK -Dtests.timezone=Australia/Sydney -Dtests.file.encoding=ISO-8859-1
...the problem is that the test does in fact do some deletes -- but
then calls optimie(), and with the randomizd merge policy in place,
there is no longer a garuntee that it will truely merge away all the
deleted docs before the comparison at the end.
working on a fix.
: Date: Thu, 15 Aug 2013 13:26:00 +0000 (UTC)
: From: Policeman Jenkins Server <je...@thetaphi.de>
: Reply-To: dev@lucene.apache.org
: To: dev@lucene.apache.org
: Subject: [JENKINS] Lucene-Solr-4.x-Linux
: (64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0) - Build # 6939 - Failure!
:
: Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/6939/
: Java: 64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0 -XnoOpt
:
: 1 tests failed.
: REGRESSION: org.apache.solr.update.SolrCmdDistributorTest.testDistribSearch
:
: Error Message:
: SOLR-3428: We only did adds - there should be no deletes expected:<548> but was:<549>
:
: Stack Trace:
: java.lang.AssertionError: SOLR-3428: We only did adds - there should be no deletes expected:<548> but was:<549>
: at __randomizedtesting.SeedInfo.seed([F16A515E53E6C331:708CDF4624B9A30D]: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.apache.solr.update.SolrCmdDistributorTest.doTest(SolrCmdDistributorTest.java:277)
: at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
: at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
: at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
: at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
: at java.lang.reflect.Method.invoke(Method.java:597)
: at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
: at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
: at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:738)
: at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:774)
: 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:683)
: 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:40)
: at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
: at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
: at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:44)
: 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:56)
: at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
: at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
: at java.lang.Thread.run(Thread.java:662)
:
:
:
:
: Build Log:
: [...truncated 9426 lines...]
: [junit4] Suite: org.apache.solr.update.SolrCmdDistributorTest
: [junit4] 2> 1238369 T3704 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /sx_xr/e
: [junit4] 2> 1238437 T3704 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
: [junit4] 2> 1238437 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=10000&connTimeout=10000
: [junit4] 2> 1238444 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
: [junit4] 2> 1238452 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:47882
: [junit4] 2> 1238453 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
: [junit4] 2> 1238453 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
: [junit4] 2> 1238453 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
: [junit4] 2> 1238454 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
: [junit4] 2> 1238474 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
: [junit4] 2> 1238535 T3704 oasc.CoreContainer.<init> New CoreContainer 474080358
: [junit4] 2> 1238536 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
: [junit4] 2> 1238538 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
: [junit4] 2> 1238539 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
: [junit4] 2> 1238539 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
: [junit4] 2> 1238540 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
: [junit4] 2> 1238540 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
: [junit4] 2> 1238541 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
: [junit4] 2> 1238541 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
: [junit4] 2> 1238542 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
: [junit4] 2> 1238542 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
: [junit4] 2> 1238543 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
: [junit4] 2> 1238554 T3714 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
: [junit4] 2> 1238554 T3714 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
: [junit4] 2> 1238555 T3714 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
: [junit4] 2> 1238555 T3714 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
: [junit4] 2> 1238600 T3714 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
: [junit4] 2> 1238656 T3714 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
: [junit4] 2> 1238657 T3714 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
: [junit4] 2> 1238667 T3714 oass.IndexSchema.readSchema [collection1] Schema name=test
: [junit4] 2> 1239178 T3714 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1239178 T3714 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1239179 T3714 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
: [junit4] 2> 1239186 T3714 oass.IndexSchema.readSchema default search field in schema is text
: [junit4] 2> 1239189 T3714 oass.IndexSchema.readSchema unique key field: id
: [junit4] 2> 1239194 T3714 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1239197 T3714 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1239200 T3714 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1239200 T3714 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1239200 T3714 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1239200 T3714 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1239201 T3714 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1239201 T3714 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1239201 T3714 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
: [junit4] 2> 1239201 T3714 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data/
: [junit4] 2> 1239202 T3714 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
: [junit4] 2> 1239202 T3714 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data
: [junit4] 2> 1239202 T3714 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data/index/
: [junit4] 2> 1239203 T3714 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data/index' doesn't exist. Creating new index...
: [junit4] 2> 1239203 T3714 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/control/data/index
: [junit4] 2> 1239204 T3714 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1239204 T3714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1239207 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
: [junit4] 2> 1239207 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
: [junit4] 2> 1239207 T3714 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
: [junit4] 2> 1239207 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1239208 T3714 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1239208 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
: [junit4] 2> 1239208 T3714 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1239208 T3714 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1239209 T3714 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
: [junit4] 2> 1239209 T3714 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
: [junit4] 2> 1239210 T3714 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
: [junit4] 2> 1239210 T3714 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
: [junit4] 2> 1239210 T3714 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
: [junit4] 2> 1239211 T3714 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
: [junit4] 2> 1239211 T3714 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
: [junit4] 2> 1239213 T3714 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
: [junit4] 2> 1239216 T3714 oasu.CommitTracker.<init> Hard AutoCommit: disabled
: [junit4] 2> 1239216 T3714 oasu.CommitTracker.<init> Soft AutoCommit: disabled
: [junit4] 2> 1239217 T3714 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1239217 T3714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1239218 T3714 oass.SolrIndexSearcher.<init> Opening Searcher@1ca48390 main
: [junit4] 2> 1239221 T3715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ca48390 main{StandardDirectoryReader(segments_1:1:nrt)}
: [junit4] 2> 1239222 T3714 oasc.CoreContainer.registerCore registering core: collection1
: [junit4] 2> 1239223 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
: [junit4] 2> 1239223 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
: [junit4] 2> 1239224 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
: [junit4] 2> 1239227 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
: [junit4] 2> 1239228 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:47436
: [junit4] 2> 1239229 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
: [junit4] 2> 1239229 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
: [junit4] 2> 1239229 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
: [junit4] 2> 1239230 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
: [junit4] 2> 1239241 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
: [junit4] 2> 1239287 T3704 oasc.CoreContainer.<init> New CoreContainer 487304869
: [junit4] 2> 1239288 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
: [junit4] 2> 1239289 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
: [junit4] 2> 1239290 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
: [junit4] 2> 1239290 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
: [junit4] 2> 1239290 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
: [junit4] 2> 1239291 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
: [junit4] 2> 1239291 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
: [junit4] 2> 1239291 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
: [junit4] 2> 1239291 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
: [junit4] 2> 1239292 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
: [junit4] 2> 1239292 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
: [junit4] 2> 1239299 T3726 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
: [junit4] 2> 1239299 T3726 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
: [junit4] 2> 1239299 T3726 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
: [junit4] 2> 1239300 T3726 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
: [junit4] 2> 1239350 T3726 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
: [junit4] 2> 1239402 T3726 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
: [junit4] 2> 1239402 T3726 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
: [junit4] 2> 1239414 T3726 oass.IndexSchema.readSchema [collection1] Schema name=test
: [junit4] 2> 1239913 T3726 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1239914 T3726 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1239914 T3726 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
: [junit4] 2> 1239921 T3726 oass.IndexSchema.readSchema default search field in schema is text
: [junit4] 2> 1239925 T3726 oass.IndexSchema.readSchema unique key field: id
: [junit4] 2> 1239930 T3726 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1239932 T3726 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1239935 T3726 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1239935 T3726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1239935 T3726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1239935 T3726 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1239936 T3726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1239936 T3726 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1239936 T3726 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
: [junit4] 2> 1239936 T3726 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data/
: [junit4] 2> 1239937 T3726 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
: [junit4] 2> 1239937 T3726 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data
: [junit4] 2> 1239937 T3726 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data/index/
: [junit4] 2> 1239938 T3726 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data/index' doesn't exist. Creating new index...
: [junit4] 2> 1239938 T3726 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard0/data/index
: [junit4] 2> 1239939 T3726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1239939 T3726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1239942 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
: [junit4] 2> 1239942 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
: [junit4] 2> 1239942 T3726 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
: [junit4] 2> 1239943 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1239943 T3726 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1239943 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
: [junit4] 2> 1239943 T3726 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1239944 T3726 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1239944 T3726 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
: [junit4] 2> 1239945 T3726 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
: [junit4] 2> 1239945 T3726 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
: [junit4] 2> 1239945 T3726 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
: [junit4] 2> 1239946 T3726 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
: [junit4] 2> 1239946 T3726 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
: [junit4] 2> 1239947 T3726 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
: [junit4] 2> 1239948 T3726 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
: [junit4] 2> 1239951 T3726 oasu.CommitTracker.<init> Hard AutoCommit: disabled
: [junit4] 2> 1239951 T3726 oasu.CommitTracker.<init> Soft AutoCommit: disabled
: [junit4] 2> 1239952 T3726 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1239953 T3726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1239953 T3726 oass.SolrIndexSearcher.<init> Opening Searcher@1c7384d4 main
: [junit4] 2> 1239959 T3727 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c7384d4 main{StandardDirectoryReader(segments_1:1:nrt)}
: [junit4] 2> 1239961 T3726 oasc.CoreContainer.registerCore registering core: collection1
: [junit4] 2> 1239961 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
: [junit4] 2> 1239962 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
: [junit4] 2> 1239962 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
: [junit4] 2> 1239965 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
: [junit4] 2> 1239967 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49644
: [junit4] 2> 1239967 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
: [junit4] 2> 1239968 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
: [junit4] 2> 1239968 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
: [junit4] 2> 1239968 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
: [junit4] 2> 1239979 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
: [junit4] 2> 1240023 T3704 oasc.CoreContainer.<init> New CoreContainer 478110690
: [junit4] 2> 1240024 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
: [junit4] 2> 1240025 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
: [junit4] 2> 1240025 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
: [junit4] 2> 1240025 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
: [junit4] 2> 1240026 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
: [junit4] 2> 1240026 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
: [junit4] 2> 1240026 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
: [junit4] 2> 1240027 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
: [junit4] 2> 1240027 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
: [junit4] 2> 1240027 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
: [junit4] 2> 1240028 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
: [junit4] 2> 1240034 T3738 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
: [junit4] 2> 1240034 T3738 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
: [junit4] 2> 1240035 T3738 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
: [junit4] 2> 1240035 T3738 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
: [junit4] 2> 1240083 T3738 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
: [junit4] 2> 1240131 T3738 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
: [junit4] 2> 1240131 T3738 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
: [junit4] 2> 1240141 T3738 oass.IndexSchema.readSchema [collection1] Schema name=test
: [junit4] 2> 1240635 T3738 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1240635 T3738 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1240636 T3738 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
: [junit4] 2> 1240643 T3738 oass.IndexSchema.readSchema default search field in schema is text
: [junit4] 2> 1240646 T3738 oass.IndexSchema.readSchema unique key field: id
: [junit4] 2> 1240651 T3738 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1240654 T3738 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1240660 T3738 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1240661 T3738 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1240661 T3738 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1240661 T3738 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1240662 T3738 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1240662 T3738 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1240662 T3738 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
: [junit4] 2> 1240662 T3738 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/
: [junit4] 2> 1240662 T3738 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
: [junit4] 2> 1240663 T3738 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data
: [junit4] 2> 1240663 T3738 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index/
: [junit4] 2> 1240663 T3738 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index' doesn't exist. Creating new index...
: [junit4] 2> 1240664 T3738 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index
: [junit4] 2> 1240665 T3738 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c3f660a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c3f6eec; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1240665 T3738 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1240668 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
: [junit4] 2> 1240668 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
: [junit4] 2> 1240668 T3738 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
: [junit4] 2> 1240668 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1240669 T3738 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1240669 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
: [junit4] 2> 1240669 T3738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1240669 T3738 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1240670 T3738 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
: [junit4] 2> 1240670 T3738 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
: [junit4] 2> 1240671 T3738 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
: [junit4] 2> 1240671 T3738 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
: [junit4] 2> 1240671 T3738 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
: [junit4] 2> 1240672 T3738 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
: [junit4] 2> 1240673 T3738 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
: [junit4] 2> 1240674 T3738 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
: [junit4] 2> 1240677 T3738 oasu.CommitTracker.<init> Hard AutoCommit: disabled
: [junit4] 2> 1240677 T3738 oasu.CommitTracker.<init> Soft AutoCommit: disabled
: [junit4] 2> 1240678 T3738 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c3f660a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c3f6eec; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1240678 T3738 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1240679 T3738 oass.SolrIndexSearcher.<init> Opening Searcher@1c483675 main
: [junit4] 2> 1240684 T3739 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c483675 main{StandardDirectoryReader(segments_1:1:nrt)}
: [junit4] 2> 1240687 T3738 oasc.CoreContainer.registerCore registering core: collection1
: [junit4] 2> 1240687 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
: [junit4] 2> 1240688 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
: [junit4] 2> 1240688 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
: [junit4] 2> 1240693 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
: [junit4] 2> 1240696 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54278
: [junit4] 2> 1240696 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
: [junit4] 2> 1240697 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
: [junit4] 2> 1240697 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
: [junit4] 2> 1240698 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
: [junit4] 2> 1240715 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
: [junit4] 2> 1240784 T3704 oasc.CoreContainer.<init> New CoreContainer 474106985
: [junit4] 2> 1240784 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
: [junit4] 2> 1240786 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
: [junit4] 2> 1240786 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
: [junit4] 2> 1240786 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
: [junit4] 2> 1240787 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
: [junit4] 2> 1240787 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
: [junit4] 2> 1240787 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
: [junit4] 2> 1240788 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
: [junit4] 2> 1240788 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
: [junit4] 2> 1240788 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
: [junit4] 2> 1240789 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
: [junit4] 2> 1240795 T3750 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
: [junit4] 2> 1240795 T3750 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
: [junit4] 2> 1240795 T3750 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
: [junit4] 2> 1240795 T3750 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
: [junit4] 2> 1240835 T3750 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
: [junit4] 2> 1240883 T3750 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
: [junit4] 2> 1240883 T3750 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
: [junit4] 2> 1240893 T3750 oass.IndexSchema.readSchema [collection1] Schema name=test
: [junit4] 2> 1241387 T3750 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1241388 T3750 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1241388 T3750 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
: [junit4] 2> 1241395 T3750 oass.IndexSchema.readSchema default search field in schema is text
: [junit4] 2> 1241400 T3750 oass.IndexSchema.readSchema unique key field: id
: [junit4] 2> 1241405 T3750 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1241407 T3750 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1241410 T3750 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1241410 T3750 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1241411 T3750 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1241411 T3750 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1241411 T3750 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1241411 T3750 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1241412 T3750 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
: [junit4] 2> 1241412 T3750 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/
: [junit4] 2> 1241412 T3750 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
: [junit4] 2> 1241413 T3750 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data
: [junit4] 2> 1241413 T3750 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index/
: [junit4] 2> 1241413 T3750 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index' doesn't exist. Creating new index...
: [junit4] 2> 1241413 T3750 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index
: [junit4] 2> 1241414 T3750 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1d18973f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d18a020; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1241414 T3750 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1241417 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
: [junit4] 2> 1241418 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
: [junit4] 2> 1241418 T3750 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
: [junit4] 2> 1241419 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1241419 T3750 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1241419 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
: [junit4] 2> 1241419 T3750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1241420 T3750 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1241420 T3750 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
: [junit4] 2> 1241421 T3750 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
: [junit4] 2> 1241421 T3750 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
: [junit4] 2> 1241422 T3750 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
: [junit4] 2> 1241422 T3750 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
: [junit4] 2> 1241423 T3750 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
: [junit4] 2> 1241423 T3750 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
: [junit4] 2> 1241424 T3750 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
: [junit4] 2> 1241427 T3750 oasu.CommitTracker.<init> Hard AutoCommit: disabled
: [junit4] 2> 1241428 T3750 oasu.CommitTracker.<init> Soft AutoCommit: disabled
: [junit4] 2> 1241429 T3750 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1d18973f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d18a020; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1241429 T3750 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1241429 T3750 oass.SolrIndexSearcher.<init> Opening Searcher@1d1e4a78 main
: [junit4] 2> 1241435 T3751 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d1e4a78 main{StandardDirectoryReader(segments_1:1:nrt)}
: [junit4] 2> 1241437 T3750 oasc.CoreContainer.registerCore registering core: collection1
: [junit4] 2> 1241438 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
: [junit4] 2> 1241438 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
: [junit4] 2> 1241439 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
: [junit4] 2> 1241442 T3704 oejs.Server.doStart jetty-8.1.10.v20130312
: [junit4] 2> 1241443 T3704 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:40898
: [junit4] 2> 1241444 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
: [junit4] 2> 1241444 T3704 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
: [junit4] 2> 1241445 T3704 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
: [junit4] 2> 1241445 T3704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
: [junit4] 2> 1241457 T3704 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
: [junit4] 2> 1241505 T3704 oasc.CoreContainer.<init> New CoreContainer 488841172
: [junit4] 2> 1241506 T3704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
: [junit4] 2> 1241507 T3704 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
: [junit4] 2> 1241507 T3704 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
: [junit4] 2> 1241508 T3704 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
: [junit4] 2> 1241508 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
: [junit4] 2> 1241508 T3704 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
: [junit4] 2> 1241509 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
: [junit4] 2> 1241509 T3704 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
: [junit4] 2> 1241509 T3704 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
: [junit4] 2> 1241510 T3704 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
: [junit4] 2> 1241510 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
: [junit4] 2> 1241516 T3762 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
: [junit4] 2> 1241516 T3762 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
: [junit4] 2> 1241517 T3762 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
: [junit4] 2> 1241517 T3762 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
: [junit4] 2> 1241557 T3762 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
: [junit4] 2> 1241604 T3762 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
: [junit4] 2> 1241604 T3762 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
: [junit4] 2> 1241614 T3762 oass.IndexSchema.readSchema [collection1] Schema name=test
: [junit4] 2> 1242120 T3762 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1242120 T3762 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
: [junit4] 2> 1242121 T3762 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
: [junit4] 2> 1242128 T3762 oass.IndexSchema.readSchema default search field in schema is text
: [junit4] 2> 1242132 T3762 oass.IndexSchema.readSchema unique key field: id
: [junit4] 2> 1242137 T3762 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1242139 T3762 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
: [junit4] 2> 1242142 T3762 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1242142 T3762 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1242143 T3762 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1242143 T3762 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
: [junit4] 2> 1242143 T3762 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
: [junit4] 2> 1242143 T3762 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
: [junit4] 2> 1242144 T3762 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
: [junit4] 2> 1242144 T3762 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/
: [junit4] 2> 1242144 T3762 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c009cf1
: [junit4] 2> 1242145 T3762 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data
: [junit4] 2> 1242145 T3762 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index/
: [junit4] 2> 1242145 T3762 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index' doesn't exist. Creating new index...
: [junit4] 2> 1242146 T3762 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index
: [junit4] 2> 1242146 T3762 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c98dc5d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c98e540; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1242147 T3762 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1242150 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
: [junit4] 2> 1242150 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
: [junit4] 2> 1242150 T3762 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
: [junit4] 2> 1242151 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1242151 T3762 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
: [junit4] 2> 1242151 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
: [junit4] 2> 1242151 T3762 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1242152 T3762 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
: [junit4] 2> 1242152 T3762 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
: [junit4] 2> 1242153 T3762 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
: [junit4] 2> 1242153 T3762 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
: [junit4] 2> 1242154 T3762 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
: [junit4] 2> 1242154 T3762 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
: [junit4] 2> 1242155 T3762 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
: [junit4] 2> 1242155 T3762 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
: [junit4] 2> 1242156 T3762 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
: [junit4] 2> 1242160 T3762 oasu.CommitTracker.<init> Hard AutoCommit: disabled
: [junit4] 2> 1242160 T3762 oasu.CommitTracker.<init> Soft AutoCommit: disabled
: [junit4] 2> 1242161 T3762 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c98dc5d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c98e540; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> 1242161 T3762 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
: [junit4] 2> 1242162 T3762 oass.SolrIndexSearcher.<init> Opening Searcher@1ca1eb8f main
: [junit4] 2> 1242168 T3763 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ca1eb8f main{StandardDirectoryReader(segments_1:1:nrt)}
: [junit4] 2> 1242170 T3762 oasc.CoreContainer.registerCore registering core: collection1
: [junit4] 2> 1242171 T3704 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
: [junit4] 2> 1242171 T3704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
: [junit4] 2> 1242171 T3704 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
: [junit4] 2> ASYNC NEW_CORE C1071 name=collection1 org.apache.solr.core.SolrCore@1ca08f5b
: [junit4] 2> 1242186 T3713 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132571721728)} 0 1
: [junit4] 2> ASYNC NEW_CORE C1072 name=collection1 org.apache.solr.core.SolrCore@1c6a667b
: [junit4] 2> 1242196 T3722 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132583256064)} 0 1
: [junit4] 2> ASYNC NEW_CORE C1073 name=collection1 org.apache.solr.core.SolrCore@1c3ed7bf
: [junit4] 2> 1242204 T3736 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132590596096)} 0 1
: [junit4] 2> ASYNC NEW_CORE C1074 name=collection1 org.apache.solr.core.SolrCore@1cefa7fb
: [junit4] 2> 1242211 T3746 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132598984704)} 0 1
: [junit4] 2> ASYNC NEW_CORE C1075 name=collection1 org.apache.solr.core.SolrCore@1c984e29
: [junit4] 2> 1242218 T3760 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1443441132606324736)} 0 1
: [junit4] 2> 1242220 T3710 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[1 (1443441132608421888)]} 0 0
: [junit4] 2> 1242221 T3704 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:47882/sx_xr/e/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
: [junit4] 2> 1242223 T3711 C1071 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
: [junit4] 2> 1242229 T3711 C1071 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_2,generation=2}
: [junit4] 2> 1242230 T3711 C1071 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
: [junit4] 2> 1242231 T3711 C1071 oass.SolrIndexSearcher.<init> Opening Searcher@1cc84872 main
: [junit4] 2> 1242232 T3711 C1071 oasu.DirectUpdateHandler2.commit end_commit_flush
: [junit4] 2> 1242233 T3715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cc84872 main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):c1)}
: [junit4] 2> 1242234 T3711 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 11
: [junit4] 2> 1242237 T3713 C1071 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
: [junit4] 2> 1242239 T3724 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&commit_end_point=true&version=2} {add=[2 (1443441132627296256), 3 (1443441132628344832), 4 (1443441132628344833)]} 0 1
: [junit4] 2> 1242240 T3712 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&commit_end_point=true&version=2} {add=[2 (1443441132628344832), 3 (1443441132629393408)]} 0 1
: [junit4] 2> 1242241 T3704 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:47882/sx_xr/e/, StdNode: http://127.0.0.1:47436/sx_xr/e/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
: [junit4] 2> 1242243 T3710 C1071 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
: [junit4] 2> 1242243 T3725 C1072 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
: [junit4] 2> 1242248 T3710 C1071 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_2,generation=2}
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1ca117ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ca12095; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_3,generation=3}
: [junit4] 2> 1242249 T3710 C1071 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
: [junit4] 2> 1242250 T3725 C1072 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_1,generation=1}
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_2,generation=2}
: [junit4] 2> 1242250 T3725 C1072 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
: [junit4] 2> 1242251 T3710 C1071 oass.SolrIndexSearcher.<init> Opening Searcher@1ccc7101 main
: [junit4] 2> 1242251 T3710 C1071 oasu.DirectUpdateHandler2.commit end_commit_flush
: [junit4] 2> 1242252 T3725 C1072 oass.SolrIndexSearcher.<init> Opening Searcher@1ccf3e11 main
: [junit4] 2> 1242252 T3715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ccc7101 main{StandardDirectoryReader(segments_3:5:nrt _0(4.5):c1 _1(4.5):c2)}
: [junit4] 2> 1242253 T3725 C1072 oasu.DirectUpdateHandler2.commit end_commit_flush
: [junit4] 2> 1242253 T3710 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 10
: [junit4] 2> 1242255 T3727 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ccf3e11 main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):c3)}
: [junit4] 2> 1242255 T3725 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 12
: [junit4] 2> 1242257 T3711 C1071 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0
: [junit4] 2> 1242260 T3722 C1072 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0
: [junit4] 2> 1242263 T3723 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&commit_end_point=true&version=2} {delete=[2 (-1443441132653510656)]} 0 0
: [junit4] 2> 1242263 T3713 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&commit_end_point=true&version=2} {delete=[2 (-1443441132653510656)]} 0 0
: [junit4] 2> 1242264 T3704 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:47882/sx_xr/e/, StdNode: http://127.0.0.1:47436/sx_xr/e/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
: [junit4] 2> 1242265 T3724 C1072 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
: [junit4] 2> 1242265 T3712 C1071 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
: [junit4] 2> 1242266 T3724 C1072 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
: [junit4] 2> 1242266 T3712 C1071 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
: [junit4] 2> 1242268 T3724 C1072 oass.SolrIndexSearcher.<init> Opening Searcher@1cb0e1d3 main
: [junit4] 2> 1242268 T3724 C1072 oasu.DirectUpdateHandler2.commit end_commit_flush
: [junit4] 2> 1242269 T3727 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cb0e1d3 main{StandardDirectoryReader(segments_2:4:nrt _0(4.5):c3/1)}
: [junit4] 2> 1242270 T3712 C1071 oass.SolrIndexSearcher.<init> Opening Searcher@1cc068ae main
: [junit4] 2> 1242270 T3724 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 5
: [junit4] 2> 1242270 T3712 C1071 oasu.DirectUpdateHandler2.commit end_commit_flush
: [junit4] 2> 1242271 T3715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cc068ae main{StandardDirectoryReader(segments_3:6:nrt _0(4.5):c1 _1(4.5):c2/1)}
: [junit4] 2> 1242272 T3712 C1071 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 7
: [junit4] 2> 1242274 T3710 C1071 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
: [junit4] 2> 1242277 T3725 C1072 oasc.SolrCore.execute [collection1] webapp=/sx_xr/e path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
: [junit4] 2> 1242279 T3722 C1072 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
: [junit4] 2> 1242285 T3722 C1072 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_2,generation=2}
: [junit4] 2> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1c6af4a8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c6afd8c; maxCacheMB=0.3525390625 maxMergeSizeMB=0.3994140625)),segFN=segments_3,generation=3}
: [junit4] 2> 1242286 T3722 C1072 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
: [junit4] 2> 1242288 T3722 C1072 oass.SolrIndexSearcher.<init> Opening Searcher@1ca7ea79 main
: [junit4] 2> 1242288 T3722 C1072 oasu.DirectUpdateHandler2.commit end_commit_flush
: [junit4] 2> 1242289 T3727 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ca7ea79 main{StandardDirectoryReader(segments_2:4:nrt _0(4.5):c3/1)}
: [junit4] 2> 1242290 T3722 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 11
: [junit4] 2> 1242292 T3737 C1073 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
: [junit4] 2> 1242292 T3737 C1073 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
: [junit4] 2> 1242293 T3737 C1073 oass.SolrIndexSearcher.<init> Opening Searcher@1cab2023 main
: [junit4] 2> 1242293 T3737 C1073 oasu.DirectUpdateHandler2.commit end_commit_flush
: [junit4] 2> 1242294 T3739 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cab2023 main{StandardDirectoryReader(segments_1:1:nrt)}
: [junit4] 2> 1242295 T3737 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 3
: [junit4] 2> 1242297 T3747 C1074 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
: [junit4] 2> 1242298 T3747 C1074 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
: [junit4] 2> 1242298 T3747 C1074 oass.SolrIndexSearcher.<init> Opening Searcher@1cdc43b8 main
: [junit4] 2> 1242298 T3747 C1074 oasu.DirectUpdateHandler2.commit end_commit_flush
: [junit4] 2> 1242300 T3751 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cdc43b8 main{StandardDirectoryReader(segments_1:1:nrt)}
: [junit4] 2> 1242300 T3747 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 3
: [junit4] 2> 1242302 T3761 C1075 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
: [junit4] 2> 1242303 T3761 C1075 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
: [junit4] 2> 1242303 T3761 C1075 oass.SolrIndexSearcher.<init> Opening Searcher@1cda9151 main
: [junit4] 2> 1242303 T3761 C1075 oasu.DirectUpdateHandler2.commit end_commit_flush
: [junit4] 2> 1242305 T3763 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cda9151 main{StandardDirectoryReader(segments_1:1:nrt)}
: [junit4] 2> 1242306 T3761 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 4
: [junit4] 2> 1242311 T3758 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[5 (1443441132699648000), 6 (1443441132700696576), 9 (1443441132701745152), 12 (1443441132701745153), 13 (1443441132701745154), 16 (1443441132702793728), 17 (1443441132702793729), 19 (1443441132702793730), 20 (1443441132702793732), 21 (1443441132703842305)]} 0 4
: [junit4] 2> 1242311 T3749 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[5 (1443441132700696576), 6 (1443441132700696577), 8 (1443441132701745152), 10 (1443441132701745153), 11 (1443441132701745154), 12 (1443441132702793728), 15 (1443441132702793729), 18 (1443441132702793730), 19 (1443441132703842304), 22 (1443441132703842305)]} 0 3
: [junit4] 2> 1242314 T3723 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[6 (1443441132701745152), 9 (1443441132702793728), 11 (1443441132703842304), 12 (1443441132703842305), 15 (1443441132703842306), 16 (1443441132704890880), 17 (1443441132704890882), 19 (1443441132704890884), 22 (1443441132705939456), 23 (1443441132705939458)]} 0 5
: [junit4] 2> 1242314 T3761 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[26 (1443441132702793731), 28 (1443441132703842304), 32 (1443441132704890880), 33 (1443441132704890881), 34 (1443441132704890882), 38 (1443441132705939456), 39 (1443441132705939457), 40 (1443441132705939458), 42 (1443441132705939459), 43 (1443441132706988032)]} 0 4
: [junit4] 2> 1242316 T3725 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[26 (1443441132704890881), 28 (1443441132704890883), 29 (1443441132705939457), 31 (1443441132705939459), 32 (1443441132706988032), 33 (1443441132706988033), 40 (1443441132706988034), 44 (1443441132708036608), 45 (1443441132708036609), 47 (1443441132708036610)]} 0 4
: [junit4] 2> 1242317 T3734 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[5 (1443441132700696576), 6 (1443441132701745152), 8 (1443441132702793728), 9 (1443441132702793730), 13 (1443441132702793731), 14 (1443441132703842304), 15 (1443441132703842305), 21 (1443441132703842306), 22 (1443441132704890880), 23 (1443441132705939456)]} 0 9
: [junit4] 2> 1242322 T3760 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[44 (1443441132710133760), 45 (1443441132710133761), 48 (1443441132711182336), 49 (1443441132711182337), 54 (1443441132711182338), 57 (1443441132712230912), 58 (1443441132712230913), 60 (1443441132714328064), 62 (1443441132714328065), 63 (1443441132714328066)]} 0 5
: [junit4] 2> 1242319 T3747 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[49 (1443441132708036608), 50 (1443441132708036609), 51 (1443441132709085184), 52 (1443441132710133760), 53 (1443441132710133761), 54 (1443441132711182336), 55 (1443441132711182337), 58 (1443441132711182338), 63 (1443441132711182339), 65 (1443441132712230912)]} 0 4
: [junit4] 2> 1242324 T3723 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[49 (1443441132713279489), 51 (1443441132714328066), 52 (1443441132714328068), 56 (1443441132715376640), 58 (1443441132715376642), 61 (1443441132715376643), 62 (1443441132716425216), 63 (1443441132716425217), 64 (1443441132716425218), 66 (1443441132717473792)]} 0 4
: [junit4] 2> 1242324 T3735 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[24 (1443441132702793729), 25 (1443441132710133761), 27 (1443441132711182336), 28 (1443441132711182337), 29 (1443441132711182338), 31 (1443441132712230912), 32 (1443441132712230913), 34 (1443441132712230914), 36 (1443441132713279488), 37 (1443441132713279491)]} 0 14
: [junit4] 2> 1242326 T3759 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[64 (1443441132712230914), 68 (1443441132715376640), 69 (1443441132716425216), 70 (1443441132716425217), 75 (1443441132716425218), 76 (1443441132717473792), 79 (1443441132717473793), 85 (1443441132717473794), 86 (1443441132718522368), 87 (1443441132718522369)]} 0 7
: [junit4] 2> 1242325 T3748 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[24 (1443441132709085185), 25 (1443441132712230913), 26 (1443441132713279488), 31 (1443441132713279489), 34 (1443441132713279490), 39 (1443441132714328065), 45 (1443441132715376640), 46 (1443441132716425216), 47 (1443441132717473792), 48 (1443441132718522368)]} 0 9
: [junit4] 2> 1242327 T3734 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[75 (1443441132714328065), 76 (1443441132715376640), 77 (1443441132715376642), 78 (1443441132716425216), 79 (1443441132716425218), 80 (1443441132717473792), 81 (1443441132717473795), 82 (1443441132717473797), 84 (1443441132719570945), 87 (1443441132720619520)]} 0 6
: [junit4] 2> 1242329 T3722 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[67 (1443441132714328067), 68 (1443441132714328069), 70 (1443441132715376641), 73 (1443441132718522368), 74 (1443441132718522369), 75 (1443441132718522370), 76 (1443441132720619520), 77 (1443441132720619521), 78 (1443441132720619522), 81 (1443441132722716672)]} 0 8
: [junit4] 2> 1242330 T3746 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[67 (1443441132714328064), 68 (1443441132714328066), 69 (1443441132715376641), 70 (1443441132718522369), 73 (1443441132718522370), 74 (1443441132719570944), 75 (1443441132719570945), 76 (1443441132719570946), 77 (1443441132719570947), 80 (1443441132720619520)]} 0 9
: [junit4] 2> 1242325 T3737 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[39 (1443441132709085184), 43 (1443441132712230915), 44 (1443441132713279490), 47 (1443441132714328064), 49 (1443441132715376641), 50 (1443441132716425217), 52 (1443441132716425219), 53 (1443441132717473793), 54 (1443441132717473794), 55 (1443441132717473796)]} 0 9
: [junit4] 2> 1242333 T3736 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[56 (1443441132709085185), 58 (1443441132710133760), 62 (1443441132713279489), 63 (1443441132719570944), 65 (1443441132719570946), 67 (1443441132721668096), 69 (1443441132721668097), 71 (1443441132721668099), 72 (1443441132725862400), 73 (1443441132725862403)]} 0 17
: [junit4] 2> 1242336 T3758 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[91 (1443441132721668096), 92 (1443441132721668097), 93 (1443441132722716674), 95 (1443441132725862400), 97 (1443441132725862403), 99 (1443441132726910976), 100 (1443441132726910978), 103 (1443441132729008130), 106 (1443441132729008132), 110 (1443441132730056704)]} 0 8
: [junit4] 2> 1242337 T3737 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[114 (1443441132724813824), 115 (1443441132725862401), 116 (1443441132725862405), 118 (1443441132726910976), 120 (1443441132727959553), 121 (1443441132727959555), 122 (1443441132727959557), 125 (1443441132729008128), 126 (1443441132729008131), 132 (1443441132731105280)]} 0 6
: [junit4] 2> 1242338 T3748 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[113 (1443441132721668097), 114 (1443441132724813824), 115 (1443441132725862400), 116 (1443441132725862401), 117 (1443441132726910976), 118 (1443441132726910977), 119 (1443441132726910978), 120 (1443441132731105280), 121 (1443441132731105281), 122 (1443441132732153856)]} 0 10
: [junit4] 2> 1242339 T3761 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[114 (1443441132722716672), 116 (1443441132725862401), 119 (1443441132725862404), 120 (1443441132726910977), 121 (1443441132726910979), 122 (1443441132731105280), 123 (1443441132732153856), 124 (1443441132732153857), 126 (1443441132732153858), 127 (1443441132733202432)]} 0 10
: [junit4] 2> 1242340 T3781 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[136 (1443441132725862402), 138 (1443441132725862404), 142 (1443441132726910977), 144 (1443441132727959552), 147 (1443441132727959554), 148 (1443441132727959556), 149 (1443441132727959558), 150 (1443441132729008129), 152 (1443441132733202433), 153 (1443441132733202434)]} 0 8
: [junit4] 2> 1242341 T3749 C1074 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[81 (1443441132721668096), 82 (1443441132733202432), 84 (1443441132733202433), 85 (1443441132733202434), 87 (1443441132734251008), 88 (1443441132734251009), 90 (1443441132734251010), 94 (1443441132735299584), 95 (1443441132735299585), 99 (1443441132735299586)]} 0 13
: [junit4] 2> 1242343 T3725 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[106 (1443441132722716673), 110 (1443441132723765248), 112 (1443441132723765250), 117 (1443441132723765252), 125 (1443441132724813825), 128 (1443441132724813827), 129 (1443441132733202432), 130 (1443441132734251008), 133 (1443441132734251010), 134 (1443441132735299585)]} 0 14
: [junit4] 2> 1242344 T3724 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[82 (1443441132713279488), 85 (1443441132714328064), 86 (1443441132714328065), 87 (1443441132733202433), 90 (1443441132734251009), 93 (1443441132735299584), 94 (1443441132735299586), 97 (1443441132737396737), 98 (1443441132737396739), 102 (1443441132738445312)]} 0 24
: [junit4] 2> 1242347 T3758 C1075 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[154 (1443441132737396736), 157 (1443441132738445312), 161 (1443441132738445315), 162 (1443441132739493888), 163 (1443441132739493889), 167 (1443441132740542464), 169 (1443441132740542465), 173 (1443441132740542466), 176 (1443441132741591040), 177 (1443441132741591041)]} 0 4
: [junit4] 2> 1242349 T3722 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[181 (1443441132737396736), 182 (1443441132737396738), 183 (1443441132739493888), 184 (1443441132739493889), 185 (1443441132739493890), 188 (1443441132740542464), 189 (1443441132740542465), 202 (1443441132742639618), 205 (1443441132742639619), 209 (1443441132743688192)]} 0 6
: [junit4] 2> 1242351 T3734 C1073 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[89 (1443441132721668098), 90 (1443441132729008130), 91 (1443441132731105281), 94 (1443441132731105282), 99 (1443441132731105283), 100 (1443441132732153856), 101 (1443441132732153857), 103 (1443441132732153858), 107 (1443441132733202432), 110 (1443441132744736769)]} 0 22
: [junit4] 2> 1242351 T3723 C1072 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[135 (1443441132722716674), 138 (1443441132722716675), 141 (1443441132723765249), 142 (1443441132723765251), 145 (1443441132724813824), 146 (1443441132724813826), 147 (1443441132725862400), 149 (1443441132742639616), 152 (1443441132742639617), 153 (1443441132744736768)]} 0 22
: [junit4] 2> ASYNC NEW_CORE C1076 name=collection1 org.apache.solr.core.SolrCore@1c984e29
: [junit4] 2> 1242340 T3760 C1076 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[130 (1443441132722716673), 131 (1443441132723765248), 134 (1443441132725862402), 135 (1443441132727959552), 140 (1443441132729008128), 141 (1443441132729008129), 142 (1443441132729008131), 145 (1443441132729008133), 150 (1443441132730056705), 152 (1443441132734251008)]} 0 11
: [junit4] 2> ASYNC NEW_CORE C1077 name=collection1 org.apache.solr.core.SolrCore@1c6a667b
: [junit4] 2> 1242366 T3724 C1077 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[211 (1443441132758368256), 214 (1443441132759416832), 217 (1443441132759416833), 220 (1443441132759416834), 224 (1443441132760465408), 225 (1443441132760465409), 229 (1443441132760465410), 230 (1443441132760465411), 231 (1443441132761513984), 232 (1443441132761513985)]} 0 3
: [junit4] 2> ASYNC NEW_CORE C1078 name=collection1 org.apache.solr.core.SolrCore@1cefa7fb
: [junit4] 2> 1242369 T3746 C1078 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[138 (1443441132736348160), 139 (1443441132736348161), 142 (1443441132737396739), 143 (1443441132738445312), 145 (1443441132738445313), 146 (1443441132738445314), 149 (1443441132739493888), 151 (1443441132762562560), 154 (1443441132762562562), 155 (1443441132763611138)]} 0 27
: [junit4] 2> ASYNC NEW_CORE C1079 name=collection1 org.apache.solr.core.SolrCore@1c3ed7bf
: [junit4] 2> 1242370 T3736 C1079 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[154 (1443441132736348160), 155 (1443441132736348161), 156 (1443441132739493888), 157 (1443441132744736770), 158 (1443441132744736772), 159 (1443441132745785344), 160 (1443441132745785345), 161 (1443441132745785346), 162 (1443441132762562560), 164 (1443441132764659715)]} 0 28
: [junit4] 2> 1242363 T3761 C1076 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[212 (1443441132742639616), 215 (1443441132742639617), 216 (1443441132744736770), 218 (1443441132744736771), 221 (1443441132745785344), 223 (1443441132745785346), 224 (1443441132745785348), 225 (1443441132746833920), 226 (1443441132757319680), 227 (1443441132758368256)]} 0 15
: [junit4] 2> 1242376 T3749 C1078 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[174 (1443441132740542464), 175 (1443441132762562563), 176 (1443441132763611139), 180 (1443441132763611141), 185 (1443441132764659712), 186 (1443441132764659714), 189 (1443441132769902592), 190 (1443441132770951169), 191 (1443441132770951172), 192 (1443441132770951174)]} 0 30
: [junit4] 2> 1242375 T3787 C1078 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[208 (1443441132746833920), 210 (1443441132762562561), 211 (1443441132763611136), 212 (1443441132763611140), 213 (1443441132763611143), 218 (1443441132764659713), 221 (1443441132765708290), 222 (1443441132765708294), 223 (1443441132770951168), 225 (1443441132770951171)]} 0 23
: [junit4] 2> 1242374 T3725 C1077 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[233 (1443441132762562561), 234 (1443441132762562563), 236 (1443441132763611138), 237 (1443441132764659712), 238 (1443441132765708290), 241 (1443441132766756865), 242 (1443441132767805440), 244 (1443441132767805442), 245 (1443441132768854016), 246 (1443441132769902592)]} 0 7
: [junit4] 2> 1242373 T3737 C1079 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[188 (1443441132737396736), 194 (1443441132740542464), 195 (1443441132744736768), 199 (1443441132744736771), 202 (1443441132764659714), 204 (1443441132765708289), 206 (1443441132766756864), 207 (1443441132767805440), 209 (1443441132768854016), 211 (1443441132768854017)]} 0 30
: [junit4] 2> 1242372 T3722 C1077 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[160 (1443441132762562560), 164 (1443441132762562562), 165 (1443441132763611136), 168 (1443441132763611137), 174 (1443441132763611139), 175 (1443441132765708288), 176 (1443441132765708289), 177 (1443441132766756864), 179 (1443441132766756866), 180 (1443441132767805441)]} 0 5
: [junit4] 2> 1242377 T3734 C1079 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[251 (1443441132764659713), 253 (1443441132765708288), 254 (1443441132765708290), 255 (1443441132765708291), 256 (1443441132768854019), 257 (1443441132769902593), 258 (1443441132769902594), 259 (1443441132769902595), 260 (1443441132770951169), 261 (1443441132771999745)]} 0 8
: [junit4] 2> 1242379 T3781 C1079 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[167 (1443441132742639616), 169 (1443441132763611136), 171 (1443441132764659712), 172 (1443441132764659716), 174 (1443441132768854018), 175 (1443441132769902592), 177 (1443441132770951168), 181 (1443441132770951170), 185 (1443441132771999746), 187 (1443441132774096897)]} 0 31
: [junit4] 2> 1242367 T3759 C1076 oasup.LogUpdateProcessor.finish [collection1] webapp=/sx_xr/e path=/update params={wt=javabin&version=2} {add=[178 (1443441132737396737), 180 (1443441132738445313), 183 (1443441132738445314), 185 (1443441132743688192), 186 (1443441132744736768), 187 (1443441132744736769), 188 (1443441132745785345), 189 (1443441132745785347), 190 (1443441132761513984), 193 (1443441132761513985)]} 0 24
: [junit4] 2> 1242382 T
:
: [...truncated too long message...]
:
: solr.update.SolrCmdDistributorTest-1376572731630/shard1/data;done=false>>]
: [junit4] 2> 1242954 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data
: [junit4] 2> 1242954 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index;done=false>>]
: [junit4] 2> 1242954 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard1/data/index
: [junit4] 2> 1242955 T3704 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/sx_xr/e,null}
: [junit4] 2> 1243027 T3704 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=474106985
: [junit4] 2> 1243027 T3704 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1cefa7fb
: [junit4] 2> 1243034 T3704 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=547,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
: [junit4] 2> 1243035 T3704 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
: [junit4] 2> 1243035 T3704 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
: [junit4] 2> 1243036 T3704 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
: [junit4] 2> 1243040 T3704 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
: [junit4] 2> 1243041 T3704 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
: [junit4] 2> 1243041 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index;done=false>>]
: [junit4] 2> 1243041 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data/index
: [junit4] 2> 1243042 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data;done=false>>]
: [junit4] 2> 1243042 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard2/data
: [junit4] 2> 1243043 T3704 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/sx_xr/e,null}
: [junit4] 2> 1243114 T3704 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=488841172
: [junit4] 2> 1243115 T3704 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1c984e29
: [junit4] 2> 1243131 T3704 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=542,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
: [junit4] 2> 1243132 T3704 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
: [junit4] 2> 1243132 T3704 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
: [junit4] 2> 1243133 T3704 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
: [junit4] 2> 1243137 T3704 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
: [junit4] 2> 1243138 T3704 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
: [junit4] 2> 1243138 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data;done=false>>]
: [junit4] 2> 1243138 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data
: [junit4] 2> 1243139 T3704 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index;done=false>>]
: [junit4] 2> 1243139 T3704 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.SolrCmdDistributorTest-1376572731630/shard3/data/index
: [junit4] 2> 1243140 T3704 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/sx_xr/e,null}
: [junit4] 2> 1243195 T3704 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
: [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrCmdDistributorTest -Dtests.method=testDistribSearch -Dtests.seed=F16A515E53E6C331 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=da_DK -Dtests.timezone=Australia/Sydney -Dtests.file.encoding=ISO-8859-1
: [junit4] FAILURE 4.84s J0 | SolrCmdDistributorTest.testDistribSearch <<<
: [junit4] > Throwable #1: java.lang.AssertionError: SOLR-3428: We only did adds - there should be no deletes expected:<548> but was:<549>
: [junit4] > at __randomizedtesting.SeedInfo.seed([F16A515E53E6C331:708CDF4624B9A30D]:0)
: [junit4] > at org.apache.solr.update.SolrCmdDistributorTest.doTest(SolrCmdDistributorTest.java:277)
: [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
: [junit4] > at java.lang.Thread.run(Thread.java:662)
: [junit4] 2> 1243207 T3704 oas.SolrTestCaseJ4.deleteCore ###deleteCore
: [junit4] 2> NOTE: test params are: codec=Lucene41, sim=DefaultSimilarity, locale=da_DK, timezone=Australia/Sydney
: [junit4] 2> NOTE: Linux 3.2.0-51-generic amd64/Oracle Corporation 1.6.0_45 (64-bit)/cpus=8,threads=53,free=100953528,total=536870912
: [junit4] 2> NOTE: All tests run in this JVM: [DateMathParserTest, TestPseudoReturnFields, TestSolrIndexConfig, TestTrie, StatelessScriptUpdateProcessorFactoryTest, SearchHandlerTest, TestLuceneMatchVersion, UnloadDistributedZkTest, TestSolrXmlPersistence, TestCharFilters, TestElisionMultitermQuery, SliceStateTest, HdfsChaosMonkeySafeLeaderTest, TestFieldResource, LukeRequestHandlerTest, TestSurroundQueryParser, AnalysisAfterCoreReloadTest, TestCloudManagedSchemaAddField, TestReplicationHandler, DistributedQueryElevationComponentTest, TestRealTimeGet, SampleTest, FieldMutatingUpdateProcessorTest, DocumentBuilderTest, TestDefaultSearchFieldResource, TestDynamicFieldCollectionResource, TestPHPSerializedResponseWriter, SpellPossibilityIteratorTest, TestReversedWildcardFilterFactory, TestCoreContainer, TestFieldTypeResource, BadComponentTest, AliasIntegrationTest, SyncSliceTest, OverseerCollectionProcessorTest, UpdateParamsTest, HdfsBasicDistributedZkTest, ReturnFieldsTest, TestMer
gePolicyConfig, DistributedTermsComponentTest, TestDocSet, TermVectorComponentTest, TestSolrDeletionPolicy1, TestSolrQueryParserDefaultOperatorResource, CSVRequestHandlerTest, CircularListTest, SolrTestCaseJ4Test, TestFieldTypeCollectionResource, SOLR749Test, DOMUtilTest, SchemaVersionSpecificBehaviorTest, PeerSyncTest, UpdateRequestProcessorFactoryTest, TestValueSourceCache, TestRecoveryHdfs, TestExtendedDismaxParser, TestRemoteStreaming, ParsingFieldUpdateProcessorsTest, RegexBoostProcessorTest, TestGroupingSearch, FastVectorHighlighterTest, DirectUpdateHandlerOptimizeTest, AddBlockUpdateTest, TestUniqueKeyFieldResource, TestPostingsSolrHighlighter, SolrCoreTest, PolyFieldTest, BasicFunctionalityTest, UUIDFieldTest, BasicDistributedZkTest, CoreMergeIndexesAdminHandlerTest, JsonLoaderTest, SuggesterFSTTest, QueryEqualityTest, TestLRUCache, TestSystemIdResolver, LeaderElectionIntegrationTest, RecoveryZkTest, ClusterStateUpdateTest, LeaderElectionTest, ZkCLITest, ShardRoutingCustomTe
st, SliceStateUpdateTest, AutoCommitTest, TestFaceting, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestStressReorder, TestReload, TestStressVersions, TestRangeQuery, SimpleFacetsTest, TestSolr4Spatial, SolrCmdDistributorTest]
: [junit4] Completed on J0 in 4.93s, 1 test, 1 failure <<< FAILURES!
:
: [...truncated 421 lines...]
: BUILD FAILED
: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:395: The following error occurred while executing this line:
: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:375: The following error occurred while executing this line:
: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:181: The following error occurred while executing this line:
: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:450: The following error occurred while executing this line:
: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 321 suites, 1365 tests, 1 failure, 35 ignored (6 assumptions)
:
: Total time: 64 minutes 45 seconds
: Build step 'Invoke Ant' marked build as failure
: Description set: Java: 64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0 -XnoOpt
: Archiving artifacts
: Recording test results
: Email was triggered for: Failure
: Sending email for trigger: Failure
:
:
:
-Hoss