[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.7.0_21) - Build # 5673 - Failure!

[Policeman Jenkins Server <je...@thetaphi.de>]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/5673/
Java: 32bit/jdk1.7.0_21 -client -XX:+UseSerialGC

1 tests failed.
REGRESSION:  org.apache.solr.handler.TestReplicationHandler.doTestStressReplication

Error Message:
timed out waiting for collection1 startAt time to exceed: Thu May 16 02:04:33 AST 2013

Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Thu May 16 02:04:33 AST 2013
	at __randomizedtesting.SeedInfo.seed([1A8EBE68600B7D0C:C125BEAE652314BF]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1473)
	at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:777)
	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:601)
	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:722)




Build Log:
[...truncated 9582 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4:junit4]   2> 613572 T2427 oas.SolrTestCaseJ4.setUp ###Starting testEmptyCommits
[junit4:junit4]   2> 613574 T2427 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 613577 T2427 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49307
[junit4:junit4]   2> 613577 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 613578 T2427 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 613578 T2427 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master
[junit4:junit4]   2> 613579 T2427 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/solr.xml
[junit4:junit4]   2> 613579 T2427 oasc.CoreContainer.<init> New CoreContainer 1262503
[junit4:junit4]   2> 613580 T2427 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4]   2> 613580 T2427 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/'
[junit4:junit4]   2> 613580 T2427 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/'
[junit4:junit4]   2> 613634 T2427 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 613634 T2427 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 613634 T2427 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 613635 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 613635 T2427 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 613635 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 613636 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 613636 T2427 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 613636 T2427 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 613637 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 613640 T2427 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 613643 T2437 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1
[junit4:junit4]   2> 613643 T2437 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/'
[junit4:junit4]   2> 613672 T2437 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 613711 T2437 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 613712 T2437 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 613714 T2437 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 613726 T2437 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 613727 T2437 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 613727 T2437 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data/
[junit4:junit4]   2> 613727 T2437 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 613727 T2437 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 613728 T2437 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data
[junit4:junit4]   2> 613729 T2437 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data/index/
[junit4:junit4]   2> 613729 T2437 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 613729 T2437 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data/index
[junit4:junit4]   2> 613730 T2437 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_1,generation=1}
[junit4:junit4]   2> 613730 T2437 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 613731 T2437 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 613731 T2437 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 613731 T2437 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 613732 T2437 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 613732 T2437 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 613732 T2437 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 613734 T2437 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 613736 T2437 oass.SolrIndexSearcher.<init> Opening Searcher@19a0537 main
[junit4:junit4]   2> 613736 T2437 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 613737 T2437 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 613737 T2437 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4]   2> 613737 T2437 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 613737 T2437 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 613737 T2438 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19a0537 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 613738 T2427 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 613739 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 613740 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 613749 T2427 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 613752 T2427 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:35040
[junit4:junit4]   2> 613753 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 613753 T2427 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 613753 T2427 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave
[junit4:junit4]   2> 613754 T2427 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/solr.xml
[junit4:junit4]   2> 613754 T2427 oasc.CoreContainer.<init> New CoreContainer 3746119
[junit4:junit4]   2> 613754 T2427 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4]   2> 613755 T2427 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/'
[junit4:junit4]   2> 613755 T2427 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/'
[junit4:junit4]   2> 613810 T2427 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 613810 T2427 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 613810 T2427 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 613811 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 613811 T2427 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 613811 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 613812 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 613812 T2427 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 613812 T2427 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 613813 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 613815 T2427 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 613818 T2449 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1
[junit4:junit4]   2> 613819 T2449 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/'
[junit4:junit4]   2> 613851 T2449 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 613895 T2449 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 613895 T2449 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 613897 T2449 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 613907 T2449 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 613908 T2449 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 613908 T2449 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/
[junit4:junit4]   2> 613908 T2449 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 613909 T2449 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 613909 T2449 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data
[junit4:junit4]   2> 613910 T2449 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index/
[junit4:junit4]   2> 613910 T2449 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 613910 T2449 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index
[junit4:junit4]   2> 613911 T2449 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_1,generation=1}
[junit4:junit4]   2> 613911 T2449 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 613912 T2449 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 613912 T2449 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 613912 T2449 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 613912 T2449 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 613913 T2449 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 613913 T2449 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 613914 T2449 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 613916 T2449 oass.SolrIndexSearcher.<init> Opening Searcher@11b8e1f main
[junit4:junit4]   2> 613916 T2449 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 613916 T2449 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 613918 T2449 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 613918 T2449 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 613918 T2450 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11b8e1f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 613919 T2427 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 613919 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 613920 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C117 name=collection1 org.apache.solr.core.SolrCore@1fbccbf
[junit4:junit4]   2> 613934 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 613939 T2434 C117 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_1,generation=1}
[junit4:junit4]   2> 613940 T2434 C117 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 613940 T2434 C117 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 3
[junit4:junit4]   2> 613943 T2435 C117 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 613944 T2435 C117 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_2,generation=2}
[junit4:junit4]   2> 613944 T2435 C117 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 613945 T2435 C117 oass.SolrIndexSearcher.<init> Opening Searcher@ed1c5b main
[junit4:junit4]   2> 613946 T2435 C117 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 613946 T2438 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ed1c5b main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 613946 T2435 C117 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} {commit=} 0 3
[junit4:junit4]   2> 613953 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 613954 T2451 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 613954 T2451 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 613954 T2451 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> ASYNC  NEW_CORE C118 name=collection1 org.apache.solr.core.SolrCore@4ff70f
[junit4:junit4]   2> 613955 T2445 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:49307/solr/replication&command=fetchindex&wait=true} status=0 QTime=0 
[junit4:junit4]   2> 613957 T2446 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:empty1&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 613958 T2427 oash.TestReplicationHandler.rQuery Waiting for 1 docs
[junit4:junit4]   2> 613958 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=2 
[junit4:junit4]   2> 613960 T2451 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 11
[junit4:junit4]   2> 613972 T2451 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403020
[junit4:junit4]   2> 613972 T2451 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index2742227428tmp lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@63e865) fullCopy=false
[junit4:junit4]   2> 613975 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 613980 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_TestBloomFilteredLucene41Postings_0.blm&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 613984 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_TestBloomFilteredLucene41Postings_0.tim&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 613988 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvd&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 613993 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 613997 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_TestBloomFilteredLucene41Postings_0.doc&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 614001 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 614005 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_TestBloomFilteredLucene41Postings_0.tip&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 614009 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvm&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 614013 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 614017 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 614019 T2451 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 614026 T2451 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 614027 T2451 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 614028 T2451 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_2,generation=2}
[junit4:junit4]   2> 614029 T2451 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 614030 T2451 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 614030 T2451 oass.SolrIndexSearcher.<init> Opening Searcher@1e08cd9 main
[junit4:junit4]   2> 614031 T2450 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e08cd9 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 614032 T2451 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403020 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403020;done=true>>]
[junit4:junit4]   2> 614032 T2451 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403020
[junit4:junit4]   2> 614033 T2451 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403020
[junit4:junit4]   2> 614061 T2447 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:empty1&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 614064 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 614067 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 614072 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614073 T2448 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4 
[junit4:junit4]   2> 614076 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614078 T2445 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614081 T2434 C117 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 614084 T2434 C117 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_3,generation=3}
[junit4:junit4]   2> 614085 T2434 C117 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4:junit4]   2> 614086 T2434 C117 oass.SolrIndexSearcher.<init> Opening Searcher@11a3ff3 main
[junit4:junit4]   2> 614086 T2434 C117 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 614086 T2438 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11a3ff3 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 614088 T2434 C117 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} {commit=} 0 7
[junit4:junit4]   2> 614089 T2456 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   2> 614090 T2456 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 614092 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614093 T2456 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4:junit4]   2> 614094 T2456 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4:junit4]   2> 614094 T2456 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 614096 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614097 T2456 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 11
[junit4:junit4]   2> 614099 T2456 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403147
[junit4:junit4]   2> 614100 T2456 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index2742227429tmp lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1173a0a) fullCopy=false
[junit4:junit4]   2> 614100 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4:junit4]   2> 614100 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.blm because it already exists
[junit4:junit4]   2> 614101 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.tim because it already exists
[junit4:junit4]   2> 614101 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvd because it already exists
[junit4:junit4]   2> 614102 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.doc because it already exists
[junit4:junit4]   2> 614102 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4:junit4]   2> 614103 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.tip because it already exists
[junit4:junit4]   2> 614103 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvm because it already exists
[junit4:junit4]   2> 614104 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4:junit4]   2> 614108 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   2> 614109 T2456 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4:junit4]   2> 614110 T2456 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 614114 T2456 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 614115 T2456 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 614115 T2456 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4]   2> 614119 T2456 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_3,generation=3}
[junit4:junit4]   2> 614119 T2456 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4:junit4]   2> 614120 T2456 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 614121 T2456 oass.SolrIndexSearcher.<init> Opening Searcher@11d349d main
[junit4:junit4]   2> 614122 T2450 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11d349d main{StandardDirectoryReader(segments_3:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 614122 T2456 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403147 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403147;done=true>>]
[junit4:junit4]   2> 614123 T2456 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403147
[junit4:junit4]   2> 614124 T2456 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403147
[junit4:junit4]   2> 614124 T2446 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:49307/solr/replication&command=fetchindex&wait=true} status=0 QTime=35 
[junit4:junit4]   2> 614128 T2434 C117 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4:junit4]   2> 614130 T2436 C117 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 614133 T2436 C117 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_3,generation=3}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_4,generation=4}
[junit4:junit4]   2> 614134 T2436 C117 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4:junit4]   2> 614134 T2436 C117 oass.SolrIndexSearcher.<init> Opening Searcher@167df44 main
[junit4:junit4]   2> 614135 T2436 C117 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 614135 T2438 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@167df44 main{StandardDirectoryReader(segments_4:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4]   2> 614135 T2436 C117 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} {commit=} 0 5
[junit4:junit4]   2> 614136 T2458 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   2> 614136 T2458 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 614138 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614138 T2458 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4:junit4]   2> 614139 T2458 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4:junit4]   2> 614139 T2458 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 614141 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=4&version=2} status=0 QTime=1 
[junit4:junit4]   2> 614141 T2458 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 21
[junit4:junit4]   2> 614142 T2458 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403191
[junit4:junit4]   2> 614142 T2458 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index2742227430tmp lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cdc5bf) fullCopy=false
[junit4:junit4]   2> 614145 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=1 
[junit4:junit4]   2> 614145 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4:junit4]   2> 614146 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.blm because it already exists
[junit4:junit4]   2> 614154 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_TestBloomFilteredLucene41Postings_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614155 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.tim because it already exists
[junit4:junit4]   2> 614157 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614158 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.tip because it already exists
[junit4:junit4]   2> 614160 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_TestBloomFilteredLucene41Postings_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614162 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_TestBloomFilteredLucene41Postings_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614164 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvm because it already exists
[junit4:junit4]   2> 614166 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614169 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_TestBloomFilteredLucene41Postings_0.blm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614172 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614175 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614179 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614181 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvd because it already exists
[junit4:junit4]   2> 614181 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.doc because it already exists
[junit4:junit4]   2> 614182 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4:junit4]   2> 614182 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4:junit4]   2> 614182 T2458 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4:junit4]   2> 614185 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_4&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0 
[junit4:junit4]   2> 614187 T2458 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 614193 T2458 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 614193 T2458 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 614193 T2458 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4]   2> 614194 T2458 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=3
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_3,generation=3}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_4,generation=4}
[junit4:junit4]   2> 614194 T2458 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4:junit4]   2> 614195 T2458 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 614195 T2458 oass.SolrIndexSearcher.<init> Opening Searcher@5e4928 main
[junit4:junit4]   2> 614195 T2450 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5e4928 main{StandardDirectoryReader(segments_4:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4]   2> 614196 T2458 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403191 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403191;done=true>>]
[junit4:junit4]   2> 614196 T2458 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403191
[junit4:junit4]   2> 614196 T2458 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403191
[junit4:junit4]   2> 614196 T2447 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:49307/solr/replication&command=fetchindex&wait=true} status=0 QTime=60 
[junit4:junit4]   2> 614199 T2445 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:empty2&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 614201 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614203 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614207 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614207 T2447 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=2 
[junit4:junit4]   2> 614209 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614211 T2448 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614214 T2435 C117 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4:junit4]   2> 614215 T2434 C117 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 614217 T2434 C117 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=4
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_3,generation=3}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_4,generation=4}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_5,generation=5}
[junit4:junit4]   2> 614217 T2434 C117 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4:junit4]   2> 614218 T2434 C117 oass.SolrIndexSearcher.<init> Opening Searcher@6dfc4a realtime
[junit4:junit4]   2> 614218 T2434 C117 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 614218 T2434 C117 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={openSearcher=false&commit=true&wt=javabin&version=2} {commit=} 0 3
[junit4:junit4]   2> 614219 T2460 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   2> 614220 T2460 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 614221 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614221 T2460 oash.SnapPuller.fetchLatestIndex Master's generation: 5
[junit4:junit4]   2> 614222 T2460 oash.SnapPuller.fetchLatestIndex Slave's generation: 4
[junit4:junit4]   2> 614222 T2460 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 614224 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=5&version=2} status=0 QTime=1 
[junit4:junit4]   2> 614224 T2460 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 31
[junit4:junit4]   2> 614225 T2460 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403273
[junit4:junit4]   2> 614225 T2460 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index2742227431tmp lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16d5c9c) fullCopy=false
[junit4:junit4]   2> 614227 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614228 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1_TestBloomFilteredLucene41Postings_0.tim because it already exists
[junit4:junit4]   2> 614228 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.tim because it already exists
[junit4:junit4]   2> 614229 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1_TestBloomFilteredLucene41Postings_0.tip because it already exists
[junit4:junit4]   2> 614229 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.tip because it already exists
[junit4:junit4]   2> 614229 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fnm because it already exists
[junit4:junit4]   2> 614229 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvd because it already exists
[junit4:junit4]   2> 614231 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_TestBloomFilteredLucene41Postings_0.blm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614232 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4:junit4]   2> 614234 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_TestBloomFilteredLucene41Postings_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614236 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1.nvm because it already exists
[junit4:junit4]   2> 614236 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4:junit4]   2> 614236 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.blm because it already exists
[junit4:junit4]   2> 614237 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1.nvd because it already exists
[junit4:junit4]   2> 614240 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=1 
[junit4:junit4]   2> 614241 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1_TestBloomFilteredLucene41Postings_0.doc because it already exists
[junit4:junit4]   2> 614242 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvm because it already exists
[junit4:junit4]   2> 614244 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614255 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614256 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdx because it already exists
[junit4:junit4]   2> 614257 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1_TestBloomFilteredLucene41Postings_0.blm because it already exists
[junit4:junit4]   2> 614259 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614260 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdt because it already exists
[junit4:junit4]   2> 614262 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_TestBloomFilteredLucene41Postings_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614263 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _1.si because it already exists
[junit4:junit4]   2> 614265 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614266 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.doc because it already exists
[junit4:junit4]   2> 614266 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4:junit4]   2> 614268 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_TestBloomFilteredLucene41Postings_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614270 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_5&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0 
[junit4:junit4]   2> 614271 T2460 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4:junit4]   2> 614271 T2460 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 614275 T2460 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 614275 T2460 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 614276 T2460 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4]   2> 614277 T2460 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=3
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_4,generation=4}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_5,generation=5}
[junit4:junit4]   2> 614277 T2460 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4:junit4]   2> 614278 T2460 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 614278 T2460 oass.SolrIndexSearcher.<init> Opening Searcher@13d579c main
[junit4:junit4]   2> 614279 T2450 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13d579c main{StandardDirectoryReader(segments_5:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4:junit4]   2> 614279 T2460 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403273 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403273;done=true>>]
[junit4:junit4]   2> 614279 T2460 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403273
[junit4:junit4]   2> 614279 T2460 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403273
[junit4:junit4]   2> 614280 T2446 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:49307/solr/replication&command=fetchindex&wait=true} status=0 QTime=61 
[junit4:junit4]   2> 614282 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:empty3&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 614284 T2445 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:empty3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 614287 T2435 C117 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 1
[junit4:junit4]   2> 614288 T2434 C117 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 614290 T2434 C117 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=5
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_3,generation=3}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_4,generation=4}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_5,generation=5}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d87466 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8daec1),segFN=segments_6,generation=6}
[junit4:junit4]   2> 614290 T2434 C117 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4:junit4]   2> 614291 T2434 C117 oass.SolrIndexSearcher.<init> Opening Searcher@1ff4031 main
[junit4:junit4]   2> 614292 T2434 C117 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 614292 T2438 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ff4031 main{StandardDirectoryReader(segments_6:9:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4:junit4]   2> 614292 T2434 C117 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} {commit=} 0 4
[junit4:junit4]   2> 614293 T2462 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   2> 614293 T2462 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 614295 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614296 T2462 oash.SnapPuller.fetchLatestIndex Master's generation: 6
[junit4:junit4]   2> 614296 T2462 oash.SnapPuller.fetchLatestIndex Slave's generation: 5
[junit4:junit4]   2> 614296 T2462 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 614298 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=6&version=2} status=0 QTime=1 
[junit4:junit4]   2> 614299 T2462 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 41
[junit4:junit4]   2> 614300 T2462 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403348
[junit4:junit4]   2> 614300 T2462 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index2742227432tmp lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1148e4b) fullCopy=false
[junit4:junit4]   2> 614300 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2.si because it already exists
[junit4:junit4]   2> 614302 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3_TestBloomFilteredLucene41Postings_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614305 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3_TestBloomFilteredLucene41Postings_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614306 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1_TestBloomFilteredLucene41Postings_0.tim because it already exists
[junit4:junit4]   2> 614306 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.tim because it already exists
[junit4:junit4]   2> 614306 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1_TestBloomFilteredLucene41Postings_0.tip because it already exists
[junit4:junit4]   2> 614307 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.tip because it already exists
[junit4:junit4]   2> 614308 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614311 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614312 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fnm because it already exists
[junit4:junit4]   2> 614314 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614315 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvd because it already exists
[junit4:junit4]   2> 614315 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2_TestBloomFilteredLucene41Postings_0.blm because it already exists
[junit4:junit4]   2> 614317 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3_TestBloomFilteredLucene41Postings_0.blm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614320 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614321 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4:junit4]   2> 614321 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2_TestBloomFilteredLucene41Postings_0.doc because it already exists
[junit4:junit4]   2> 614321 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1.nvm because it already exists
[junit4:junit4]   2> 614322 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4:junit4]   2> 614322 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.blm because it already exists
[junit4:junit4]   2> 614322 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1.nvd because it already exists
[junit4:junit4]   2> 614322 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2.fdt because it already exists
[junit4:junit4]   2> 614322 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1_TestBloomFilteredLucene41Postings_0.doc because it already exists
[junit4:junit4]   2> 614322 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvm because it already exists
[junit4:junit4]   2> 614323 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2.fdx because it already exists
[junit4:junit4]   2> 614325 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3_TestBloomFilteredLucene41Postings_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614326 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2.fnm because it already exists
[junit4:junit4]   2> 614335 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614337 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdx because it already exists
[junit4:junit4]   2> 614337 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1_TestBloomFilteredLucene41Postings_0.blm because it already exists
[junit4:junit4]   2> 614338 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2.nvm because it already exists
[junit4:junit4]   2> 614338 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdt because it already exists
[junit4:junit4]   2> 614338 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2_TestBloomFilteredLucene41Postings_0.tip because it already exists
[junit4:junit4]   2> 614339 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _1.si because it already exists
[junit4:junit4]   2> 614339 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2.nvd because it already exists
[junit4:junit4]   2> 614339 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0_TestBloomFilteredLucene41Postings_0.doc because it already exists
[junit4:junit4]   2> 614340 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4:junit4]   2> 614340 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _2_TestBloomFilteredLucene41Postings_0.tim because it already exists
[junit4:junit4]   2> 614343 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614347 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_6&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=6} status=0 QTime=0 
[junit4:junit4]   2> 614348 T2462 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4:junit4]   2> 614349 T2462 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 614356 T2462 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 614357 T2462 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 614357 T2462 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4]   2> 614360 T2462 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=4
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_4,generation=4}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_5,generation=5}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1941b9c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cd8fd2),segFN=segments_6,generation=6}
[junit4:junit4]   2> 614361 T2462 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4:junit4]   2> 614361 T2462 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 614363 T2462 oass.SolrIndexSearcher.<init> Opening Searcher@9d540d main
[junit4:junit4]   2> 614363 T2450 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9d540d main{StandardDirectoryReader(segments_6:9:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4:junit4]   2> 614364 T2462 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403348 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403348;done=true>>]
[junit4:junit4]   2> 614365 T2462 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403348
[junit4:junit4]   2> 614365 T2462 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index.20130516020403348
[junit4:junit4]   2> 614366 T2447 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:49307/solr/replication&command=fetchindex&wait=true} status=0 QTime=73 
[junit4:junit4]   2> 614369 T2433 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:(empty1+empty2+empty3+empty4)&wt=javabin&version=2} hits=4 status=0 QTime=1 
[junit4:junit4]   2> 614372 T2446 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:(empty1+empty2+empty3+empty4)&wt=javabin&version=2} hits=4 status=0 QTime=1 
[junit4:junit4]   2> 614375 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614378 T2434 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614384 T2436 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 614385 T2445 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4 
[junit4:junit4]   2> 614388 T2435 C117 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614390 T2447 C118 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 614391 T2427 oas.SolrTestCaseJ4.tearDown ###Ending testEmptyCommits
[junit4:junit4]   2> 614413 T2427 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1262503
[junit4:junit4]   2> 614415 T2427 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1fbccbf
[junit4:junit4]   2> 614416 T2427 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 614417 T2427 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 614418 T2427 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 614418 T2427 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 614420 T2427 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 614421 T2427 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   2> 614422 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data;done=false>>]
[junit4:junit4]   2> 614422 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data
[junit4:junit4]   2> 614423 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data/index;done=false>>]
[junit4:junit4]   2> 614424 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042621/master/collection1/data/index
[junit4:junit4]   2> 614425 T2427 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 614499 T2427 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=3746119
[junit4:junit4]   2> 614501 T2427 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4ff70f
[junit4:junit4]   2> 614502 T2427 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 614503 T2427 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 614504 T2427 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 614505 T2427 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 614507 T2427 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 614508 T2427 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   2> 614509 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data;done=false>>]
[junit4:junit4]   2> 614510 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data
[junit4:junit4]   2> 614511 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index;done=false>>]
[junit4:junit4]   2> 614512 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659042794/slave/collection1/data/index
[junit4:junit4]   2> 614513 T2427 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 614582 T2427 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterStartupWithNoActivity
[junit4:junit4]   2> 614590 T2427 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 614592 T2427 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48872
[junit4:junit4]   2> 614593 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 614593 T2427 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 614593 T2427 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master
[junit4:junit4]   2> 614594 T2427 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/solr.xml
[junit4:junit4]   2> 614594 T2427 oasc.CoreContainer.<init> New CoreContainer 25106702
[junit4:junit4]   2> 614595 T2427 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4]   2> 614595 T2427 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/'
[junit4:junit4]   2> 614595 T2427 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/'
[junit4:junit4]   2> 614654 T2427 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 614654 T2427 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 614654 T2427 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 614655 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 614655 T2427 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 614656 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 614656 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 614656 T2427 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 614657 T2427 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 614657 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 614660 T2427 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 614664 T2473 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1
[junit4:junit4]   2> 614664 T2473 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/'
[junit4:junit4]   2> 614688 T2473 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 614724 T2473 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 614724 T2473 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 614726 T2473 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 614738 T2473 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 614739 T2473 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 614740 T2473 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/
[junit4:junit4]   2> 614740 T2473 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 614740 T2473 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 614741 T2473 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data
[junit4:junit4]   2> 614741 T2473 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index/
[junit4:junit4]   2> 614741 T2473 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 614742 T2473 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index
[junit4:junit4]   2> 614743 T2473 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@adb60b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c7ecd9),segFN=segments_1,generation=1}
[junit4:junit4]   2> 614743 T2473 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 614744 T2473 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 614744 T2473 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 614744 T2473 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 614744 T2473 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 614745 T2473 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 614745 T2473 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 614747 T2473 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 614749 T2473 oass.SolrIndexSearcher.<init> Opening Searcher@33669a main
[junit4:junit4]   2> 614750 T2473 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 614750 T2473 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 614750 T2473 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4]   2> 614751 T2473 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 614751 T2473 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 614751 T2474 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@33669a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 614751 T2427 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 614752 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 614752 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 614757 T2427 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 614759 T2427 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:39416
[junit4:junit4]   2> 614759 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 614760 T2427 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 614760 T2427 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave
[junit4:junit4]   2> 614760 T2427 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/solr.xml
[junit4:junit4]   2> 614761 T2427 oasc.CoreContainer.<init> New CoreContainer 16975804
[junit4:junit4]   2> 614761 T2427 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4]   2> 614761 T2427 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/'
[junit4:junit4]   2> 614762 T2427 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/'
[junit4:junit4]   2> 614810 T2427 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 614811 T2427 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 614811 T2427 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 614811 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 614812 T2427 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 614812 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 614812 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 614812 T2427 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 614813 T2427 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 614813 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 614816 T2427 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 614819 T2485 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1
[junit4:junit4]   2> 614820 T2485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/'
[junit4:junit4]   2> 614848 T2485 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 614887 T2485 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 614887 T2485 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 614889 T2485 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 614900 T2485 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 614901 T2485 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 614901 T2485 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data/
[junit4:junit4]   2> 614902 T2485 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 614902 T2485 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 614903 T2485 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data
[junit4:junit4]   2> 614903 T2485 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data/index/
[junit4:junit4]   2> 614903 T2485 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 614904 T2485 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data/index
[junit4:junit4]   2> 614904 T2485 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e23a4e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fd7bb7),segFN=segments_1,generation=1}
[junit4:junit4]   2> 614905 T2485 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 614905 T2485 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 614906 T2485 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 614906 T2485 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 614906 T2485 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 614906 T2485 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 614906 T2485 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 614908 T2485 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 614910 T2485 oass.SolrIndexSearcher.<init> Opening Searcher@1aafee0 main
[junit4:junit4]   2> 614910 T2485 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 614910 T2485 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 614910 T2485 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 614911 T2485 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 614911 T2486 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1aafee0 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 614911 T2427 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 614912 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 614912 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 614936 T2427 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=16975804
[junit4:junit4]   2> 614937 T2427 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@170e057
[junit4:junit4]   2> 614937 T2427 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 614938 T2427 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 614939 T2427 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 614939 T2427 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 614940 T2427 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 614940 T2427 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   2> 614941 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data;done=false>>]
[junit4:junit4]   2> 614941 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data
[junit4:junit4]   2> 614942 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data/index;done=false>>]
[junit4:junit4]   2> 614943 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043804/slave/collection1/data/index
[junit4:junit4]   2> 614943 T2427 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> ASYNC  NEW_CORE C119 name=collection1 org.apache.solr.core.SolrCore@9145f7
[junit4:junit4]   2> 615003 T2470 C119 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 615004 T2470 C119 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@adb60b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c7ecd9),segFN=segments_1,generation=1}
[junit4:junit4]   2> 615005 T2470 C119 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 615006 T2470 C119 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 3
[junit4:junit4]   2> 615009 T2471 C119 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 615009 T2471 C119 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@adb60b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c7ecd9),segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@adb60b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c7ecd9),segFN=segments_2,generation=2}
[junit4:junit4]   2> 615010 T2471 C119 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 615010 T2471 C119 oass.SolrIndexSearcher.<init> Opening Searcher@7ae2af main
[junit4:junit4]   2> 615011 T2471 C119 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 615011 T2474 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7ae2af main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4:junit4]   2> 615011 T2471 C119 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 2
[junit4:junit4]   2> 615033 T2427 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=25106702
[junit4:junit4]   2> 615034 T2427 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@9145f7
[junit4:junit4]   2> 615035 T2427 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 615035 T2427 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 615036 T2427 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 615036 T2427 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 615037 T2427 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 615037 T2427 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   2> 615038 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index;done=false>>]
[junit4:junit4]   2> 615038 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index
[junit4:junit4]   2> 615039 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data;done=false>>]
[junit4:junit4]   2> 615039 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data
[junit4:junit4]   2> 615040 T2427 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 615092 T2427 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 615094 T2427 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46933
[junit4:junit4]   2> 615094 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 615095 T2427 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 615095 T2427 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master
[junit4:junit4]   2> 615096 T2427 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/solr.xml
[junit4:junit4]   2> 615096 T2427 oasc.CoreContainer.<init> New CoreContainer 22135119
[junit4:junit4]   2> 615096 T2427 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4]   2> 615097 T2427 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/'
[junit4:junit4]   2> 615097 T2427 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/'
[junit4:junit4]   2> 615150 T2427 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 615150 T2427 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 615151 T2427 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 615151 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 615151 T2427 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 615152 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 615152 T2427 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 615152 T2427 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 615153 T2427 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 615153 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 615156 T2427 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 615159 T2498 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1
[junit4:junit4]   2> 615159 T2498 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/'
[junit4:junit4]   2> 615193 T2498 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 615231 T2498 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 615231 T2498 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 615233 T2498 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 615243 T2498 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 615244 T2498 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 615249 T2498 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/
[junit4:junit4]   2> 615250 T2498 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 615250 T2498 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 615250 T2498 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data
[junit4:junit4]   2> 615251 T2498 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index/
[junit4:junit4]   2> 615251 T2498 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 615252 T2498 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index
[junit4:junit4]   2> 615335 T2498 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index,segFN=segments_1,generation=1}
[junit4:junit4]   2> 615336 T2498 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 615337 T2498 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 615337 T2498 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 615338 T2498 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 615338 T2498 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 615338 T2498 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 615339 T2498 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 615340 T2498 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 615343 T2498 oass.SolrIndexSearcher.<init> Opening Searcher@13908a main
[junit4:junit4]   2> 615344 T2498 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 615344 T2498 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 615345 T2498 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4]   2> 615346 T2498 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index,segFN=segments_1,generation=1}
[junit4:junit4]   2> 615346 T2498 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 615347 T2498 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 615347 T2498 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 615347 T2499 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13908a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 615347 T2427 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 615348 T2427 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 615349 T2427 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C120 name=collection1 org.apache.solr.core.SolrCore@ee8bbf
[junit4:junit4]   2> 615360 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4:junit4]   2> 615363 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4:junit4]   2> 615365 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4:junit4]   2> 615367 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4:junit4]   2> 615369 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4:junit4]   2> 615371 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4:junit4]   2> 615373 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
[junit4:junit4]   2> 615375 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4:junit4]   2> 615377 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4:junit4]   2> 615380 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 0
[junit4:junit4]   2> 615382 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4:junit4]   2> 615384 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4:junit4]   2> 615387 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4:junit4]   2> 615389 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4:junit4]   2> 615392 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4:junit4]   2> 615395 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 1
[junit4:junit4]   2> 615397 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4:junit4]   2> 615400 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 1
[junit4:junit4]   2> 615402 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
[junit4:junit4]   2> 615405 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4:junit4]   2> 615408 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4:junit4]   2> 615410 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 0
[junit4:junit4]   2> 615413 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4:junit4]   2> 615416 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 0
[junit4:junit4]   2> 615419 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 1
[junit4:junit4]   2> 615421 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 0
[junit4:junit4]   2> 615424 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 0
[junit4:junit4]   2> 615427 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
[junit4:junit4]   2> 615430 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 1
[junit4:junit4]   2> 615432 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4:junit4]   2> 615435 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 0
[junit4:junit4]   2> 615438 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 0
[junit4:junit4]   2> 615441 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 0
[junit4:junit4]   2> 615444 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 1
[junit4:junit4]   2> 615446 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
[junit4:junit4]   2> 615449 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
[junit4:junit4]   2> 615451 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4:junit4]   2> 615454 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4:junit4]   2> 615457 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 0
[junit4:junit4]   2> 615459 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 0
[junit4:junit4]   2> 615462 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 0
[junit4:junit4]   2> 615465 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 1
[junit4:junit4]   2> 615467 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4:junit4]   2> 615470 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4:junit4]   2> 615472 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 0
[junit4:junit4]   2> 615475 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 0
[junit4:junit4]   2> 615478 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 1
[junit4:junit4]   2> 615480 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4:junit4]   2> 615483 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 1
[junit4:junit4]   2> 615485 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 0
[junit4:junit4]   2> 615488 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 0
[junit4:junit4]   2> 615491 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 1
[junit4:junit4]   2> 615493 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 0
[junit4:junit4]   2> 615496 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4:junit4]   2> 615499 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 1
[junit4:junit4]   2> 615501 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 0
[junit4:junit4]   2> 615504 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 0
[junit4:junit4]   2> 615506 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 0
[junit4:junit4]   2> 615509 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4:junit4]   2> 615512 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 0
[junit4:junit4]   2> 615514 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 0
[junit4:junit4]   2> 615517 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 0
[junit4:junit4]   2> 615520 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 1
[junit4:junit4]   2> 615522 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 0
[junit4:junit4]   2> 615525 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 1
[junit4:junit4]   2> 615527 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 0
[junit4:junit4]   2> 615530 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 0
[junit4:junit4]   2> 615533 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 1
[junit4:junit4]   2> 615535 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 0
[junit4:junit4]   2> 615538 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4:junit4]   2> 615541 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 0
[junit4:junit4]   2> 615544 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 0
[junit4:junit4]   2> 615547 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 1
[junit4:junit4]   2> 615549 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 0
[junit4:junit4]   2> 615552 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 0
[junit4:junit4]   2> 615555 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 0
[junit4:junit4]   2> 615558 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 1
[junit4:junit4]   2> 615560 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 0
[junit4:junit4]   2> 615563 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 0
[junit4:junit4]   2> 615566 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 0
[junit4:junit4]   2> 615569 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 1
[junit4:junit4]   2> 615571 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 0
[junit4:junit4]   2> 615574 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 0
[junit4:junit4]   2> 615577 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 1
[junit4:junit4]   2> 615579 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4:junit4]   2> 615582 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 0
[junit4:junit4]   2> 615585 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 0
[junit4:junit4]   2> 615588 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 0
[junit4:junit4]   2> 615591 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 1
[junit4:junit4]   2> 615593 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 1
[junit4:junit4]   2> 615595 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 1
[junit4:junit4]   2> 615597 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 0
[junit4:junit4]   2> 615599 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4:junit4]   2> 615601 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 0
[junit4:junit4]   2> 615603 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 0
[junit4:junit4]   2> 615605 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 0
[junit4:junit4]   2> 615607 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4:junit4]   2> 615610 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 1
[junit4:junit4]   2> 615612 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 1
[junit4:junit4]   2> 615614 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 0
[junit4:junit4]   2> 615616 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 0
[junit4:junit4]   2> 615618 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 0
[junit4:junit4]   2> 615620 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 0
[junit4:junit4]   2> 615622 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4:junit4]   2> 615624 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4:junit4]   2> 615625 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 0
[junit4:junit4]   2> 615628 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 1
[junit4:junit4]   2> 615639 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 0
[junit4:junit4]   2> 615642 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 1
[junit4:junit4]   2> 615644 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 0
[junit4:junit4]   2> 615647 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 0
[junit4:junit4]   2> 615650 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 1
[junit4:junit4]   2> 615652 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 0
[junit4:junit4]   2> 615655 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 0
[junit4:junit4]   2> 615658 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 1
[junit4:junit4]   2> 615660 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 0
[junit4:junit4]   2> 615663 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 0
[junit4:junit4]   2> 615666 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 0
[junit4:junit4]   2> 615669 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 1
[junit4:junit4]   2> 615671 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4:junit4]   2> 615674 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 1
[junit4:junit4]   2> 615676 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 0
[junit4:junit4]   2> 615679 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 0
[junit4:junit4]   2> 615682 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 1
[junit4:junit4]   2> 615685 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 1
[junit4:junit4]   2> 615687 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 0
[junit4:junit4]   2> 615690 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 0
[junit4:junit4]   2> 615693 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 1
[junit4:junit4]   2> 615696 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128]} 0 1
[junit4:junit4]   2> 615698 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 0
[junit4:junit4]   2> 615701 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4:junit4]   2> 615704 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 1
[junit4:junit4]   2> 615706 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132]} 0 0
[junit4:junit4]   2> 615709 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133]} 0 1
[junit4:junit4]   2> 615711 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 0
[junit4:junit4]   2> 615714 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135]} 0 0
[junit4:junit4]   2> 615717 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 1
[junit4:junit4]   2> 615719 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137]} 0 0
[junit4:junit4]   2> 615722 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138]} 0 0
[junit4:junit4]   2> 615725 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 0
[junit4:junit4]   2> 615727 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 0
[junit4:junit4]   2> 615730 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 0
[junit4:junit4]   2> 615733 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 0
[junit4:junit4]   2> 615736 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 0
[junit4:junit4]   2> 615739 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 0
[junit4:junit4]   2> 615741 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 0
[junit4:junit4]   2> 615744 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 0
[junit4:junit4]   2> 615747 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 0
[junit4:junit4]   2> 615750 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 1
[junit4:junit4]   2> 615752 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149]} 0 0
[junit4:junit4]   2> 615755 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[150]} 0 0
[junit4:junit4]   2> 615758 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[151]} 0 1
[junit4:junit4]   2> 615760 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[152]} 0 0
[junit4:junit4]   2> 615763 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[153]} 0 0
[junit4:junit4]   2> 615766 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[154]} 0 0
[junit4:junit4]   2> 615769 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[155]} 0 0
[junit4:junit4]   2> 615772 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[156]} 0 0
[junit4:junit4]   2> 615774 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[157]} 0 0
[junit4:junit4]   2> 615777 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[158]} 0 0
[junit4:junit4]   2> 615780 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[159]} 0 0
[junit4:junit4]   2> 615783 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[160]} 0 1
[junit4:junit4]   2> 615786 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[161]} 0 1
[junit4:junit4]   2> 615788 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[162]} 0 0
[junit4:junit4]   2> 615791 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[163]} 0 0
[junit4:junit4]   2> 615794 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[164]} 0 0
[junit4:junit4]   2> 615797 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[165]} 0 1
[junit4:junit4]   2> 615799 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[166]} 0 0
[junit4:junit4]   2> 615802 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[167]} 0 0
[junit4:junit4]   2> 615805 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[168]} 0 0
[junit4:junit4]   2> 615808 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[169]} 0 1
[junit4:junit4]   2> 615810 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[170]} 0 0
[junit4:junit4]   2> 615813 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[171]} 0 0
[junit4:junit4]   2> 615816 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[172]} 0 0
[junit4:junit4]   2> 615819 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[173]} 0 0
[junit4:junit4]   2> 615821 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[174]} 0 0
[junit4:junit4]   2> 615824 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[175]} 0 0
[junit4:junit4]   2> 615827 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[176]} 0 0
[junit4:junit4]   2> 615830 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[177]} 0 0
[junit4:junit4]   2> 615833 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[178]} 0 1
[junit4:junit4]   2> 615835 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[179]} 0 0
[junit4:junit4]   2> 615838 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[180]} 0 0
[junit4:junit4]   2> 615841 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[181]} 0 1
[junit4:junit4]   2> 615843 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[182]} 0 0
[junit4:junit4]   2> 615846 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[183]} 0 0
[junit4:junit4]   2> 615849 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[184]} 0 0
[junit4:junit4]   2> 615852 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[185]} 0 0
[junit4:junit4]   2> 615854 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[186]} 0 0
[junit4:junit4]   2> 615857 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[187]} 0 0
[junit4:junit4]   2> 615860 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[188]} 0 1
[junit4:junit4]   2> 615862 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[189]} 0 0
[junit4:junit4]   2> 615865 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[190]} 0 0
[junit4:junit4]   2> 615868 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[191]} 0 0
[junit4:junit4]   2> 615871 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[192]} 0 1
[junit4:junit4]   2> 615873 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[193]} 0 0
[junit4:junit4]   2> 615876 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[194]} 0 0
[junit4:junit4]   2> 615879 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[195]} 0 1
[junit4:junit4]   2> 615881 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[196]} 0 0
[junit4:junit4]   2> 615884 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[197]} 0 0
[junit4:junit4]   2> 615887 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[198]} 0 0
[junit4:junit4]   2> 615890 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[199]} 0 1
[junit4:junit4]   2> 615892 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[200]} 0 0
[junit4:junit4]   2> 615895 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[201]} 0 0
[junit4:junit4]   2> 615898 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[202]} 0 1
[junit4:junit4]   2> 615900 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[203]} 0 0
[junit4:junit4]   2> 615903 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[204]} 0 0
[junit4:junit4]   2> 615906 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[205]} 0 1
[junit4:junit4]   2> 615908 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[206]} 0 0
[junit4:junit4]   2> 615911 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[207]} 0 1
[junit4:junit4]   2> 615913 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[208]} 0 0
[junit4:junit4]   2> 615916 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[209]} 0 0
[junit4:junit4]   2> 615919 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[210]} 0 1
[junit4:junit4]   2> 615921 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[211]} 0 0
[junit4:junit4]   2> 615924 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[212]} 0 0
[junit4:junit4]   2> 615926 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[213]} 0 0
[junit4:junit4]   2> 615929 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[214]} 0 0
[junit4:junit4]   2> 615932 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[215]} 0 1
[junit4:junit4]   2> 615934 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[216]} 0 0
[junit4:junit4]   2> 615937 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[217]} 0 0
[junit4:junit4]   2> 615940 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[218]} 0 1
[junit4:junit4]   2> 615942 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[219]} 0 0
[junit4:junit4]   2> 615945 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[220]} 0 1
[junit4:junit4]   2> 615947 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[221]} 0 0
[junit4:junit4]   2> 615950 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222]} 0 0
[junit4:junit4]   2> 615953 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[223]} 0 1
[junit4:junit4]   2> 615955 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[224]} 0 0
[junit4:junit4]   2> 615958 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[225]} 0 0
[junit4:junit4]   2> 615961 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[226]} 0 1
[junit4:junit4]   2> 615963 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[227]} 0 0
[junit4:junit4]   2> 615966 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[228]} 0 0
[junit4:junit4]   2> 615969 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[229]} 0 0
[junit4:junit4]   2> 615971 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[230]} 0 0
[junit4:junit4]   2> 615974 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[231]} 0 0
[junit4:junit4]   2> 615977 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[232]} 0 0
[junit4:junit4]   2> 615980 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[233]} 0 1
[junit4:junit4]   2> 615982 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[234]} 0 0
[junit4:junit4]   2> 615985 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[235]} 0 0
[junit4:junit4]   2> 615988 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[236]} 0 0
[junit4:junit4]   2> 615991 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[237]} 0 0
[junit4:junit4]   2> 615994 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[238]} 0 1
[junit4:junit4]   2> 615996 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[239]} 0 0
[junit4:junit4]   2> 615999 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[240]} 0 0
[junit4:junit4]   2> 616001 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[241]} 0 0
[junit4:junit4]   2> 616004 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[242]} 0 0
[junit4:junit4]   2> 616007 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[243]} 0 0
[junit4:junit4]   2> 616010 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[244]} 0 0
[junit4:junit4]   2> 616013 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[245]} 0 1
[junit4:junit4]   2> 616015 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[246]} 0 0
[junit4:junit4]   2> 616018 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[247]} 0 1
[junit4:junit4]   2> 616021 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[248]} 0 1
[junit4:junit4]   2> 616023 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[249]} 0 0
[junit4:junit4]   2> 616026 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[250]} 0 1
[junit4:junit4]   2> 616028 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[251]} 0 0
[junit4:junit4]   2> 616031 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[252]} 0 0
[junit4:junit4]   2> 616033 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[253]} 0 0
[junit4:junit4]   2> 616036 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[254]} 0 0
[junit4:junit4]   2> 616039 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[255]} 0 1
[junit4:junit4]   2> 616041 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[256]} 0 0
[junit4:junit4]   2> 616044 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[257]} 0 0
[junit4:junit4]   2> 616046 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[258]} 0 0
[junit4:junit4]   2> 616049 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[259]} 0 0
[junit4:junit4]   2> 616051 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[260]} 0 0
[junit4:junit4]   2> 616054 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[261]} 0 0
[junit4:junit4]   2> 616056 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[262]} 0 0
[junit4:junit4]   2> 616059 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[263]} 0 0
[junit4:junit4]   2> 616061 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[264]} 0 0
[junit4:junit4]   2> 616064 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[265]} 0 0
[junit4:junit4]   2> 616067 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[266]} 0 0
[junit4:junit4]   2> 616069 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[267]} 0 0
[junit4:junit4]   2> 616072 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[268]} 0 0
[junit4:junit4]   2> 616074 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[269]} 0 0
[junit4:junit4]   2> 616077 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[270]} 0 0
[junit4:junit4]   2> 616079 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[271]} 0 0
[junit4:junit4]   2> 616082 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[272]} 0 0
[junit4:junit4]   2> 616085 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[273]} 0 0
[junit4:junit4]   2> 616087 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[274]} 0 0
[junit4:junit4]   2> 616090 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[275]} 0 0
[junit4:junit4]   2> 616092 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[276]} 0 0
[junit4:junit4]   2> 616095 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[277]} 0 0
[junit4:junit4]   2> 616098 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[278]} 0 0
[junit4:junit4]   2> 616101 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[279]} 0 1
[junit4:junit4]   2> 616103 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[280]} 0 0
[junit4:junit4]   2> 616106 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[281]} 0 0
[junit4:junit4]   2> 616109 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[282]} 0 0
[junit4:junit4]   2> 616112 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[283]} 0 0
[junit4:junit4]   2> 616115 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[284]} 0 1
[junit4:junit4]   2> 616117 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[285]} 0 0
[junit4:junit4]   2> 616120 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[286]} 0 0
[junit4:junit4]   2> 616123 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[287]} 0 1
[junit4:junit4]   2> 616125 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[288]} 0 0
[junit4:junit4]   2> 616128 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[289]} 0 0
[junit4:junit4]   2> 616131 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[290]} 0 1
[junit4:junit4]   2> 616133 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[291]} 0 0
[junit4:junit4]   2> 616136 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[292]} 0 0
[junit4:junit4]   2> 616139 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[293]} 0 0
[junit4:junit4]   2> 616142 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[294]} 0 1
[junit4:junit4]   2> 616144 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[295]} 0 0
[junit4:junit4]   2> 616147 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[296]} 0 0
[junit4:junit4]   2> 616150 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[297]} 0 0
[junit4:junit4]   2> 616152 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[298]} 0 0
[junit4:junit4]   2> 616155 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[299]} 0 1
[junit4:junit4]   2> 616157 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[300]} 0 0
[junit4:junit4]   2> 616160 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[301]} 0 0
[junit4:junit4]   2> 616163 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[302]} 0 0
[junit4:junit4]   2> 616166 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[303]} 0 1
[junit4:junit4]   2> 616168 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[304]} 0 0
[junit4:junit4]   2> 616171 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[305]} 0 0
[junit4:junit4]   2> 616174 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[306]} 0 0
[junit4:junit4]   2> 616176 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[307]} 0 0
[junit4:junit4]   2> 616179 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[308]} 0 0
[junit4:junit4]   2> 616182 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[309]} 0 1
[junit4:junit4]   2> 616184 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[310]} 0 0
[junit4:junit4]   2> 616187 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[311]} 0 0
[junit4:junit4]   2> 616190 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[312]} 0 1
[junit4:junit4]   2> 616192 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[313]} 0 0
[junit4:junit4]   2> 616195 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[314]} 0 0
[junit4:junit4]   2> 616198 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[315]} 0 1
[junit4:junit4]   2> 616200 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[316]} 0 0
[junit4:junit4]   2> 616203 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[317]} 0 0
[junit4:junit4]   2> 616206 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[318]} 0 1
[junit4:junit4]   2> 616209 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[319]} 0 1
[junit4:junit4]   2> 616211 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[320]} 0 0
[junit4:junit4]   2> 616214 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[321]} 0 0
[junit4:junit4]   2> 616217 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[322]} 0 1
[junit4:junit4]   2> 616219 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[323]} 0 0
[junit4:junit4]   2> 616222 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[324]} 0 1
[junit4:junit4]   2> 616224 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[325]} 0 0
[junit4:junit4]   2> 616227 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[326]} 0 0
[junit4:junit4]   2> 616230 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[327]} 0 1
[junit4:junit4]   2> 616232 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[328]} 0 0
[junit4:junit4]   2> 616235 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[329]} 0 1
[junit4:junit4]   2> 616237 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[330]} 0 0
[junit4:junit4]   2> 616240 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[331]} 0 0
[junit4:junit4]   2> 616243 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[332]} 0 1
[junit4:junit4]   2> 616245 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[333]} 0 0
[junit4:junit4]   2> 616248 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[334]} 0 0
[junit4:junit4]   2> 616251 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[335]} 0 0
[junit4:junit4]   2> 616254 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[336]} 0 0
[junit4:junit4]   2> 616257 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[337]} 0 0
[junit4:junit4]   2> 616260 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[338]} 0 1
[junit4:junit4]   2> 616263 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[339]} 0 1
[junit4:junit4]   2> 616266 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[340]} 0 1
[junit4:junit4]   2> 616269 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[341]} 0 1
[junit4:junit4]   2> 616272 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[342]} 0 0
[junit4:junit4]   2> 616274 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[343]} 0 0
[junit4:junit4]   2> 616277 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[344]} 0 1
[junit4:junit4]   2> 616279 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[345]} 0 1
[junit4:junit4]   2> 616281 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[346]} 0 0
[junit4:junit4]   2> 616283 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[347]} 0 0
[junit4:junit4]   2> 616285 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[348]} 0 0
[junit4:junit4]   2> 616287 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[349]} 0 0
[junit4:junit4]   2> 616289 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[350]} 0 0
[junit4:junit4]   2> 616291 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[351]} 0 1
[junit4:junit4]   2> 616293 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[352]} 0 1
[junit4:junit4]   2> 616294 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[353]} 0 0
[junit4:junit4]   2> 616297 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[354]} 0 1
[junit4:junit4]   2> 616299 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[355]} 0 1
[junit4:junit4]   2> 616300 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[356]} 0 0
[junit4:junit4]   2> 616302 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[357]} 0 0
[junit4:junit4]   2> 616304 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[358]} 0 0
[junit4:junit4]   2> 616306 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[359]} 0 0
[junit4:junit4]   2> 616308 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[360]} 0 0
[junit4:junit4]   2> 616310 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[361]} 0 0
[junit4:junit4]   2> 616312 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[362]} 0 0
[junit4:junit4]   2> 616314 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[363]} 0 0
[junit4:junit4]   2> 616316 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[364]} 0 0
[junit4:junit4]   2> 616319 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[365]} 0 1
[junit4:junit4]   2> 616321 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[366]} 0 1
[junit4:junit4]   2> 616323 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[367]} 0 1
[junit4:junit4]   2> 616324 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[368]} 0 0
[junit4:junit4]   2> 616326 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[369]} 0 0
[junit4:junit4]   2> 616329 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[370]} 0 1
[junit4:junit4]   2> 616331 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[371]} 0 1
[junit4:junit4]   2> 616333 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[372]} 0 1
[junit4:junit4]   2> 616335 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[373]} 0 0
[junit4:junit4]   2> 616337 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[374]} 0 1
[junit4:junit4]   2> 616339 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[375]} 0 0
[junit4:junit4]   2> 616341 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[376]} 0 1
[junit4:junit4]   2> 616343 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[377]} 0 0
[junit4:junit4]   2> 616345 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[378]} 0 1
[junit4:junit4]   2> 616346 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[379]} 0 0
[junit4:junit4]   2> 616348 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[380]} 0 0
[junit4:junit4]   2> 616350 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[381]} 0 0
[junit4:junit4]   2> 616352 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[382]} 0 0
[junit4:junit4]   2> 616354 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[383]} 0 0
[junit4:junit4]   2> 616356 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[384]} 0 0
[junit4:junit4]   2> 616358 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[385]} 0 0
[junit4:junit4]   2> 616360 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[386]} 0 0
[junit4:junit4]   2> 616362 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[387]} 0 0
[junit4:junit4]   2> 616364 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[388]} 0 0
[junit4:junit4]   2> 616366 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[389]} 0 0
[junit4:junit4]   2> 616368 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[390]} 0 0
[junit4:junit4]   2> 616370 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[391]} 0 0
[junit4:junit4]   2> 616372 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[392]} 0 0
[junit4:junit4]   2> 616374 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[393]} 0 0
[junit4:junit4]   2> 616376 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[394]} 0 0
[junit4:junit4]   2> 616378 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[395]} 0 0
[junit4:junit4]   2> 616380 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[396]} 0 0
[junit4:junit4]   2> 616382 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[397]} 0 0
[junit4:junit4]   2> 616384 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[398]} 0 0
[junit4:junit4]   2> 616386 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[399]} 0 0
[junit4:junit4]   2> 616388 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[400]} 0 0
[junit4:junit4]   2> 616390 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[401]} 0 0
[junit4:junit4]   2> 616392 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[402]} 0 0
[junit4:junit4]   2> 616394 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[403]} 0 0
[junit4:junit4]   2> 616396 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[404]} 0 0
[junit4:junit4]   2> 616399 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[405]} 0 1
[junit4:junit4]   2> 616401 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[406]} 0 0
[junit4:junit4]   2> 616403 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[407]} 0 0
[junit4:junit4]   2> 616405 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[408]} 0 0
[junit4:junit4]   2> 616407 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[409]} 0 0
[junit4:junit4]   2> 616409 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[410]} 0 0
[junit4:junit4]   2> 616411 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[411]} 0 0
[junit4:junit4]   2> 616413 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[412]} 0 0
[junit4:junit4]   2> 616415 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[413]} 0 0
[junit4:junit4]   2> 616417 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[414]} 0 0
[junit4:junit4]   2> 616419 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[415]} 0 0
[junit4:junit4]   2> 616421 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[416]} 0 0
[junit4:junit4]   2> 616423 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[417]} 0 0
[junit4:junit4]   2> 616425 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[418]} 0 0
[junit4:junit4]   2> 616427 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[419]} 0 0
[junit4:junit4]   2> 616429 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[420]} 0 0
[junit4:junit4]   2> 616431 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[421]} 0 0
[junit4:junit4]   2> 616433 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[422]} 0 0
[junit4:junit4]   2> 616435 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[423]} 0 0
[junit4:junit4]   2> 616437 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[424]} 0 0
[junit4:junit4]   2> 616439 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[425]} 0 0
[junit4:junit4]   2> 616441 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[426]} 0 1
[junit4:junit4]   2> 616443 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[427]} 0 1
[junit4:junit4]   2> 616444 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[428]} 0 0
[junit4:junit4]   2> 616446 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[429]} 0 0
[junit4:junit4]   2> 616448 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[430]} 0 0
[junit4:junit4]   2> 616450 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[431]} 0 0
[junit4:junit4]   2> 616452 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[432]} 0 0
[junit4:junit4]   2> 616454 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[433]} 0 0
[junit4:junit4]   2> 616456 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[434]} 0 0
[junit4:junit4]   2> 616458 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[435]} 0 0
[junit4:junit4]   2> 616460 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[436]} 0 0
[junit4:junit4]   2> 616462 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[437]} 0 0
[junit4:junit4]   2> 616464 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[438]} 0 0
[junit4:junit4]   2> 616466 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[439]} 0 0
[junit4:junit4]   2> 616468 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[440]} 0 0
[junit4:junit4]   2> 616470 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[441]} 0 0
[junit4:junit4]   2> 616472 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[442]} 0 0
[junit4:junit4]   2> 616474 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[443]} 0 0
[junit4:junit4]   2> 616476 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[444]} 0 0
[junit4:junit4]   2> 616478 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[445]} 0 0
[junit4:junit4]   2> 616480 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[446]} 0 1
[junit4:junit4]   2> 616482 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[447]} 0 1
[junit4:junit4]   2> 616483 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[448]} 0 0
[junit4:junit4]   2> 616486 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[449]} 0 1
[junit4:junit4]   2> 616487 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[450]} 0 0
[junit4:junit4]   2> 616489 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[451]} 0 0
[junit4:junit4]   2> 616491 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[452]} 0 0
[junit4:junit4]   2> 616493 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[453]} 0 0
[junit4:junit4]   2> 616495 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[454]} 0 0
[junit4:junit4]   2> 616497 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[455]} 0 0
[junit4:junit4]   2> 616500 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[456]} 0 0
[junit4:junit4]   2> 616502 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[457]} 0 0
[junit4:junit4]   2> 616504 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[458]} 0 0
[junit4:junit4]   2> 616506 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[459]} 0 0
[junit4:junit4]   2> 616508 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[460]} 0 0
[junit4:junit4]   2> 616510 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[461]} 0 0
[junit4:junit4]   2> 616512 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[462]} 0 0
[junit4:junit4]   2> 616514 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[463]} 0 1
[junit4:junit4]   2> 616515 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[464]} 0 0
[junit4:junit4]   2> 616517 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[465]} 0 0
[junit4:junit4]   2> 616519 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[466]} 0 0
[junit4:junit4]   2> 616521 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[467]} 0 0
[junit4:junit4]   2> 616523 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[468]} 0 0
[junit4:junit4]   2> 616525 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[469]} 0 0
[junit4:junit4]   2> 616526 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[470]} 0 0
[junit4:junit4]   2> 616528 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[471]} 0 0
[junit4:junit4]   2> 616530 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[472]} 0 0
[junit4:junit4]   2> 616532 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[473]} 0 0
[junit4:junit4]   2> 616534 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[474]} 0 0
[junit4:junit4]   2> 616536 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[475]} 0 1
[junit4:junit4]   2> 616537 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[476]} 0 0
[junit4:junit4]   2> 616539 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[477]} 0 0
[junit4:junit4]   2> 616541 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[478]} 0 0
[junit4:junit4]   2> 616543 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[479]} 0 0
[junit4:junit4]   2> 616545 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[480]} 0 0
[junit4:junit4]   2> 616547 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[481]} 0 0
[junit4:junit4]   2> 616549 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[482]} 0 0
[junit4:junit4]   2> 616551 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[483]} 0 0
[junit4:junit4]   2> 616552 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[484]} 0 0
[junit4:junit4]   2> 616554 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[485]} 0 0
[junit4:junit4]   2> 616556 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[486]} 0 0
[junit4:junit4]   2> 616558 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[487]} 0 0
[junit4:junit4]   2> 616560 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[488]} 0 0
[junit4:junit4]   2> 616562 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[489]} 0 0
[junit4:junit4]   2> 616564 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[490]} 0 0
[junit4:junit4]   2> 616566 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[491]} 0 0
[junit4:junit4]   2> 616568 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[492]} 0 1
[junit4:junit4]   2> 616569 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[493]} 0 0
[junit4:junit4]   2> 616571 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[494]} 0 0
[junit4:junit4]   2> 616573 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[495]} 0 0
[junit4:junit4]   2> 616575 T2497 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[496]} 0 0
[junit4:junit4]   2> 616577 T2495 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[497]} 0 0
[junit4:junit4]   2> 616579 T2496 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[498]} 0 0
[junit4:junit4]   2> 616581 T2494 C120 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 616664 T2494 C120 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index,segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659043632/master/collection1/data/index,segFN=segments_2,generation=2}
[junit4:junit4]   2> 616664 T2494 C120 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 616667 T2494 C120 oass.SolrIndexSearcher.<init> Opening Searcher@6d9748 main
[junit4:junit4]   2> 616667 T2494 C120 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 616667 T2499 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6d9748 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C499)}
[junit4:junit4]   2> 616668 T2494 C120 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 87
[junit4:junit4]   2> 616689 T2427 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=22135119
[junit4:junit4]   2> 616690 T2427 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@ee8bbf
[junit4:junit4]   2> 616690 T2427 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimize

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

