lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-trunk - Build # 457 - Still Failing
Date Tue, 03 Dec 2013 04:22:00 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/457/

1 tests failed.
REGRESSION:  org.apache.solr.update.PeerSyncTest.testDistribSearch

Error Message:
.response[8][id][0]:2!=1

Stack Trace:
junit.framework.AssertionFailedError: .response[8][id][0]:2!=1
	at __randomizedtesting.SeedInfo.seed([68F3C30A3C1BA0B:8769B228D49EDA37]:0)
	at junit.framework.Assert.fail(Assert.java:50)
	at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:816)
	at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:835)
	at org.apache.solr.BaseDistributedSearchTestCase.queryAndCompare(BaseDistributedSearchTestCase.java:581)
	at org.apache.solr.BaseDistributedSearchTestCase.queryAndCompare(BaseDistributedSearchTestCase.java:572)
	at org.apache.solr.update.PeerSyncTest.doTest(PeerSyncTest.java:98)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:843)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:724)




Build Log:
[...truncated 10421 lines...]
   [junit4] Suite: org.apache.solr.update.PeerSyncTest
   [junit4]   2> 1414081 T3056 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /_zs/p
   [junit4]   2> 1414085 T3056 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 1414088 T3056 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1414091 T3056 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:18925
   [junit4]   2> 1414092 T3056 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1414093 T3056 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1414093 T3056 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr
   [junit4]   2> 1414093 T3056 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 1414139 T3056 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/solr.xml
   [junit4]   2> 1414285 T3056 oasc.CoreContainer.<init> New CoreContainer 1810276394
   [junit4]   2> 1414286 T3056 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/]
   [junit4]   2> 1414288 T3056 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1414288 T3056 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1414289 T3056 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1414289 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1414289 T3056 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1414290 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1414290 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1414290 T3056 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1414291 T3056 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1414315 T3056 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1414316 T3056 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1414316 T3056 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1414322 T3066 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1
   [junit4]   2> 1414323 T3066 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/'
   [junit4]   2> 1414325 T3066 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1414325 T3066 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1414394 T3066 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1414455 T3066 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 1414456 T3066 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1414467 T3066 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 1415260 T3066 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1415272 T3066 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1415276 T3066 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1415305 T3066 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1415310 T3066 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1415314 T3066 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1415315 T3066 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1415315 T3066 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1415316 T3066 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1415316 T3066 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1415316 T3066 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1415317 T3066 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1415317 T3066 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.PeerSyncTest-1386042323538/control/data/
   [junit4]   2> 1415317 T3066 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@112a1123
   [junit4]   2> 1415318 T3066 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.PeerSyncTest-1386042323538/control/data
   [junit4]   2> 1415318 T3066 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.PeerSyncTest-1386042323538/control/data/index/
   [junit4]   2> 1415319 T3066 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.PeerSyncTest-1386042323538/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1415319 T3066 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.PeerSyncTest-1386042323538/control/data/index
   [junit4]   2> 1415319 T3066 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1415320 T3066 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@a1cc84c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41bb4284; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 1415321 T3066 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1415325 T3066 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1415325 T3066 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1415325 T3066 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1415326 T3066 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1415326 T3066 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1415326 T3066 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1415327 T3066 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1415327 T3066 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1415327 T3066 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1415328 T3066 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1415329 T3066 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1415329 T3066 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1415329 T3066 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1415330 T3066 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1415330 T3066 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1415331 T3066 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1415331 T3066 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 1415347 T3066 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1415356 T3066 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1415357 T3066 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1415357 T3066 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=92.9228515625, floorSegmentMB=2.1396484375, forceMergeDeletesPctAllowed=11.425026660373259, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1415358 T3066 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@a1cc84c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41bb4284; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 1415358 T3066 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1415358 T3066 oass.SolrIndexSearcher.<init> Opening Searcher@1063cde main
   [junit4]   2> 1415362 T3067 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1063cde main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1415364 T3066 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1415365 T3056 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 1415365 T3056 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1415367 T3056 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1415370 T3056 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:18927
   [junit4]   2> 1415371 T3056 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1415371 T3056 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1415372 T3056 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr
   [junit4]   2> 1415372 T3056 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 1415388 T3056 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/solr.xml
   [junit4]   2> 1415461 T3056 oasc.CoreContainer.<init> New CoreContainer 1731194072
   [junit4]   2> 1415462 T3056 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/]
   [junit4]   2> 1415470 T3056 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1415471 T3056 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1415471 T3056 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1415472 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1415472 T3056 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1415472 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1415473 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1415473 T3056 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1415474 T3056 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1415486 T3056 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1415487 T3056 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1415487 T3056 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1415493 T3078 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1
   [junit4]   2> 1415493 T3078 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/'
   [junit4]   2> 1415494 T3078 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1415494 T3078 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1415553 T3078 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1415620 T3078 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 1415621 T3078 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1415632 T3078 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 1416334 T3078 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1416342 T3078 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1416345 T3078 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1416355 T3078 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1416359 T3078 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1416363 T3078 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1416364 T3078 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1416365 T3078 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1416365 T3078 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1416365 T3078 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1416366 T3078 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1416366 T3078 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1416366 T3078 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data/
   [junit4]   2> 1416367 T3078 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@112a1123
   [junit4]   2> 1416368 T3078 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data
   [junit4]   2> 1416368 T3078 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data/index/
   [junit4]   2> 1416368 T3078 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1416368 T3078 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data/index
   [junit4]   2> 1416369 T3078 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1416370 T3078 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 1416370 T3078 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1416375 T3078 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1416375 T3078 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1416376 T3078 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1416376 T3078 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1416376 T3078 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1416377 T3078 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1416377 T3078 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1416378 T3078 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1416378 T3078 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1416378 T3078 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1416379 T3078 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1416379 T3078 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1416380 T3078 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1416380 T3078 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1416381 T3078 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1416381 T3078 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1416382 T3078 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 1416384 T3078 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1416390 T3078 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1416391 T3078 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1416391 T3078 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=92.9228515625, floorSegmentMB=2.1396484375, forceMergeDeletesPctAllowed=11.425026660373259, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1416392 T3078 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 1416392 T3078 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1416393 T3078 oass.SolrIndexSearcher.<init> Opening Searcher@4f8e0893 main
   [junit4]   2> 1416397 T3079 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4f8e0893 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1416400 T3078 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1416401 T3056 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 1416402 T3056 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1416413 T3056 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1416416 T3056 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:18953
   [junit4]   2> 1416417 T3056 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1416417 T3056 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1416418 T3056 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr
   [junit4]   2> 1416418 T3056 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 1416433 T3056 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/solr.xml
   [junit4]   2> 1416494 T3056 oasc.CoreContainer.<init> New CoreContainer 843877291
   [junit4]   2> 1416494 T3056 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/]
   [junit4]   2> 1416501 T3056 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1416502 T3056 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1416502 T3056 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1416503 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1416503 T3056 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1416503 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1416504 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1416504 T3056 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1416505 T3056 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1416517 T3056 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1416518 T3056 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1416518 T3056 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1416524 T3090 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1
   [junit4]   2> 1416525 T3090 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/'
   [junit4]   2> 1416526 T3090 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1416526 T3090 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1416592 T3090 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1416668 T3090 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 1416669 T3090 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1416679 T3090 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 1417863 T3090 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1417891 T3090 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1417906 T3090 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1417942 T3090 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1417955 T3090 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1417975 T3090 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1417978 T3090 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1417979 T3090 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1417981 T3090 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1417983 T3090 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1417983 T3090 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1417984 T3090 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1417984 T3090 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data/
   [junit4]   2> 1417985 T3090 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@112a1123
   [junit4]   2> 1417986 T3090 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data
   [junit4]   2> 1417987 T3090 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data/index/
   [junit4]   2> 1417987 T3090 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1417988 T3090 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data/index
   [junit4]   2> 1417988 T3090 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1417991 T3090 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 1417992 T3090 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1418000 T3090 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1418001 T3090 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1418001 T3090 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1418001 T3090 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1418002 T3090 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1418002 T3090 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1418003 T3090 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1418004 T3090 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1418004 T3090 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1418005 T3090 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1418006 T3090 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1418006 T3090 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1418007 T3090 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1418008 T3090 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1418009 T3090 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1418010 T3090 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1418011 T3090 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 1418019 T3090 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1418066 T3090 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1418067 T3090 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1418071 T3090 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=92.9228515625, floorSegmentMB=2.1396484375, forceMergeDeletesPctAllowed=11.425026660373259, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1418073 T3090 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 1418073 T3090 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1418074 T3090 oass.SolrIndexSearcher.<init> Opening Searcher@10769d5d main
   [junit4]   2> 1418080 T3091 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10769d5d main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1418085 T3090 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1418087 T3056 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 1418088 T3056 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1418093 T3056 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1418100 T3056 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:18954
   [junit4]   2> 1418101 T3056 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1418102 T3056 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1418103 T3056 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr
   [junit4]   2> 1418103 T3056 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 1418133 T3056 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/solr.xml
   [junit4]   2> 1418333 T3056 oasc.CoreContainer.<init> New CoreContainer 1170173532
   [junit4]   2> 1418334 T3056 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/]
   [junit4]   2> 1418336 T3056 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1418337 T3056 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1418337 T3056 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1418338 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1418338 T3056 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1418339 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1418339 T3056 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1418340 T3056 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1418340 T3056 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1418383 T3056 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1418384 T3056 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1418384 T3056 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1418406 T3102 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1
   [junit4]   2> 1418407 T3102 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/'
   [junit4]   2> 1418411 T3102 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1418412 T3102 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1418590 T3102 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1418813 T3102 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 1418814 T3102 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1418834 T3102 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 1421011 T3102 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1421033 T3102 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1421043 T3102 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1421057 T3102 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1421068 T3102 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1421078 T3102 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1421079 T3102 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1421080 T3102 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1421080 T3102 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1421081 T3102 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1421081 T3102 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1421081 T3102 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1421081 T3102 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data/
   [junit4]   2> 1421082 T3102 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@112a1123
   [junit4]   2> 1421083 T3102 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data
   [junit4]   2> 1421083 T3102 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data/index/
   [junit4]   2> 1421084 T3102 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1421085 T3102 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data/index
   [junit4]   2> 1421085 T3102 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1421087 T3102 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@33eef9cb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b7116ba; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 1421087 T3102 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1421094 T3102 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1421095 T3102 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1421096 T3102 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1421096 T3102 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1421096 T3102 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1421097 T3102 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1421098 T3102 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1421098 T3102 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1421098 T3102 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1421099 T3102 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1421100 T3102 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1421100 T3102 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1421101 T3102 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1421102 T3102 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1421102 T3102 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1421103 T3102 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1421104 T3102 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 1421107 T3102 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1421148 T3102 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1421148 T3102 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1421149 T3102 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=92.9228515625, floorSegmentMB=2.1396484375, forceMergeDeletesPctAllowed=11.425026660373259, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1421150 T3102 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@33eef9cb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b7116ba; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 1421151 T3102 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1421151 T3102 oass.SolrIndexSearcher.<init> Opening Searcher@6c864876 main
   [junit4]   2> 1421158 T3103 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6c864876 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1421162 T3102 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1421164 T3056 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 1421165 T3056 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C1282 name=collection1 org.apache.solr.core.SolrCore@50bd3023
   [junit4]   2> 1421208 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[1 (1)]} 0 13
   [junit4]   2> ASYNC  NEW_CORE C1283 name=collection1 org.apache.solr.core.SolrCore@af036
   [junit4]   2> 1421231 T3083 C1283 oasu.PeerSync.sync PeerSync: core=collection1 url= START replicas=[127.0.0.1:18927/_zs/p] nUpdates=100
   [junit4]   2> 1421232 T3083 C1283 oasu.PeerSync.sync PeerSync: core=collection1 url= DONE.  We have no versions.  sync failed.
   [junit4]   2> 1421233 T3083 C1283 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={getVersions=100&sync=127.0.0.1:18927/_zs/p&version=2&qt=/get&wt=javabin} status=0 QTime=3 
   [junit4]   2> 1421235 T3072 C1282 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={getVersions=100&version=2&distrib=false&qt=/get&wt=javabin} status=0 QTime=0 
   [junit4]   2> 1421246 T3083 C1283 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[1 (1)]} 0 10
   [junit4]   2> 1421250 T3083 C1283 oasu.PeerSync.sync PeerSync: core=collection1 url= START replicas=[127.0.0.1:18927/_zs/p] nUpdates=100
   [junit4]   2> 1421253 T3072 C1282 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={getVersions=100&version=2&distrib=false&qt=/get&wt=javabin} status=0 QTime=0 
   [junit4]   2> 1421254 T3083 C1283 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=  Received 1 versions from 127.0.0.1:18927/_zs/p
   [junit4]   2> 1421255 T3083 C1283 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=  Our versions are newer. ourLowThreshold=1 otherHigh=1
   [junit4]   2> 1421256 T3083 C1283 oasu.PeerSync.sync PeerSync: core=collection1 url= DONE. sync succeeded
   [junit4]   2> 1421256 T3083 C1283 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={getVersions=100&sync=127.0.0.1:18927/_zs/p&version=2&qt=/get&wt=javabin} status=0 QTime=6 
   [junit4]   2> 1421259 T3071 C1282 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1421265 T3071 C1282 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_2,generation=2}
   [junit4]   2> 1421266 T3071 C1282 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 1421269 T3071 C1282 oass.SolrIndexSearcher.<init> Opening Searcher@4b991a74 main
   [junit4]   2> 1421269 T3071 C1282 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1421271 T3079 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4b991a74 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c1)}
   [junit4]   2> 1421271 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={softCommit=false&waitSearcher=true&commit=true&version=2&wt=javabin} {commit=} 0 12
   [junit4]   2> 1421274 T3083 C1283 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1421280 T3083 C1283 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_2,generation=2}
   [junit4]   2> 1421281 T3083 C1283 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 1421283 T3083 C1283 oass.SolrIndexSearcher.<init> Opening Searcher@6678e412 main
   [junit4]   2> 1421284 T3083 C1283 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1421285 T3091 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6678e412 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c1)}
   [junit4]   2> 1421285 T3083 C1283 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={softCommit=false&waitSearcher=true&commit=true&version=2&wt=javabin} {commit=} 0 11
   [junit4]   2> 1421288 T3071 C1282 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/select params={q=*:*&version=2&wt=javabin} hits=1 status=0 QTime=0 
   [junit4]   2> 1421291 T3083 C1283 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/select params={q=*:*&version=2&wt=javabin} hits=1 status=0 QTime=0 
   [junit4]   2> 1421297 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[2 (2)]} 0 3
   [junit4]   2> 1421300 T3083 C1283 oasu.PeerSync.sync PeerSync: core=collection1 url= START replicas=[127.0.0.1:18927/_zs/p] nUpdates=100
   [junit4]   2> 1421303 T3072 C1282 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={getVersions=100&version=2&distrib=false&qt=/get&wt=javabin} status=0 QTime=0 
   [junit4]   2> 1421304 T3083 C1283 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=  Received 2 versions from 127.0.0.1:18927/_zs/p
   [junit4]   2> 1421304 T3083 C1283 oasu.PeerSync.requestUpdates PeerSync: core=collection1 url= Requesting updates from 127.0.0.1:18927/_zs/pn=1 versions=[2]
   [junit4]   2> 1421308 T3072 C1282 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={version=2&distrib=false&qt=/get&wt=javabin&getUpdates=2} status=0 QTime=1 
   [junit4]   2> 1421311 T3083 C1283 oasup.LogUpdateProcessor.finish [collection1] {add=[2 (2)]} 0 3
   [junit4]   2> 1421312 T3083 C1283 oasu.PeerSync.sync PeerSync: core=collection1 url= DONE. sync succeeded
   [junit4]   2> 1421312 T3083 C1283 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={getVersions=100&sync=127.0.0.1:18927/_zs/p&version=2&qt=/get&wt=javabin} status=0 QTime=12 
   [junit4]   2> 1421315 T3071 C1282 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1421320 T3071 C1282 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_3,generation=3}
   [junit4]   2> 1421321 T3071 C1282 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 1421322 T3071 C1282 oass.SolrIndexSearcher.<init> Opening Searcher@bb55ed2 main
   [junit4]   2> 1421323 T3071 C1282 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1421324 T3079 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bb55ed2 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):c1 _1(5.0):c1)}
   [junit4]   2> 1421324 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={softCommit=false&waitSearcher=true&commit=true&version=2&wt=javabin} {commit=} 0 9
   [junit4]   2> 1421327 T3083 C1283 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1421333 T3083 C1283 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_3,generation=3}
   [junit4]   2> 1421334 T3083 C1283 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 1421336 T3083 C1283 oass.SolrIndexSearcher.<init> Opening Searcher@5a65ccde main
   [junit4]   2> 1421336 T3083 C1283 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1421337 T3091 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5a65ccde main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):c1 _1(5.0):c1)}
   [junit4]   2> 1421337 T3083 C1283 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={softCommit=false&waitSearcher=true&commit=true&version=2&wt=javabin} {commit=} 0 10
   [junit4]   2> 1421340 T3071 C1282 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/select params={q=*:*&version=2&wt=javabin} hits=2 status=0 QTime=0 
   [junit4]   2> 1421343 T3083 C1283 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/select params={q=*:*&version=2&wt=javabin} hits=2 status=0 QTime=0 
   [junit4]   2> 1421351 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[3 (3)]} 0 4
   [junit4]   2> 1421355 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[4 (4)]} 0 1
   [junit4]   2> 1421360 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[5 (5)]} 0 2
   [junit4]   2> 1421365 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[6 (6)]} 0 2
   [junit4]   2> 1421369 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[7 (7)]} 0 1
   [junit4]   2> 1421373 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[8 (8)]} 0 1
   [junit4]   2> 1421377 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[9 (9)]} 0 1
   [junit4]   2> 1421382 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={update.distrib=FROMLEADER&version=2&wt=javabin} {add=[10 (10)]} 0 2
   [junit4]   2> 1421385 T3083 C1283 oasu.PeerSync.sync PeerSync: core=collection1 url= START replicas=[127.0.0.1:18927/_zs/p] nUpdates=100
   [junit4]   2> 1421388 T3072 C1282 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={getVersions=100&version=2&distrib=false&qt=/get&wt=javabin} status=0 QTime=0 
   [junit4]   2> 1421388 T3083 C1283 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=  Received 10 versions from 127.0.0.1:18927/_zs/p
   [junit4]   2> 1421389 T3083 C1283 oasu.PeerSync.requestUpdates PeerSync: core=collection1 url= Requesting updates from 127.0.0.1:18927/_zs/pn=8 versions=[10, 9, 8, 7, 6, 5, 4, 3]
   [junit4]   2> 1421391 T3072 C1282 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={version=2&distrib=false&qt=/get&wt=javabin&getUpdates=10,9,8,7,6,5,4,3} status=0 QTime=0 
   [junit4]   2> 1421402 T3083 C1283 oasup.LogUpdateProcessor.finish [collection1] {add=[3 (3), 4 (4), 5 (5), 6 (6), 7 (7), 8 (8), 9 (9), 10 (10)]} 0 10
   [junit4]   2> 1421402 T3083 C1283 oasu.PeerSync.sync PeerSync: core=collection1 url= DONE. sync succeeded
   [junit4]   2> 1421403 T3083 C1283 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/get params={getVersions=100&sync=127.0.0.1:18927/_zs/p&version=2&qt=/get&wt=javabin} status=0 QTime=18 
   [junit4]   2> 1421405 T3071 C1282 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1421484 T3071 C1282 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_4,generation=4}
   [junit4]   2> 1421484 T3071 C1282 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 1421486 T3071 C1282 oass.SolrIndexSearcher.<init> Opening Searcher@26c19483 main
   [junit4]   2> 1421487 T3071 C1282 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1421488 T3079 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@26c19483 main{StandardDirectoryReader(segments_4:9:nrt _3(5.0):C10)}
   [junit4]   2> 1421488 T3071 C1282 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={softCommit=false&waitSearcher=true&commit=true&version=2&wt=javabin} {commit=} 0 83
   [junit4]   2> 1421491 T3083 C1283 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1421550 T3083 C1283 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_4,generation=4}
   [junit4]   2> 1421551 T3083 C1283 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 1421553 T3083 C1283 oass.SolrIndexSearcher.<init> Opening Searcher@50e7c69d main
   [junit4]   2> 1421553 T3083 C1283 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1421554 T3091 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50e7c69d main{StandardDirectoryReader(segments_4:9:nrt _3(5.0):C10)}
   [junit4]   2> 1421555 T3083 C1283 oasup.LogUpdateProcessor.finish [collection1] webapp=/_zs/p path=/update params={softCommit=false&waitSearcher=true&commit=true&version=2&wt=javabin} {commit=} 0 64
   [junit4]   2> 1421557 T3071 C1282 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/select params={q=*:*&version=2&wt=javabin} hits=10 status=0 QTime=0 
   [junit4]   2> 1421560 T3083 C1283 oasc.SolrCore.execute [collection1] webapp=/_zs/p path=/select params={q=*:*&version=2&wt=javabin} hits=10 status=0 QTime=0 
   [junit4]   2> 1421563 T3056 oas.BaseDistributedSearchTestCase.compareSolrResponses ERROR Mismatched responses:
   [junit4]   2> 	{responseHeader={status=0,QTime=0},response={numFound=10,start=0,docs=[SolrDocument{id=3, range_facet_si=3, range_facet_l=[3], range_facet_sl=[3], _version_=3, n_ti1=398760759, n_f1=0.79486823, n_tf1=0.3475845, n_d1=0.7691069245121166, n_td1=0.5755156290238749, n_l1=7926319543551721947, n_tl1=2209575646856325768, n_dt1=Thu Oct 25 04:37:08 ART 74941060, n_tdt1=Sat Aug 03 20:59:24 ART 92080937, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=4, range_facet_si=4, range_facet_l=[4], range_facet_sl=[4], _version_=4, n_ti1=622935526, n_f1=0.20816708, n_tf1=0.522238, n_d1=0.1121397450212418, n_td1=0.7470340857425672, n_l1=-8505545731434569840, n_tl1=-725921375138321574, n_dt1=Fri Aug 23 23:51:08 ART 91682605, n_tdt1=Tue Oct 07 03:54:57 ART 285117614, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=5, range_facet_si=5, range_facet_l=[5], range_facet_sl=[5], _version_=5, n_ti1=347443943, n_f1=0.17525578, n_tf1=0.9628041, n_d1=0.5563709177281216, n_td1=0.685058806128004, n_l1=-1523032829780179790, n_tl1=-1147327034867280305, n_dt1=Tue Dec 12 08:50:28 ART 72754147, n_tdt1=Sat Mar 28 06:11:09 ART 75180448, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=6, range_facet_si=6, range_facet_l=[6], range_facet_sl=[6], _version_=6, n_ti1=-747328606, n_f1=0.74413556, n_tf1=0.44321454, n_d1=0.41453798250404283, n_td1=0.571536996932703, n_l1=7469308103414134415, n_tl1=-1181244973744245117, n_dt1=Mon Apr 25 00:29:47 ART 150657267, n_tdt1=Wed Nov 15 13:36:05 ART 109755256, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=7, range_facet_si=7, range_facet_l=[7], range_facet_sl=[7], _version_=7, n_ti1=1181155955, n_f1=0.49027056, n_tf1=0.5960829, n_d1=0.550078742465184, n_td1=0.7815569220370907, n_l1=-5238226227783969612, n_tl1=-2666051301283077671, n_dt1=Tue Mar 26 23:28:24 ART 5703574, n_tdt1=Sat Feb 01 14:19:34 ART 84029721, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=8, range_facet_si=8, range_facet_l=[8], range_facet_sl=[8], _version_=8, n_ti1=1623878245, n_f1=0.96042657, n_tf1=0.5623742, n_d1=0.6065438091373003, n_td1=0.4975670596329992, n_l1=8686156877586229717, n_tl1=3404523220799243599, n_dt1=Wed Sep 14 11:34:13 ART 141779064, n_tdt1=Sun Dec 08 21:04:55 ART 105370695, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=9, range_facet_si=9, range_facet_l=[9], range_facet_sl=[9], _version_=9, n_ti1=2043306743, n_f1=0.9398676, n_tf1=0.39716846, n_d1=0.5827679282149939, n_td1=0.679571420456672, n_l1=7498025882369970426, n_tl1=-3363324170880791817, n_dt1=Tue Sep 09 23:57:50 ART 239923952, n_tdt1=Thu Jan 06 22:22:10 ART 33081335, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=10, range_facet_si=10, range_facet_l=[10], range_facet_sl=[10], _version_=10, n_ti1=-352030854, n_f1=0.022055805, n_tf1=0.6511533, n_d1=0.6294998981724105, n_td1=0.5724863286676631, n_l1=9136142155904523700, n_tl1=-9002668801883261239, n_dt1=Mon Feb 20 17:59:18 ART 2008040, n_tdt1=Tue May 13 07:45:18 ART 151502064, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=2, range_facet_si=2, range_facet_l=[2], range_facet_sl=[2], _version_=2, n_ti1=1453723720, n_f1=0.46580738, n_tf1=0.9154596, n_d1=0.4746864656571518, n_td1=0.8993103067325791, n_l1=7408740578201042841, n_tl1=977016817822976096, n_dt1=Mon Dec 19 09:59:05 ART 199215661, n_tdt1=Wed Dec 15 00:24:06 ART 45322094, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=1, range_facet_si=1, range_facet_l=[1], range_facet_sl=[1], _version_=1, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}]}}
   [junit4]   2> 	{responseHeader={status=0,QTime=0},response={numFound=10,start=0,docs=[SolrDocument{id=3, range_facet_si=3, range_facet_l=[3], range_facet_sl=[3], _version_=3, n_ti1=398760759, n_f1=0.79486823, n_tf1=0.3475845, n_d1=0.7691069245121166, n_td1=0.5755156290238749, n_l1=7926319543551721947, n_tl1=2209575646856325768, n_dt1=Thu Oct 25 04:37:08 ART 74941060, n_tdt1=Sat Aug 03 20:59:24 ART 92080937, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=4, range_facet_si=4, range_facet_l=[4], range_facet_sl=[4], _version_=4, n_ti1=622935526, n_f1=0.20816708, n_tf1=0.522238, n_d1=0.1121397450212418, n_td1=0.7470340857425672, n_l1=-8505545731434569840, n_tl1=-725921375138321574, n_dt1=Fri Aug 23 23:51:08 ART 91682605, n_tdt1=Tue Oct 07 03:54:57 ART 285117614, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=5, range_facet_si=5, range_facet_l=[5], range_facet_sl=[5], _version_=5, n_ti1=347443943, n_f1=0.17525578, n_tf1=0.9628041, n_d1=0.5563709177281216, n_td1=0.685058806128004, n_l1=-1523032829780179790, n_tl1=-1147327034867280305, n_dt1=Tue Dec 12 08:50:28 ART 72754147, n_tdt1=Sat Mar 28 06:11:09 ART 75180448, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=6, range_facet_si=6, range_facet_l=[6], range_facet_sl=[6], _version_=6, n_ti1=-747328606, n_f1=0.74413556, n_tf1=0.44321454, n_d1=0.41453798250404283, n_td1=0.571536996932703, n_l1=7469308103414134415, n_tl1=-1181244973744245117, n_dt1=Mon Apr 25 00:29:47 ART 150657267, n_tdt1=Wed Nov 15 13:36:05 ART 109755256, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=7, range_facet_si=7, range_facet_l=[7], range_facet_sl=[7], _version_=7, n_ti1=1181155955, n_f1=0.49027056, n_tf1=0.5960829, n_d1=0.550078742465184, n_td1=0.7815569220370907, n_l1=-5238226227783969612, n_tl1=-2666051301283077671, n_dt1=Tue Mar 26 23:28:24 ART 5703574, n_tdt1=Sat Feb 01 14:19:34 ART 84029721, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=8, range_facet_si=8, range_facet_l=[8], range_facet_sl=[8], _version_=8, n_ti1=1623878245, n_f1=0.96042657, n_tf1=0.5623742, n_d1=0.6065438091373003, n_td1=0.4975670596329992, n_l1=8686156877586229717, n_tl1=3404523220799243599, n_dt1=Wed Sep 14 11:34:13 ART 141779064, n_tdt1=Sun Dec 08 21:04:55 ART 105370695, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=9, range_facet_si=9, range_facet_l=[9], range_facet_sl=[9], _version_=9, n_ti1=2043306743, n_f1=0.9398676, n_tf1=0.39716846, n_d1=0.5827679282149939, n_td1=0.679571420456672, n_l1=7498025882369970426, n_tl1=-3363324170880791817, n_dt1=Tue Sep 09 23:57:50 ART 239923952, n_tdt1=Thu Jan 06 22:22:10 ART 33081335, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=10, range_facet_si=10, range_facet_l=[10], range_facet_sl=[10], _version_=10, n_ti1=-352030854, n_f1=0.022055805, n_tf1=0.6511533, n_d1=0.6294998981724105, n_td1=0.5724863286676631, n_l1=9136142155904523700, n_tl1=-9002668801883261239, n_dt1=Mon Feb 20 17:59:18 ART 2008040, n_tdt1=Tue May 13 07:45:18 ART 151502064, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=1, range_facet_si=1, range_facet_l=[1], range_facet_sl=[1], _version_=1, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}, SolrDocument{id=2, range_facet_si=2, range_facet_l=[2], range_facet_sl=[2], _version_=2, n_ti1=1453723720, n_f1=0.46580738, n_tf1=0.9154596, n_d1=0.4746864656571518, n_td1=0.8993103067325791, n_l1=7408740578201042841, n_tl1=977016817822976096, n_dt1=Mon Dec 19 09:59:05 ART 199215661, n_tdt1=Wed Dec 15 00:24:06 ART 45322094, intDefault=42, multiDefault=[muLti-Default], timestamp=Tue Dec 03 00:45:30 ART 2013}]}}
   [junit4]   2> 1421565 T3056 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1810276394
   [junit4]   2> 1421566 T3056 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@37084695
   [junit4]   2> 1421571 T3056 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
   [junit4]   2> 1421572 T3056 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1421572 T3056 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1421572 T3056 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1421573 T3056 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1421574 T3056 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1421574 T3056 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.PeerSyncTest-1386042323538/control/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.PeerSyncTest-1386042323538/control/data/index;done=false>>]
   [junit4]   2> 1421574 T3056 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.PeerSyncTest-1386042323538/control/data/index
   [junit4]   2> 1421575 T3056 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.PeerSyncTest-1386042323538/control/data [CachedDir<<refCount=0;path=./org.apache.solr.update.PeerSyncTest-1386042323538/control/data;done=false>>]
   [junit4]   2> 1421575 T3056 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.PeerSyncTest-1386042323538/control/data
   [junit4]   2> 1421576 T3056 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/_zs/p,null}
   [junit4]   2> 1421630 T3056 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1731194072
   [junit4]   2> 1421631 T3056 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@50bd3023
   [junit4]   2> 1421636 T3056 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=2088,transaction_logs_total_number=3}
   [junit4]   2> 1421636 T3056 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1421637 T3056 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1421637 T3056 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1421639 T3056 C1282 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_4,generation=4}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@354b1ebe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39e209fa; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_5,generation=5}
   [junit4]   2> 1421639 T3056 C1282 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
   [junit4]   2> 1421640 T3056 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1421692 T3056 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1421692 T3056 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data [CachedDir<<refCount=0;path=./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data;done=false>>]
   [junit4]   2> 1421692 T3056 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data
   [junit4]   2> 1421693 T3056 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data/index;done=false>>]
   [junit4]   2> 1421693 T3056 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.PeerSyncTest-1386042323538/shard0/data/index
   [junit4]   2> 1421694 T3056 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/_zs/p,null}
   [junit4]   2> 1421747 T3056 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=843877291
   [junit4]   2> 1421748 T3056 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@af036
   [junit4]   2> 1421753 T3056 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=2088,transaction_logs_total_number=3}
   [junit4]   2> 1421754 T3056 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1421754 T3056 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1421754 T3056 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1421756 T3056 C1283 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_4,generation=4}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@62600b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7fc6e1f2; maxCacheMB=0.5537109375 maxMergeSizeMB=0.318359375)),segFN=segments_5,generation=5}
   [junit4]   2> 1421756 T3056 C1283 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
   [junit4]   2> 1421757 T3056 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1421757 T3056 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1421758 T3056 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data/index;done=false>>]
   [junit4]   2> 1421758 T3056 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data/index
   [junit4]   2> 1421758 T3056 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data [CachedDir<<refCount=0;path=./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data;done=false>>]
   [junit4]   2> 1421759 T3056 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.PeerSyncTest-1386042323538/shard1/data
   [junit4]   2> 1421759 T3056 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/_zs/p,null}
   [junit4]   2> 1421814 T3056 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1170173532
   [junit4]   2> 1421815 T3056 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@6958efdb
   [junit4]   2> 1421823 T3056 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
   [junit4]   2> 1421824 T3056 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1421824 T3056 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1421825 T3056 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1421826 T3056 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1421826 T3056 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1421826 T3056 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data/index [CachedDir<<refCount=0;path=./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data/index;done=false>>]
   [junit4]   2> 1421827 T3056 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data/index
   [junit4]   2> 1421827 T3056 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data [CachedDir<<refCount=0;path=./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data;done=false>>]
   [junit4]   2> 1421827 T3056 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.update.PeerSyncTest-1386042323538/shard2/data
   [junit4]   2> 1421828 T3056 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/_zs/p,null}
   [junit4]   2> 1421978 T3056 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=PeerSyncTest -Dtests.method=testDistribSearch -Dtests.seed=68F3C30A3C1BA0B -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/hudson/lucene-data/enwiki.random.lines.txt -Dtests.locale=ca -Dtests.timezone=America/Jujuy -Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 7.92s J0 | PeerSyncTest.testDistribSearch <<<
   [junit4]    > Throwable #1: junit.framework.AssertionFailedError: .response[8][id][0]:2!=1
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([68F3C30A3C1BA0B:8769B228D49EDA37]:0)
   [junit4]    > 	at junit.framework.Assert.fail(Assert.java:50)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:816)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:835)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.queryAndCompare(BaseDistributedSearchTestCase.java:581)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.queryAndCompare(BaseDistributedSearchTestCase.java:572)
   [junit4]    > 	at org.apache.solr.update.PeerSyncTest.doTest(PeerSyncTest.java:98)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:843)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:724)
   [junit4]   2> 1422002 T3056 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Lucene46: {n_d1=PostingsFormat(name=MockRandom), n_tl1=FST41, n_td1=FST41, _version_=FST41, n_dt1=FST41, n_f1=FST41, range_facet_sl=PostingsFormat(name=SimpleText), id=PostingsFormat(name=SimpleText), n_ti1=PostingsFormat(name=SimpleText), n_l1=PostingsFormat(name=MockRandom), n_tf1=PostingsFormat(name=MockRandom), n_tdt1=FST41, range_facet_l=PostingsFormat(name=MockRandom), timestamp=PostingsFormat(name=MockRandom), range_facet_si=PostingsFormat(name=MockRandom), intDefault=FST41, multiDefault=PostingsFormat(name=MockRandom)}, docValues:{timestamp=DocValuesFormat(name=Lucene45)}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ca, timezone=America/Jujuy
   [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_25 (64-bit)/cpus=16,threads=5,free=137859168,total=354418688
   [junit4]   2> NOTE: All tests run in this JVM: [HdfsBasicDistributedZk2Test, HdfsLockFactoryTest, CircularListTest, TestDocSet, ParsingFieldUpdateProcessorsTest, DistributedSuggesterComponentTest, TestNonNRTOpen, SliceStateTest, IndexSchemaTest, DocValuesTest, TestLRUCache, SearchHandlerTest, BasicDistributedZkTest, TestExtendedDismaxParser, CacheHeaderTest, TestPerFieldSimilarity, TestJoin, DistributedTermsComponentTest, AutoCommitTest, BJQParserTest, ShowFileRequestHandlerTest, PrimitiveFieldTypeTest, QueryEqualityTest, TermVectorComponentTest, AbstractAnalyticsFacetTest, ReturnFieldsTest, SolrInfoMBeanTest, TestReplicationHandler, StatelessScriptUpdateProcessorFactoryTest, AnalysisAfterCoreReloadTest, TestNoOpRegenerator, BadComponentTest, QueryElevationComponentTest, TestCoreContainer, TestFaceting, TestQueryUtils, ClusterStateTest, TestSuggestSpellingConverter, LukeRequestHandlerTest, TestPostingsSolrHighlighter, AddSchemaFieldsUpdateProcessorFactoryTest, FastVectorHighlighterTest, DistributedSpellCheckComponentTest, UUIDFieldTest, TestLMDirichletSimilarityFactory, TestMaxScoreQueryParser, TestFieldCollectionResource, TestElisionMultitermQuery, JsonLoaderTest, TestSystemIdResolver, TestDocumentBuilder, SchemaVersionSpecificBehaviorTest, SampleTest, SliceStateUpdateTest, CopyFieldTest, AssignTest, QueryParsingTest, InfoHandlerTest, TestReloadAndDeleteDocs, TriLevelCompositeIdRoutingTest, PeerSyncTest]
   [junit4] Completed on J0 in 7.95s, 1 test, 1 failure <<< FAILURES!

[...truncated 675 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:427: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:400: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:37: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:189: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:491: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1276: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:909: There were test failures: 357 suites, 1570 tests, 1 failure, 32 ignored (4 assumptions)

Total time: 135 minutes 38 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message