ection1/data/index;done=false>>]
[junit4:junit4]   2> 679239 T3039 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105622/slave/collection1/data/index/
[junit4:junit4]   2> 679239 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105409/master/collection1/data/index
[junit4:junit4]   2> 679240 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105409/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105409/master/collection1/data;done=false>>]
[junit4:junit4]   2> 679240 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105409/master/collection1/data
[junit4:junit4]   2> 679240 T3039 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 679241 T2427 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 679241 T3039 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 679241 T3039 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 679241 T3039 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 679241 T3039 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 679242 T3039 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 679244 T3039 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 679246 T3039 oass.SolrIndexSearcher.<init> Opening Searcher@e339d6 main
[junit4:junit4]   2> 679246 T3039 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 679247 T3039 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 679247 T3039 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 679247 T3039 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 679247 T3040 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e339d6 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 679247 T3039 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 679248 T3039 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4]   2> 679249 T3039 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e0cce4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1106220),segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e0cce4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1106220),segFN=segments_2,generation=2}
[junit4:junit4]   2> 679249 T3039 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 679249 T3039 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 679250 T3039 oass.SolrIndexSearcher.<init> Opening Searcher@7b783a main
[junit4:junit4]   2> 679250 T3039 oasc.CoreContainer.registerCore replacing core: collection1
[junit4:junit4]   2> 679250 T3040 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b783a main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 679250 T3039 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1e330b
[junit4:junit4]   2> 679251 T3039 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 679251 T3039 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 679313 T2427 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=27987779
[junit4:junit4]   2> 679314 T2427 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@125a59
[junit4:junit4]   2> 679314 T2427 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 679315 T2427 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 679315 T2427 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 679315 T2427 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 679316 T2427 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 679316 T2427 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   2> 679317 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105622/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105622/slave/collection1/data/index;done=false>>]
[junit4:junit4]   2> 679317 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105622/slave/collection1/data/index
[junit4:junit4]   2> 679318 T2427 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105622/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105622/slave/collection1/data;done=false>>]
[junit4:junit4]   2> 679318 T2427 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1368659105622/slave/collection1/data
[junit4:junit4]   2> 679319 T2427 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 679374 T2427 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {newname=PostingsFormat(name=TestBloomFilteredLucene41Postings), id=PostingsFormat(name=TestBloomFilteredLucene41Postings), name=PostingsFormat(name=TestBloomFilteredLucene41Postings)}, docValues:{}, sim=DefaultSimilarity, locale=ro, timezone=Asia/Aden
[junit4:junit4]   2> NOTE: Linux 3.2.0-41-generic i386/Oracle Corporation 1.7.0_21 (32-bit)/cpus=8,threads=1,free=50892240,total=135409664
[junit4:junit4]   2> NOTE: All tests run in this JVM: [IndexSchemaTest, TestFuzzyAnalyzedSuggestions, StatelessScriptUpdateProcessorFactoryTest, TestSurroundQueryParser, SimpleFacetsTest, TestSort, SyncSliceTest, TestConfig, ResponseLogComponentTest, PeerSyncTest, TestCoreContainer, TestSchemaResource, SynonymTokenizerTest, CSVRequestHandlerTest, AliasIntegrationTest, MinimalSchemaTest, BasicDistributedZkTest, ZkControllerTest, TestPerFieldSimilarity, TestStressVersions, DocumentBuilderTest, DocValuesTest, TestCharFilters, TestRandomDVFaceting, LukeRequestHandlerTest, TestPluginEnable, ReturnFieldsTest, RegexBoostProcessorTest, QueryResultKeyTest, TestBadConfig, TestSolrQueryParser, RecoveryZkTest, TestManagedSchema, SearchHandlerTest, TestManagedSchemaFieldResource, TestDocumentBuilder, LoggingHandlerTest, FieldMutatingUpdateProcessorTest, ShardRoutingTest, PreAnalyzedUpdateProcessorTest, TestSolrIndexConfig, DistributedQueryElevationComponentTest, TestCloudManagedSchema, TestSolrCoreProperties, DistributedTermsComponentTest, TestWriterPerf, OverseerTest, DirectUpdateHandlerOptimizeTest, JsonLoaderTest, TestReversedWildcardFilterFactory, RAMDirectoryFactoryTest, FastVectorHighlighterTest, TestFunctionQuery, ExternalFileFieldSortTest, BasicFunctionalityTest, MoreLikeThisHandlerTest, PrimitiveFieldTypeTest, TestLRUCache, TestReplicationHandler]
[junit4:junit4] Completed on J0 in 65.85s, 13 tests, 1 failure <<< FAILURES!

[...truncated 505 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:377: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:357: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:437: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1240: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:884: There were test failures: 295 suites, 1231 tests, 1 failure, 13 ignored (7 assumptions)

Total time: 40 minutes 10 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_21 -client -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure

[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0-ea-b89) - Build # 5674 - Still Failing!

[Policeman Jenkins Server <je...@thetaphi.de>]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/5674/
Java: 64bit/jdk1.8.0-ea-b89 -XX:-UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.update.processor.RegexBoostProcessorTest

Error Message:
Clean up static fields (in @AfterClass?), your test seems to hang on to approximately 12,639,224 bytes (threshold is 10,485,760). Field reference sizes (counted individually):   - 13,658,200 bytes, protected static org.apache.solr.servlet.SolrRequestParsers org.apache.solr.update.processor.RegexBoostProcessorTest._parser   - 6,360 bytes, private static org.apache.solr.update.processor.RegexpBoostProcessorFactory org.apache.solr.update.processor.RegexBoostProcessorTest.factory   - 5,392 bytes, private static org.apache.solr.update.processor.RegexpBoostProcessor org.apache.solr.update.processor.RegexBoostProcessorTest.reProcessor   - 1,144 bytes, protected static org.apache.solr.common.params.ModifiableSolrParams org.apache.solr.update.processor.RegexBoostProcessorTest.parameters   - 328 bytes, public static org.junit.rules.TestRule org.apache.solr.SolrTestCaseJ4.solrClassRules   - 232 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.testSolrHome   - 144 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp   - 80 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName

Stack Trace:
junit.framework.AssertionFailedError: Clean up static fields (in @AfterClass?), your test seems to hang on to approximately 12,639,224 bytes (threshold is 10,485,760). Field reference sizes (counted individually):
  - 13,658,200 bytes, protected static org.apache.solr.servlet.SolrRequestParsers org.apache.solr.update.processor.RegexBoostProcessorTest._parser
  - 6,360 bytes, private static org.apache.solr.update.processor.RegexpBoostProcessorFactory org.apache.solr.update.processor.RegexBoostProcessorTest.factory
  - 5,392 bytes, private static org.apache.solr.update.processor.RegexpBoostProcessor org.apache.solr.update.processor.RegexBoostProcessorTest.reProcessor
  - 1,144 bytes, protected static org.apache.solr.common.params.ModifiableSolrParams org.apache.solr.update.processor.RegexBoostProcessorTest.parameters
  - 328 bytes, public static org.junit.rules.TestRule org.apache.solr.SolrTestCaseJ4.solrClassRules
  - 232 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.testSolrHome
  - 144 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp
  - 80 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName
	at __randomizedtesting.SeedInfo.seed([A65382B085D8CF01]:0)
	at com.carrotsearch.randomizedtesting.rules.StaticFieldsInvariantRule$1.afterAlways(StaticFieldsInvariantRule.java:127)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	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 9284 lines...]
[junit4:junit4] Suite: org.apache.solr.update.processor.RegexBoostProcessorTest
[junit4:junit4]   2> 207630 T876 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242
[junit4:junit4]   2> 207630 T876 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 207635 T876 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 207635 T876 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 207664 T876 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 207699 T876 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 207700 T876 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4:junit4]   2> 207707 T876 oass.IndexSchema.readSchema [null] Schema name=test
[junit4:junit4]   2> 207976 T876 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 207978 T876 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 207979 T876 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 207990 T876 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 207993 T876 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 207994 T876 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 207995 T876 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 207995 T876 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 208002 T876 oasc.CoreContainer.<init> New CoreContainer 1419052132
[junit4:junit4]   2> 208030 T876 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 208030 T876 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242/
[junit4:junit4]   2> 208030 T876 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7698193c
[junit4:junit4]   2> 208031 T876 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 208031 T876 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242
[junit4:junit4]   2> 208031 T876 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242/index/
[junit4:junit4]   2> 208031 T876 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 208032 T876 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242/index
[junit4:junit4]   2> 208033 T876 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@7cd56168 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16618297)),segFN=segments_1,generation=1}
[junit4:junit4]   2> 208033 T876 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 208033 T876 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 208035 T876 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 208035 T876 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 208035 T876 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 208035 T876 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 208036 T876 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 208036 T876 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 208036 T876 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 208037 T876 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 208037 T876 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 208037 T876 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 208038 T876 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 208038 T876 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 208038 T876 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 208038 T876 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 208039 T876 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 208039 T876 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 208039 T876 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 208040 T876 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 208040 T876 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 208040 T876 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 208040 T876 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 208041 T876 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 208041 T876 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 208041 T876 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 208042 T876 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 208042 T876 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 208044 T876 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 208045 T876 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 208046 T876 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [STOPWITHBOM.TXT, JASUGGEST.TXT, KEEP-1.TXT, BAD-SCHEMA-DOCVALUES-NOT-REQUIRED-NO-DEFAULT.XML, SCHEMA-EFF.XML, BAD-SCHEMA-BOGUS-ANALYSIS-PARAMETERS.XML, BAD-SCHEMA-MULTIPLE-ASTERISK-COPYFIELD-SOURCE-SHOULD-FAIL-TEST.XML, BAD-SOLRCONFIG-SCHEMA-MUTABLE-BUT-NOT-MANAGED.XML, THROW.ERROR.ON.ADD.UPDATEPROCESSOR.JS, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, SCHEMA-CHARFILTERS.XML, BAD-SCHEMA-SWEETSPOT-PARTIAL-BASELINE.XML, SOLRCONFIG-TERMINDEX.XML, BAD-SCHEMA-DUP-DYNAMICFIELD.XML, BAD-SCHEMA-CODEC-GLOBAL-VS-FT-MISMATCH.XML, SOLRCONFIG-HIGHLIGHT.XML, SOLRCONFIG_PERF.XML, SOLRCONFIG-XINCLUDE.XML, SOLRCONFIG-COMPONENTS-NAME.XML, SCHEMA-SPATIAL.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, BAD-SCHEMA-CURRENCY-MULTIVALUED.XML, BAD-SCHEMA-BOGUS-FIELD-PARAMETERS.XML, SOLRCONFIG-DELPOLICY1.XML, SCHEMA-DFR.XML, SOLRCONFIG-NOCACHE.XML, SCHEMA-SNIPPET-TYPE.XML, SCHEMA-LUCENEMATCHVERSION.XML, BAD-SCHEMA-UNSUPPORTED-DOCVALUES.XML, SOLRCONFIG-SLAVE.XML, SOLRCONFIG-TLOG-MUTABLE-MANAGED-SCHEMA.XML, STEMDICT.TXT, SCHEMA-DOCVALUES.XML, SOLRCONFIG-NATIVELOCK.XML, BAD-SCHEMA-NOT-INDEXED-BUT-TF.XML, SCHEMA-SYNONYM-TOKENIZER.XML, BAD-SOLRCONFIG-MISSING-SCRIPTFILE.XML, SCHEMA-NUMERIC.XML, BAD-SCHEMA-SWEETSPOT-PARTIAL-NORMS.XML, BAD-SCHEMA-CURRENCY-FT-OER-NORATES.XML, SCHEMA-TFIDF.XML, SOLRCONFIG-MANAGED-SCHEMA.XML, TRIVIAL.UPDATEPROCESSOR1.JS, SCHEMA-ID-AND-VERSION-FIELDS-ONLY.XML, SOLRCONFIG-RESPONSE-LOG-COMPONENT.XML, FUZZYSUGGEST.TXT, KEEP-2.TXT, SOLRCONFIG-LAZYWRITER.XML, STOPTYPES-2.TXT, SOLRCONFIG-MASTER2.XML, SCHEMA-LMDIRICHLET.XML, SOLRCONFIG-BASIC.XML, BAD-SOLRCONFIG-UNEXPECTED-SCHEMA-ATTRIBUTE.XML, BAD-SCHEMA-MISPLACED-ASTERISK-COPYFIELD-SOURCE-SHOULD-FAIL-TEST.XML, SOLRCONFIG-ENABLEPLUGIN.XML, SCHEMA_CODEC.XML, BAD-SCHEMA-SWEETSPOT-PARTIAL-HYPERBOLIC.XML, HUNSPELL-TEST.AFF, XSLT, MISSLEADING.EXTENSION.UPDATEPROCESSOR.JS.TXT, SOLRCONFIG-SLAVE1.XML, SCHEMA-MINIMAL.XML, SOLRCONFIG-SCRIPT-UPDATEPROCESSOR.XML, OPEN-EXCHANGE-RATES.JSON, BAD-SCHEMA-CURRENCY-FT-BOGUS-CODE-IN-XML.XML, MISSING.FUNCTIONS.UPDATEPROCESSOR.JS, SOLRCONFIG-DELPOLICY2.XML, SCHEMA-REST-LUCENE-MATCH-VERSION.XML, SOLRCONFIG-FUNCTIONQUERY.XML, BAD-SCHEMA-CURRENCY-FT-MULTIVALUED.XML, SOLRCONFIG-QUERYSENDER.XML, STOP-2.TXT, SOLRCONFIG-ELEVATE.XML, COMPOUNDDICTIONARY.TXT, SOLRCONFIG-PHRASESUGGEST.XML, SCHEMA-LMJELINEKMERCER.XML, SCHEMA-FOLDING.XML, STOPWORDS.TXT, SOLRCONFIG_CODEC.XML, WDFTYPES.TXT, SCHEMA-CLASS-NAME-SHORTENING-ON-SERIALIZATION.XML, SCHEMA-BEHAVIOR.XML, SCHEMA-REVERSED.XML, SOLRCONFIG-PROPINJECT.XML, SOLRCONFIG-PROPINJECT-INDEXDEFAULT.XML, SCHEMA-REQUIRED-FIELDS.XML, SOLRCONFIG-INDEXCONFIG.XML, STOP-1.TXT, ELEVATE.XML, STOPWORDSWRONGENCODING.TXT, ADDFIELDS.UPDATEPROCESSOR.JS, BAD-SCHEMA-MULTIPLE-ASTERISK-COPYFIELD-DEST-SHOULD-FAIL-TEST.XML, SOLRCONFIG-MUTABLE-MANAGED-SCHEMA.XML, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, SCHEMA-DOCVALUESFACETING.XML, SCHEMA-XINCLUDE.XML, BAD-SCHEMA-DUP-FIELD.XML, SOLRCONFIG-MASTER.XML, SCHEMA-COLLATE.XML, SOLRCONFIG-MINIMAL.XML, HYPHENATION.DTD, STOP-SNOWBALL.TXT, FRENCHARTICLES.TXT, PHRASESUGGEST.TXT, REGEX-BOOST-PROCESSOR-TEST.TXT, SOLRCONFIG-ALTDIRECTORY.XML, SOLRCONFIG-SOLCOREPROPERTIES.XML, SCHEMA-COPYFIELD-TEST.XML, SCHEMA-SPELLCHECKER.XML, BAD-CURRENCY.XML, BAD-MP-SOLRCONFIG.XML, PROTWORDS.TXT, DA_COMPOUNDDICTIONARY.TXT, BAD-SCHEMA-UNIQUEKEY-IS-COPYFIELD-DEST.XML, SCHEMA-NOT-REQUIRED-UNIQUE-KEY.XML, SOLRCONFIG-SIMPLELOCK.XML, SOLRCONFIG-SPELLCHECKCOMPONENT.XML, SOLRCONFIG-REQHANDLER.INCL, SOLRCONFIG-TLOG.XML, SCHEMA12.XML, SCHEMA-IB.XML, MAPPING-ISOLATIN1ACCENT.TXT, SCHEMA-SWEETSPOT.XML, SOLRCONFIG-SNIPPET-PROCESSOR.XML, BAD-SCHEMA-SWEETSPOT-BOTH-TF.XML, SCHEMA-SIM.XML, BAD-SCHEMA-CURRENCY-DYNAMIC-MULTIVALUED.XML, BAD-SOLRCONFIG-MANAGED-SCHEMA-NAMED-SCHEMA.XML.XML, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML, SCHEMA-STOP-KEEP.XML, STOPTYPES-1.TXT, SOLRCONFIG-CACHING.XML, TRIVIAL.UPDATEPROCESSOR0.JS, SCHEMA-BM25.XML, SOLRCONFIG-MASTER1.XML, SCHEMA-ONE-FIELD-NO-DYNAMIC-FIELD.XML, SCHEMA-REPLICATION2.XML, SOLRCONFIG-REPEATER.XML, SCHEMASURROUND.XML, BAD-SCHEMA-NON-GLOB-COPYFIELD-SOURCE-MATCHING-NOTHING-SHOULD-FAIL-TEST.XML, SOLRCONFIG-POSTINGSHIGHLIGHT.XML, SCHEMA-BINARYFIELD.XML, SOLRCONFIG-TRANSFORMERS.XML, SOLRCONFIG-MASTER3.XML, SOLRCONFIG-TLOG-MANAGED-SCHEMA.XML, BAD-SCHEMA-EXTERNAL-FILEFIELD.XML, BAD-SOLRCONFIG-INVALID-SCRIPTFILE.XML, SYNONYMS.TXT, SCHEMA-SNIPPET-FIELD.XML, SCHEMA-PHRASESUGGEST.XML, DA_UTF8.XML, BAD_SOLRCONFIG.XML, BAD-SCHEMA-MISPLACED-ASTERISK-COPYFIELD-DEST-SHOULD-FAIL-TEST.XML, SOLRCONFIG-MASTER1-KEEPONEBACKUP.XML, SCHEMA-DOCVALUESMULTI.XML, BAD-SCHEMA-NOT-INDEXED-BUT-NORMS.XML, BAD-SCHEMA-DUP-FIELDTYPE.XML, SOLRCONFIG-MERGEPOLICY.XML, SCHEMA15.XML, SCHEMA-TINY.XML, OLD_SYNONYMS.TXT, SCHEMA-TRIE.XML, SCHEMA11.XML, SCHEMA-REPLICATION1.XML, CURRENCY.XML, SCHEMA-POSTINGSHIGHLIGHT.XML, SOLRCONFIG-SPELLCHECKER.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, SOLRCONFIG-WARMER.XML, BAD-SCHEMA-NOT-INDEXED-BUT-POS.XML, HUNSPELL-TEST.DIC, SOLRCONFIG-SOLR-749.XML, SCHEMA-REST.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, BAD-SCHEMA-UNIQUEKEY-MULTIVALUED.XML, BAD-SCHEMA-UNIQUEKEY-USES-DEFAULT.XML, BAD-SCHEMA-NONTEXT-ANALYZER.XML, BAD-SCHEMA-CURRENCY-FT-BOGUS-DEFAULT-CODE.XML, CONDITIONAL.UPDATEPROCESSOR.JS]
[junit4:junit4]   2> 208048 T876 oass.SolrIndexSearcher.<init> Opening Searcher@68319a01 main
[junit4:junit4]   2> 208048 T876 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 208049 T876 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 208049 T876 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 208115 T876 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 208135 T877 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@68319a01 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 208138 T876 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 208140 T876 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 208145 T876 oas.SolrTestCaseJ4.setUp ###Starting testDeboostOld
[junit4:junit4]   2> 208146 T876 oas.SolrTestCaseJ4.tearDown ###Ending testDeboostOld
[junit4:junit4]   2> 208149 T876 oas.SolrTestCaseJ4.setUp ###Starting testTwoRules
[junit4:junit4]   2> 208149 T876 oas.SolrTestCaseJ4.tearDown ###Ending testTwoRules
[junit4:junit4]   2> 208153 T876 oas.SolrTestCaseJ4.setUp ###Starting testBoostGood
[junit4:junit4]   2> 208153 T876 oas.SolrTestCaseJ4.tearDown ###Ending testBoostGood
[junit4:junit4]   2> 208157 T876 oas.SolrTestCaseJ4.setUp ###Starting testNoBoost
[junit4:junit4]   2> 208157 T876 oas.SolrTestCaseJ4.tearDown ###Ending testNoBoost
[junit4:junit4]   2> 208158 T876 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 208158 T876 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1419052132
[junit4:junit4]   2> 208158 T876 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@5676d367
[junit4:junit4]   2> 208165 T876 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 208166 T876 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 208166 T876 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 208166 T876 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 208166 T876 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 208166 T876 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   2> 208166 T876 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242;done=false>>]
[junit4:junit4]   2> 208167 T876 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242
[junit4:junit4]   2> 208167 T876 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242/index;done=false>>]
[junit4:junit4]   2> 208167 T876 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-RegexBoostProcessorTest-1368664200242/index
[junit4:junit4]   2> NOTE: test params are: codec=Asserting, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=en_NZ, timezone=Australia/Darwin
[junit4:junit4]   2> NOTE: Linux 3.2.0-41-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=141868504,total=311296000
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestExtendedDismaxParser, MBeansHandlerTest, LegacyHTMLStripCharFilterTest, TestGroupingSearch, TestConfig, TestStressRecovery, TestReplicationHandler, TestDefaultSearchFieldResource, MoreLikeThisHandlerTest, PeerSyncTest, TestJoin, TestRemoteStreaming, LoggingHandlerTest, TestSolrDeletionPolicy2, TestSort, RegexBoostProcessorTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RegexBoostProcessorTest -Dtests.seed=A65382B085D8CF01 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en_NZ -Dtests.timezone=Australia/Darwin -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   0.00s J0 | RegexBoostProcessorTest (suite) <<<
[junit4:junit4]    > Throwable #1: junit.framework.AssertionFailedError: Clean up static fields (in @AfterClass?), your test seems to hang on to approximately 12,639,224 bytes (threshold is 10,485,760). Field reference sizes (counted individually):
[junit4:junit4]    >   - 13,658,200 bytes, protected static org.apache.solr.servlet.SolrRequestParsers org.apache.solr.update.processor.RegexBoostProcessorTest._parser
[junit4:junit4]    >   - 6,360 bytes, private static org.apache.solr.update.processor.RegexpBoostProcessorFactory org.apache.solr.update.processor.RegexBoostProcessorTest.factory
[junit4:junit4]    >   - 5,392 bytes, private static org.apache.solr.update.processor.RegexpBoostProcessor org.apache.solr.update.processor.RegexBoostProcessorTest.reProcessor
[junit4:junit4]    >   - 1,144 bytes, protected static org.apache.solr.common.params.ModifiableSolrParams org.apache.solr.update.processor.RegexBoostProcessorTest.parameters
[junit4:junit4]    >   - 328 bytes, public static org.junit.rules.TestRule org.apache.solr.SolrTestCaseJ4.solrClassRules
[junit4:junit4]    >   - 232 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.testSolrHome
[junit4:junit4]    >   - 144 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp
[junit4:junit4]    >   - 80 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([A65382B085D8CF01]:0)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:724)
[junit4:junit4] Completed on J0 in 1.20s, 4 tests, 1 failure <<< FAILURES!

[...truncated 761 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:377: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:357: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:437: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1240: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:884: There were test failures: 295 suites, 1231 tests, 1 suite-level error, 16 ignored (7 assumptions)

Total time: 39 minutes 44 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b89 -XX:-UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure