lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-ea-b51) - Build # 1362 - Failure!
Date Wed, 26 Sep 2012 03:50:21 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/1362/
Java: 32bit/jdk1.8.0-ea-b51 -client -XX:+UseG1GC

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler

Error Message:
ERROR: SolrIndexSearcher opens=61 closes=60

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=61 closes=60
	at __randomizedtesting.SeedInfo.seed([AA3D9D6756B8420D]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:241)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:96)
	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:474)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
	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:45)
	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 7724 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4:junit4]   2> 4 T75 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6 T75 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:53312
[junit4:junit4]   2> 7 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master
[junit4:junit4]   2> 8 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/'
[junit4:junit4]   2> 21 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 22 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 22 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master
[junit4:junit4]   2> 22 T75 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/solr.xml
[junit4:junit4]   2> 23 T75 oasc.CoreContainer.<init> New CoreContainer 4894214
[junit4:junit4]   2> 23 T75 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 23 T75 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/'
[junit4:junit4]   2> 24 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/'
[junit4:junit4]   2> 38 T75 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 51 T75 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1
[junit4:junit4]   2> 51 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/'
[junit4:junit4]   2> 81 T75 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 116 T75 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 116 T75 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 119 T75 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 132 T75 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 133 T75 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/
[junit4:junit4]   2> 133 T75 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 139 T75 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index/
[junit4:junit4]   2> 140 T75 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 140 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index forceNew:false
[junit4:junit4]   2> 265 T75 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 266 T75 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 267 T75 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 267 T75 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 267 T75 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 268 T75 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 268 T75 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 268 T75 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 269 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 272 T75 oass.SolrIndexSearcher.<init> Opening Searcher@51e65a main
[junit4:junit4]   2> 272 T75 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 273 T75 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 273 T75 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 273 T75 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 274 T75 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 274 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 274 T75 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 275 T75 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 275 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 275 T75 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 276 T75 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 276 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 280 T75 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4]   2> 280 T75 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 281 T85 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@51e65a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 280 T75 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 281 T75 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 281 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 282 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 290 T75 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 292 T75 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:43941
[junit4:junit4]   2> 292 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 292 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave
[junit4:junit4]   2> 293 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/'
[junit4:junit4]   2> 306 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 306 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 307 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave
[junit4:junit4]   2> 307 T75 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/solr.xml
[junit4:junit4]   2> 307 T75 oasc.CoreContainer.<init> New CoreContainer 22776981
[junit4:junit4]   2> 307 T75 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 308 T75 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/'
[junit4:junit4]   2> 308 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/'
[junit4:junit4]   2> 322 T75 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 347 T75 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1
[junit4:junit4]   2> 347 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/'
[junit4:junit4]   2> 370 T75 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 402 T75 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 402 T75 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 405 T75 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 417 T75 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 418 T75 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/
[junit4:junit4]   2> 418 T75 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 419 T75 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/
[junit4:junit4]   2> 419 T75 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 419 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index forceNew:false
[junit4:junit4]   2> 422 T75 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 422 T75 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 423 T75 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 424 T75 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 424 T75 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 424 T75 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 425 T75 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 426 T75 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 427 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 429 T75 oass.SolrIndexSearcher.<init> Opening Searcher@1181113 main
[junit4:junit4]   2> 430 T75 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 431 T75 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 431 T75 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 431 T75 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 432 T75 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 432 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 432 T75 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 433 T75 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 433 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 433 T75 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 434 T75 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 434 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 437 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 444 T75 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 444 T95 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1181113 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 444 T75 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 445 T75 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 445 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 445 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 453 T75 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4:junit4]   2> 453 T75 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=22776981
[junit4:junit4]   2> 454 T75 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@bccd5e
[junit4:junit4]   2> 454 T75 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 455 T75 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> 455 T75 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 455 T75 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 456 T75 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> ASYNC  NEW_CORE C5 name=collection1 org.apache.solr.core.SolrCore@14411bc
[junit4:junit4]   2> 512 T78 C5 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 514 T78 C5 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 514 T78 C5 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 515 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 4
[junit4:junit4]   2> 519 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4:junit4]   2> 522 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4:junit4]   2> 525 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4:junit4]   2> 527 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4:junit4]   2> 530 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4:junit4]   2> 533 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4:junit4]   2> 536 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
[junit4:junit4]   2> 539 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 1
[junit4:junit4]   2> 542 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 1
[junit4:junit4]   2> 544 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 0
[junit4:junit4]   2> 548 T78 C5 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 562 T78 C5 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.fdt, _0.fnm, _0.si, _0_nrm.cfs, _0_nrm.cfe, _0.fdx, _0_SimpleText_0.pst, segments_2]
[junit4:junit4]   2> 563 T78 C5 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 566 T78 C5 oass.SolrIndexSearcher.<init> Opening Searcher@831414 main
[junit4:junit4]   2> 566 T85 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@831414 main{StandardDirectoryReader(segments_2:4 _0(4.1):C10)}
[junit4:junit4]   2> 566 T78 C5 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 567 T78 C5 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 19
[junit4:junit4]   2> 569 T78 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=10 status=0 QTime=0 
[junit4:junit4]   2> 672 T75 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=4894214
[junit4:junit4]   2> 673 T75 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@14411bc
[junit4:junit4]   2> 673 T75 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 674 T75 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=10,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 675 T75 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 675 T75 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 676 T75 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 727 T75 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 729 T75 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:53658
[junit4:junit4]   2> 731 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 732 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master
[junit4:junit4]   2> 733 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/'
[junit4:junit4]   2> 754 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 754 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 755 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master
[junit4:junit4]   2> 755 T75 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/solr.xml
[junit4:junit4]   2> 755 T75 oasc.CoreContainer.<init> New CoreContainer 6936474
[junit4:junit4]   2> 756 T75 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 756 T75 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/'
[junit4:junit4]   2> 757 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/'
[junit4:junit4]   2> 771 T75 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 785 T75 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1
[junit4:junit4]   2> 785 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/'
[junit4:junit4]   2> 810 T75 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 845 T75 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 845 T75 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 848 T75 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 860 T75 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 861 T75 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/
[junit4:junit4]   2> 862 T75 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 862 T75 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index/
[junit4:junit4]   2> 863 T75 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 864 T75 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 864 T75 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 864 T75 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 865 T75 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 865 T75 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 866 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 869 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index forceNew:false
[junit4:junit4]   2> 870 T75 oass.SolrIndexSearcher.<init> Opening Searcher@cc5fb1 main
[junit4:junit4]   2> 871 T75 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 871 T75 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 872 T75 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 872 T75 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 872 T75 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 873 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 873 T75 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 873 T75 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 874 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 874 T75 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 874 T75 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 875 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 878 T75 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4]   2> 879 T75 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 879 T75 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 880 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index forceNew:true
[junit4:junit4]   2> 882 T75 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.fdt, _0.fnm, _0.si, _0_nrm.cfe, _0_nrm.cfs, _0.fdx, _0_SimpleText_0.pst, segments_2]
[junit4:junit4]   2> 882 T75 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 883 T75 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 883 T75 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 883 T106 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@cc5fb1 main{StandardDirectoryReader(segments_2:4 _0(4.1):C10)}
[junit4:junit4]   2> 883 T75 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 884 T75 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 884 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 885 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 889 T75 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 891 T75 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:57309
[junit4:junit4]   2> 891 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 892 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave
[junit4:junit4]   2> 892 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/'
[junit4:junit4]   2> 902 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 902 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 903 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave
[junit4:junit4]   2> 903 T75 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/solr.xml
[junit4:junit4]   2> 903 T75 oasc.CoreContainer.<init> New CoreContainer 6496549
[junit4:junit4]   2> 904 T75 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 904 T75 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/'
[junit4:junit4]   2> 904 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/'
[junit4:junit4]   2> 917 T75 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 930 T75 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1
[junit4:junit4]   2> 931 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/'
[junit4:junit4]   2> 954 T75 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 986 T75 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 987 T75 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 989 T75 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1001 T75 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1002 T75 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/
[junit4:junit4]   2> 1002 T75 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 1003 T75 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/
[junit4:junit4]   2> 1004 T75 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1004 T75 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1004 T75 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1005 T75 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1005 T75 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1006 T75 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1006 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1009 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index forceNew:false
[junit4:junit4]   2> 1009 T75 oass.SolrIndexSearcher.<init> Opening Searcher@122aaf4 main
[junit4:junit4]   2> 1010 T75 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1010 T75 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1011 T75 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1011 T75 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1011 T75 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1012 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1012 T75 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1012 T75 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1013 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1013 T75 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1014 T75 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1014 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1018 T75 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 1018 T116 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@122aaf4 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1018 T75 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1019 T75 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1019 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1020 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C6 name=collection1 org.apache.solr.core.SolrCore@1038e75
[junit4:junit4]   2> 1033 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1136 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1239 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1342 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1445 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1548 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1651 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1754 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=1 
[junit4:junit4]   2> ASYNC  NEW_CORE C7 name=collection1 org.apache.solr.core.SolrCore@1322d18
[junit4:junit4]   2> 1794 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=indexversion&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 1795 T117 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 1795 T117 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 1795 T117 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 1800 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&generation=2&command=filelist&qt=/replication&version=2} status=0 QTime=3 
[junit4:junit4]   2> 1801 T117 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 8
[junit4:junit4]   2> 1802 T117 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114111012 fullCopy=false
[junit4:junit4]   2> 1808 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=2&command=filecontent&qt=/replication&file=_0.fdt} status=0 QTime=2 
[junit4:junit4]   2> 1816 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=2&command=filecontent&qt=/replication&file=_0.fnm} status=0 QTime=0 
[junit4:junit4]   2> 1819 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=2&command=filecontent&qt=/replication&file=_0.si} status=0 QTime=0 
[junit4:junit4]   2> 1823 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=2&command=filecontent&qt=/replication&file=_0_nrm.cfs} status=0 QTime=1 
[junit4:junit4]   2> 1826 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=2&command=filecontent&qt=/replication&file=_0_nrm.cfe} status=0 QTime=0 
[junit4:junit4]   2> 1829 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=2&command=filecontent&qt=/replication&file=_0.fdx} status=0 QTime=0 
[junit4:junit4]   2> 1834 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=2&command=filecontent&qt=/replication&file=_0_SimpleText_0.pst} status=0 QTime=1 
[junit4:junit4]   2> 1838 T99 C7 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=2&command=filecontent&qt=/replication&file=segments_2} status=0 QTime=1 
[junit4:junit4]   2> 1839 T117 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 1857 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1960 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1961 T117 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 1961 T117 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 1962 T117 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index forceNew:true
[junit4:junit4]   2> 1964 T117 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.fdt, _0.fnm, _0.si, _0_nrm.cfe, _0_nrm.cfs, _0.fdx, _0_SimpleText_0.pst, segments_2]
[junit4:junit4]   2> 1965 T117 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 1965 T117 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 1966 T117 oass.SolrIndexSearcher.<init> Opening Searcher@114fb0a main
[junit4:junit4]   2> 1967 T116 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@114fb0a main{StandardDirectoryReader(segments_2:4:nrt _0(4.1):C10)}
[junit4:junit4]   2> 1967 T116 oasc.CachingDirectoryFactory.close Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index
[junit4:junit4]   2> 1967 T117 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1968 T117 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1968 T117 oash.SnapPuller.fetchLatestIndex removing temporary index download directory ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114111012
[junit4:junit4]   2> 2063 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 2173 T100 C7 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=indexversion&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 2175 T100 C7 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=commits&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 2180 T100 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1
[junit4:junit4]   2> 2180 T100 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/'
[junit4:junit4]   2> 2208 T100 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 2242 T100 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2243 T100 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2245 T100 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2261 T100 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2262 T100 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/
[junit4:junit4]   2> 2263 T100 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 2263 T100 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index/
[junit4:junit4]   2> 2264 T100 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2265 T100 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2265 T100 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2265 T100 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2266 T100 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2266 T100 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2267 T100 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2272 T100 oass.SolrIndexSearcher.<init> Opening Searcher@13f3c7d main
[junit4:junit4]   2> 2273 T100 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2273 T100 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2274 T100 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4]   2> 2274 T100 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 2274 T100 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 2274 T100 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 2275 T100 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index forceNew:true
[junit4:junit4]   2> 2277 T100 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.fdt, _0.fnm, _0.si, _0_nrm.cfe, _0_nrm.cfs, _0.fdx, _0_SimpleText_0.pst, segments_2]
[junit4:junit4]   2> 2278 T100 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 2278 T100 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 2278 T100 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 2284 T119 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13f3c7d main{StandardDirectoryReader(segments_2:4 _0(4.1):C10)}
[junit4:junit4]   2> 2284 T100 oasc.CoreContainer.register replacing core: collection1
[junit4:junit4]   2> 2285 T100 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1322d18
[junit4:junit4]   2> 2285 T100 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 2286 T100 oasc.CachingDirectoryFactory.close Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index
[junit4:junit4]   2> 2287 T100 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> ASYNC  NEW_CORE C8 name=collection1 org.apache.solr.core.SolrCore@bda240
[junit4:junit4]   2> 2295 T100 C8 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=indexversion&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 2298 T100 C8 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=commits&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 2304 T100 C8 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 2
[junit4:junit4]   2> 2307 T100 C8 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 1
[junit4:junit4]   2> 2310 T100 C8 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2362 T100 C8 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.fdt, _0.fnm, _0.si, _0_nrm.cfe, _0_nrm.cfs, _0.fdx, _0_SimpleText_0.pst, segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.fdt, _0.fnm, _1_nrm.cfs, _1_SimpleText_0.pst, _1.fdt, _0.fdx, _1.fdx, _1.si, _0.si, _1_nrm.cfe, _0_nrm.cfs, _0_nrm.cfe, _1.fnm, segments_3, _0_SimpleText_0.pst]
[junit4:junit4]   2> 2363 T100 C8 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 2367 T100 C8 oass.SolrIndexSearcher.<init> Opening Searcher@b46dee main
[junit4:junit4]   2> 2367 T100 C8 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2367 T119 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b46dee main{StandardDirectoryReader(segments_3:6 _0(4.1):C10 _1(4.1):C2)}
[junit4:junit4]   2> 2368 T100 C8 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 58
[junit4:junit4]   2> 2371 T100 C8 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 2474 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 2577 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=10 status=0 QTime=0 
[junit4:junit4]   2> 2680 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=10 status=0 QTime=0 
[junit4:junit4]   2> 2783 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=10 status=0 QTime=0 
[junit4:junit4]   2> 2791 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=indexversion&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 2791 T117 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4:junit4]   2> 2792 T117 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4:junit4]   2> 2792 T117 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 2795 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&generation=3&command=filelist&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 2796 T117 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 15
[junit4:junit4]   2> 2798 T117 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114112008 fullCopy=false
[junit4:junit4]   2> 2799 T117 oash.SnapPuller.downloadIndexFiles Skipping download for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/_0.fdt
[junit4:junit4]   2> 2799 T117 oash.SnapPuller.downloadIndexFiles Skipping download for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/_0.fnm
[junit4:junit4]   2> 2802 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=3&command=filecontent&qt=/replication&file=_1_nrm.cfs} status=0 QTime=0 
[junit4:junit4]   2> 2807 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=3&command=filecontent&qt=/replication&file=_1_SimpleText_0.pst} status=0 QTime=0 
[junit4:junit4]   2> 2809 T117 oash.SnapPuller.downloadIndexFiles Skipping download for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/_0.fdx
[junit4:junit4]   2> 2812 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=3&command=filecontent&qt=/replication&file=_1.fdt} status=0 QTime=0 
[junit4:junit4]   2> 2816 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=3&command=filecontent&qt=/replication&file=_1.fdx} status=0 QTime=0 
[junit4:junit4]   2> 2820 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=3&command=filecontent&qt=/replication&file=_1.si} status=0 QTime=0 
[junit4:junit4]   2> 2822 T117 oash.SnapPuller.downloadIndexFiles Skipping download for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/_0.si
[junit4:junit4]   2> 2825 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=3&command=filecontent&qt=/replication&file=_1_nrm.cfe} status=0 QTime=1 
[junit4:junit4]   2> 2826 T117 oash.SnapPuller.downloadIndexFiles Skipping download for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/_0_nrm.cfe
[junit4:junit4]   2> 2827 T117 oash.SnapPuller.downloadIndexFiles Skipping download for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/_0_nrm.cfs
[junit4:junit4]   2> 2829 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=3&command=filecontent&qt=/replication&file=_1.fnm} status=0 QTime=0 
[junit4:junit4]   2> 2831 T117 oash.SnapPuller.downloadIndexFiles Skipping download for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/_0_SimpleText_0.pst
[junit4:junit4]   2> 2834 T99 C8 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=3&command=filecontent&qt=/replication&file=segments_3} status=0 QTime=0 
[junit4:junit4]   2> 2835 T117 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 2838 T117 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 2838 T117 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 2838 T117 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4]   2> 2839 T117 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index forceNew:true
[junit4:junit4]   2> 2852 T117 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.fdt, _0.fnm, _0.si, _0_nrm.cfe, _0_nrm.cfs, _0.fdx, _0_SimpleText_0.pst, segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.fdt, _0.fnm, _1_nrm.cfs, _1_SimpleText_0.pst, _1.fdt, _0.fdx, _1.fdx, _1.si, _0.si, _1_nrm.cfe, _0_nrm.cfs, _0_nrm.cfe, _1.fnm, segments_3, _0_SimpleText_0.pst]
[junit4:junit4]   2> 2853 T117 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 2853 T117 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 2855 T117 oass.SolrIndexSearcher.<init> Opening Searcher@df145c main
[junit4:junit4]   2> 2855 T116 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@df145c main{StandardDirectoryReader(segments_3:6:nrt _0(4.1):C10 _1(4.1):C2)}
[junit4:junit4]   2> 2856 T117 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2856 T117 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2856 T117 oash.SnapPuller.fetchLatestIndex removing temporary index download directory ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114112008
[junit4:junit4]   2> 2887 T109 C6 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 2990 T75 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=6936474
[junit4:junit4]   2> 2990 T75 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@bda240
[junit4:junit4]   2> 2991 T75 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 2992 T75 oasc.CachingDirectoryFactory.close Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index
[junit4:junit4]   2> 2993 T75 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=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 2993 T75 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 2994 T75 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 2995 T75 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 3046 T75 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3048 T75 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:45949
[junit4:junit4]   2> 3048 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3049 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master
[junit4:junit4]   2> 3049 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/'
[junit4:junit4]   2> 3063 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3064 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3064 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master
[junit4:junit4]   2> 3065 T75 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/solr.xml
[junit4:junit4]   2> 3065 T75 oasc.CoreContainer.<init> New CoreContainer 2390201
[junit4:junit4]   2> 3065 T75 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 3066 T75 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/'
[junit4:junit4]   2> 3066 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/'
[junit4:junit4]   2> 3081 T75 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3094 T75 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1
[junit4:junit4]   2> 3095 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/'
[junit4:junit4]   2> 3119 T75 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 3151 T75 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3152 T75 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3155 T75 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3167 T75 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3168 T75 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/
[junit4:junit4]   2> 3168 T75 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 3169 T75 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index/
[junit4:junit4]   2> 3170 T75 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3170 T75 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3171 T75 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3171 T75 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3171 T75 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3172 T75 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3173 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3176 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index forceNew:false
[junit4:junit4]   2> 3179 T75 oass.SolrIndexSearcher.<init> Opening Searcher@18dfc01 main
[junit4:junit4]   2> 3179 T75 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3180 T75 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3180 T75 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3180 T75 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3181 T75 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3181 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3182 T75 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3182 T75 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3182 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3183 T75 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3183 T75 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3183 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3187 T75 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4]   2> 3188 T75 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 3189 T75 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3189 T75 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3190 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3190 T130 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18dfc01 main{StandardDirectoryReader(segments_3:6 _0(4.1):C10 _1(4.1):C2)}
[junit4:junit4]   2> 3190 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3195 T75 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=6496549
[junit4:junit4]   2> 3196 T75 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1038e75
[junit4:junit4]   2> 3196 T75 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 3197 T75 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,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> 3197 T75 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 3198 T75 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 3199 T75 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 3251 T75 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3253 T75 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:60782
[junit4:junit4]   2> 3253 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3253 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave
[junit4:junit4]   2> 3254 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/'
[junit4:junit4]   2> 3265 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3265 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3266 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave
[junit4:junit4]   2> 3266 T75 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/solr.xml
[junit4:junit4]   2> 3266 T75 oasc.CoreContainer.<init> New CoreContainer 15233372
[junit4:junit4]   2> 3267 T75 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 3267 T75 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/'
[junit4:junit4]   2> 3268 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/'
[junit4:junit4]   2> 3282 T75 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3297 T75 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1
[junit4:junit4]   2> 3297 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/'
[junit4:junit4]   2> 3323 T75 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 3357 T75 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3358 T75 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3360 T75 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3376 T75 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3377 T75 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/
[junit4:junit4]   2> 3378 T75 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 3378 T75 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/
[junit4:junit4]   2> 3379 T75 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3380 T75 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3380 T75 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3381 T75 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3381 T75 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3381 T75 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3382 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3385 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index forceNew:false
[junit4:junit4]   2> 3388 T75 oass.SolrIndexSearcher.<init> Opening Searcher@1d33d3e main
[junit4:junit4]   2> 3388 T75 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3389 T75 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3389 T75 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3390 T75 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3390 T75 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3390 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3391 T75 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3391 T75 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3391 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3392 T75 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3392 T75 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3393 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3396 T75 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 3396 T140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d33d3e main{StandardDirectoryReader(segments_3:6 _0(4.1):C10 _1(4.1):C2)}
[junit4:junit4]   2> 3396 T75 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3397 T75 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3397 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3398 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C9 name=collection1 org.apache.solr.core.SolrCore@6aad80
[junit4:junit4]   2> 3419 T123 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=details&qt=/replication&version=2} status=0 QTime=14 
[junit4:junit4]   2> 3428 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=details&slave=false&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> ASYNC  NEW_CORE C10 name=collection1 org.apache.solr.core.SolrCore@c09dc5
[junit4:junit4]   2> 3429 T133 C10 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=details&qt=/replication&version=2} status=0 QTime=5 
[junit4:junit4]   2> 3433 T75 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3435 T75 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:59247
[junit4:junit4]   2> 3435 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3436 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater
[junit4:junit4]   2> 3436 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/'
[junit4:junit4]   2> 3449 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3449 T75 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3450 T75 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater
[junit4:junit4]   2> 3450 T75 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/solr.xml
[junit4:junit4]   2> 3450 T75 oasc.CoreContainer.<init> New CoreContainer 17529660
[junit4:junit4]   2> 3451 T75 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 3451 T75 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/'
[junit4:junit4]   2> 3452 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/'
[junit4:junit4]   2> 3465 T75 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3477 T75 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/collection1
[junit4:junit4]   2> 3478 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/collection1/'
[junit4:junit4]   2> 3503 T75 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 3536 T75 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3536 T75 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3539 T75 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3552 T75 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3553 T75 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/collection1/data/
[junit4:junit4]   2> 3553 T75 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 3554 T75 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/collection1/data/index/
[junit4:junit4]   2> 3555 T75 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3555 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/collection1/data/index forceNew:false
[junit4:junit4]   2> 3584 T75 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630872641/repeater/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3584 T75 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3585 T75 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3586 T75 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3586 T75 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3586 T75 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3587 T75 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3587 T75 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3588 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3591 T75 oass.SolrIndexSearcher.<init> Opening Searcher@242c99 main
[junit4:junit4]   2> 3591 T75 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3592 T75 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3592 T75 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3593 T75 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3593 T75 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3593 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3594 T75 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3594 T75 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3594 T75 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3595 T75 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3595 T75 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3595 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3599 T75 oash.SnapPuller.<init> WARNING 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   2> 3599 T75 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 3600 T75 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4]   2> 3600 T75 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 3600 T151 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@242c99 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3600 T75 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3601 T75 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3601 T75 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3602 T75 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3612 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=details&slave=false&qt=/replication&version=2} status=0 QTime=2 
[junit4:junit4]   2> ASYNC  NEW_CORE C11 name=collection1 org.apache.solr.core.SolrCore@f9ed78
[junit4:junit4]   2> 3613 T144 C11 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=details&qt=/replication&version=2} status=0 QTime=6 
[junit4:junit4]   2> 3614 T75 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=17529660
[junit4:junit4]   2> 3614 T75 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@f9ed78
[junit4:junit4]   2> 3615 T75 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 3615 T75 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> 3615 T75 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 3616 T75 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 3616 T75 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 3672 T123 C9 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 3675 T123 C9 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 3680 T123 C9 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.fdt, _0.fnm, _0.si, _0_nrm.cfe, _0_nrm.cfs, _0.fdx, _0_SimpleText_0.pst, segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.fdt, _0.fnm, _1_nrm.cfs, _1_SimpleText_0.pst, _1.fdt, _0.fdx, _1.fdx, _1.si, _0.si, _1_nrm.cfe, _0_nrm.cfs, _0_nrm.cfe, _1.fnm, segments_3, _0_SimpleText_0.pst]
[junit4:junit4]   2> 3687 T123 C9 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 3690 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 15
[junit4:junit4]   2> 3693 T123 C9 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3762 T123 C9 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.fdt, _0.fnm, _1_nrm.cfs, _1_SimpleText_0.pst, _1.fdt, _0.fdx, _1.fdx, _1.si, _0.si, _1_nrm.cfe, _0_nrm.cfs, _0_nrm.cfe, _1.fnm, segments_3, _0_SimpleText_0.pst]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 3763 T123 C9 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 3765 T123 C9 oass.SolrIndexSearcher.<init> Opening Searcher@9d609d main
[junit4:junit4]   2> 3766 T123 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3766 T130 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9d609d main{StandardDirectoryReader(segments_4:7)}
[junit4:junit4]   2> 3768 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 75
[junit4:junit4]   2> 3770 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=12 status=0 QTime=0 
[junit4:junit4]   2> 3791 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=indexversion&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3791 T141 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4:junit4]   2> 3792 T141 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4:junit4]   2> 3792 T141 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 3794 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&generation=4&command=filelist&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 3795 T141 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 3796 T141 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114113006 fullCopy=false
[junit4:junit4]   2> 3798 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=4&command=filecontent&qt=/replication&file=segments_4} status=0 QTime=0 
[junit4:junit4]   2> 3800 T141 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 3843 T141 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 3843 T141 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 3843 T141 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index forceNew:true
[junit4:junit4]   2> 3846 T141 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.fdt, _0.fnm, _1_nrm.cfs, _1_SimpleText_0.pst, _1.fdt, _0.fdx, _1.fdx, _1.si, _0.si, _1_nrm.cfe, _0_nrm.cfs, _0_nrm.cfe, _1.fnm, segments_3, _0_SimpleText_0.pst]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 3847 T141 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 3848 T141 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 3848 T141 oass.SolrIndexSearcher.<init> Opening Searcher@c9af0e main
[junit4:junit4]   2> 3849 T140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c9af0e main{StandardDirectoryReader(segments_4:7:nrt)}
[junit4:junit4]   2> 3850 T140 oasc.CachingDirectoryFactory.close Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index
[junit4:junit4]   2> 3851 T141 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3851 T141 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3852 T141 oash.SnapPuller.fetchLatestIndex removing temporary index download directory ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114113006
[junit4:junit4]   2> 3874 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 3977 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4:junit4]   2> 3980 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4:junit4]   2> 3982 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4:junit4]   2> 3985 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4:junit4]   2> 3987 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4:junit4]   2> 3989 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4:junit4]   2> 3992 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4:junit4]   2> 3994 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4:junit4]   2> 3996 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4:junit4]   2> 3998 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 0
[junit4:junit4]   2> 4001 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4:junit4]   2> 4003 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4:junit4]   2> 4006 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 1
[junit4:junit4]   2> 4008 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4:junit4]   2> 4010 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4:junit4]   2> 4013 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 1
[junit4:junit4]   2> 4015 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4:junit4]   2> 4017 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4:junit4]   2> 4019 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
[junit4:junit4]   2> 4022 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 1
[junit4:junit4]   2> 4024 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4:junit4]   2> 4026 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 0
[junit4:junit4]   2> 4029 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 1
[junit4:junit4]   2> 4032 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 1
[junit4:junit4]   2> 4045 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 1
[junit4:junit4]   2> 4048 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 0
[junit4:junit4]   2> 4054 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 0
[junit4:junit4]   2> 4057 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 1
[junit4:junit4]   2> 4059 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 0
[junit4:junit4]   2> 4061 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4:junit4]   2> 4064 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 1
[junit4:junit4]   2> 4066 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 0
[junit4:junit4]   2> 4068 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 0
[junit4:junit4]   2> 4071 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 1
[junit4:junit4]   2> 4073 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
[junit4:junit4]   2> 4075 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
[junit4:junit4]   2> 4078 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4:junit4]   2> 4080 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4:junit4]   2> 4083 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 1
[junit4:junit4]   2> 4085 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 0
[junit4:junit4]   2> 4087 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 0
[junit4:junit4]   2> 4101 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 1
[junit4:junit4]   2> 4104 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4:junit4]   2> 4106 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4:junit4]   2> 4109 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 0
[junit4:junit4]   2> 4111 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 0
[junit4:junit4]   2> 4113 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4:junit4]   2> 4115 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4:junit4]   2> 4118 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 1
[junit4:junit4]   2> 4120 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 0
[junit4:junit4]   2> 4122 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 0
[junit4:junit4]   2> 4125 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 1
[junit4:junit4]   2> 4127 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 0
[junit4:junit4]   2> 4129 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4:junit4]   2> 4132 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 1
[junit4:junit4]   2> 4134 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 0
[junit4:junit4]   2> 4136 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 0
[junit4:junit4]   2> 4139 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 1
[junit4:junit4]   2> 4141 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4:junit4]   2> 4143 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 0
[junit4:junit4]   2> 4146 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 1
[junit4:junit4]   2> 4148 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 0
[junit4:junit4]   2> 4150 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 0
[junit4:junit4]   2> 4152 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 0
[junit4:junit4]   2> 4155 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 1
[junit4:junit4]   2> 4157 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 0
[junit4:junit4]   2> 4159 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 0
[junit4:junit4]   2> 4162 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 1
[junit4:junit4]   2> 4164 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 0
[junit4:junit4]   2> 4166 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4:junit4]   2> 4169 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 1
[junit4:junit4]   2> 4171 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 0
[junit4:junit4]   2> 4173 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 0
[junit4:junit4]   2> 4176 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 1
[junit4:junit4]   2> 4178 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 0
[junit4:junit4]   2> 4181 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 0
[junit4:junit4]   2> 4184 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 1
[junit4:junit4]   2> 4186 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 0
[junit4:junit4]   2> 4188 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 0
[junit4:junit4]   2> 4191 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 1
[junit4:junit4]   2> 4193 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 0
[junit4:junit4]   2> 4195 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 0
[junit4:junit4]   2> 4197 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 0
[junit4:junit4]   2> 4200 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 1
[junit4:junit4]   2> 4202 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4:junit4]   2> 4204 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 0
[junit4:junit4]   2> 4206 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 0
[junit4:junit4]   2> 4209 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 1
[junit4:junit4]   2> 4211 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 0
[junit4:junit4]   2> 4213 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 0
[junit4:junit4]   2> 4216 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 1
[junit4:junit4]   2> 4218 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 0
[junit4:junit4]   2> 4220 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4:junit4]   2> 4223 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 1
[junit4:junit4]   2> 4225 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 0
[junit4:junit4]   2> 4227 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 0
[junit4:junit4]   2> 4229 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4:junit4]   2> 4231 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 0
[junit4:junit4]   2> 4234 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 1
[junit4:junit4]   2> 4236 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 0
[junit4:junit4]   2> 4238 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 0
[junit4:junit4]   2> 4240 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 0
[junit4:junit4]   2> 4243 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 1
[junit4:junit4]   2> 4245 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4:junit4]   2> 4247 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4:junit4]   2> 4249 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 0
[junit4:junit4]   2> 4252 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 1
[junit4:junit4]   2> 4254 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 0
[junit4:junit4]   2> 4256 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 0
[junit4:junit4]   2> 4258 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 0
[junit4:junit4]   2> 4261 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 1
[junit4:junit4]   2> 4263 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 0
[junit4:junit4]   2> 4265 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 0
[junit4:junit4]   2> 4267 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 0
[junit4:junit4]   2> 4270 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 1
[junit4:junit4]   2> 4272 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 0
[junit4:junit4]   2> 4274 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 0
[junit4:junit4]   2> 4276 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 0
[junit4:junit4]   2> 4279 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 1
[junit4:junit4]   2> 4281 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4:junit4]   2> 4283 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 0
[junit4:junit4]   2> 4286 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 1
[junit4:junit4]   2> 4288 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 0
[junit4:junit4]   2> 4290 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 0
[junit4:junit4]   2> 4292 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 0
[junit4:junit4]   2> 4295 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 1
[junit4:junit4]   2> 4297 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 0
[junit4:junit4]   2> 4299 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 0
[junit4:junit4]   2> 4302 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128]} 0 1
[junit4:junit4]   2> 4304 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 0
[junit4:junit4]   2> 4306 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4:junit4]   2> 4308 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 0
[junit4:junit4]   2> 4310 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132]} 0 0
[junit4:junit4]   2> 4313 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133]} 0 0
[junit4:junit4]   2> 4315 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 0
[junit4:junit4]   2> 4317 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135]} 0 0
[junit4:junit4]   2> 4320 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 1
[junit4:junit4]   2> 4322 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137]} 0 0
[junit4:junit4]   2> 4324 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138]} 0 0
[junit4:junit4]   2> 4326 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 0
[junit4:junit4]   2> 4329 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 1
[junit4:junit4]   2> 4331 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 0
[junit4:junit4]   2> 4333 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 0
[junit4:junit4]   2> 4335 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 0
[junit4:junit4]   2> 4338 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 1
[junit4:junit4]   2> 4340 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 0
[junit4:junit4]   2> 4342 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 0
[junit4:junit4]   2> 4344 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 0
[junit4:junit4]   2> 4347 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 1
[junit4:junit4]   2> 4349 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149]} 0 0
[junit4:junit4]   2> 4351 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[150]} 0 0
[junit4:junit4]   2> 4354 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[151]} 0 1
[junit4:junit4]   2> 4356 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[152]} 0 0
[junit4:junit4]   2> 4358 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[153]} 0 0
[junit4:junit4]   2> 4360 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[154]} 0 0
[junit4:junit4]   2> 4363 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[155]} 0 1
[junit4:junit4]   2> 4365 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[156]} 0 0
[junit4:junit4]   2> 4367 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[157]} 0 0
[junit4:junit4]   2> 4369 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[158]} 0 0
[junit4:junit4]   2> 4371 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[159]} 0 0
[junit4:junit4]   2> 4374 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[160]} 0 1
[junit4:junit4]   2> 4376 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[161]} 0 0
[junit4:junit4]   2> 4378 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[162]} 0 0
[junit4:junit4]   2> 4381 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[163]} 0 1
[junit4:junit4]   2> 4383 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[164]} 0 0
[junit4:junit4]   2> 4385 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[165]} 0 0
[junit4:junit4]   2> 4387 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[166]} 0 0
[junit4:junit4]   2> 4390 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[167]} 0 0
[junit4:junit4]   2> 4392 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[168]} 0 0
[junit4:junit4]   2> 4394 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[169]} 0 0
[junit4:junit4]   2> 4397 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[170]} 0 1
[junit4:junit4]   2> 4399 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[171]} 0 0
[junit4:junit4]   2> 4401 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[172]} 0 0
[junit4:junit4]   2> 4404 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[173]} 0 1
[junit4:junit4]   2> 4406 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[174]} 0 0
[junit4:junit4]   2> 4408 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[175]} 0 0
[junit4:junit4]   2> 4410 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[176]} 0 0
[junit4:junit4]   2> 4413 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[177]} 0 1
[junit4:junit4]   2> 4415 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[178]} 0 0
[junit4:junit4]   2> 4417 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[179]} 0 0
[junit4:junit4]   2> 4419 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[180]} 0 0
[junit4:junit4]   2> 4422 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[181]} 0 1
[junit4:junit4]   2> 4442 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[182]} 0 1
[junit4:junit4]   2> 4445 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[183]} 0 1
[junit4:junit4]   2> 4448 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[184]} 0 1
[junit4:junit4]   2> 4450 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[185]} 0 0
[junit4:junit4]   2> 4453 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[186]} 0 0
[junit4:junit4]   2> 4456 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[187]} 0 0
[junit4:junit4]   2> 4459 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[188]} 0 1
[junit4:junit4]   2> 4461 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[189]} 0 0
[junit4:junit4]   2> 4471 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[190]} 0 0
[junit4:junit4]   2> 4474 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[191]} 0 1
[junit4:junit4]   2> 4476 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[192]} 0 0
[junit4:junit4]   2> 4478 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[193]} 0 0
[junit4:junit4]   2> 4481 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[194]} 0 1
[junit4:junit4]   2> 4483 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[195]} 0 1
[junit4:junit4]   2> 4485 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[196]} 0 1
[junit4:junit4]   2> 4487 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[197]} 0 0
[junit4:junit4]   2> 4489 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[198]} 0 0
[junit4:junit4]   2> 4491 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[199]} 0 0
[junit4:junit4]   2> 4493 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[200]} 0 0
[junit4:junit4]   2> 4495 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[201]} 0 0
[junit4:junit4]   2> 4498 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[202]} 0 1
[junit4:junit4]   2> 4500 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[203]} 0 1
[junit4:junit4]   2> 4502 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[204]} 0 0
[junit4:junit4]   2> 4504 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[205]} 0 0
[junit4:junit4]   2> 4506 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[206]} 0 0
[junit4:junit4]   2> 4508 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[207]} 0 0
[junit4:junit4]   2> 4510 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[208]} 0 0
[junit4:junit4]   2> 4513 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[209]} 0 1
[junit4:junit4]   2> 4515 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[210]} 0 1
[junit4:junit4]   2> 4517 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[211]} 0 0
[junit4:junit4]   2> 4519 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[212]} 0 0
[junit4:junit4]   2> 4521 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[213]} 0 0
[junit4:junit4]   2> 4523 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[214]} 0 0
[junit4:junit4]   2> 4525 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[215]} 0 0
[junit4:junit4]   2> 4527 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[216]} 0 0
[junit4:junit4]   2> 4529 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[217]} 0 0
[junit4:junit4]   2> 4532 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[218]} 0 1
[junit4:junit4]   2> 4534 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[219]} 0 0
[junit4:junit4]   2> 4536 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[220]} 0 0
[junit4:junit4]   2> 4538 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[221]} 0 0
[junit4:junit4]   2> 4540 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222]} 0 0
[junit4:junit4]   2> 4542 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[223]} 0 0
[junit4:junit4]   2> 4544 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[224]} 0 0
[junit4:junit4]   2> 4546 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[225]} 0 0
[junit4:junit4]   2> 4549 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[226]} 0 1
[junit4:junit4]   2> 4551 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[227]} 0 0
[junit4:junit4]   2> 4553 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[228]} 0 0
[junit4:junit4]   2> 4555 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[229]} 0 0
[junit4:junit4]   2> 4557 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[230]} 0 0
[junit4:junit4]   2> 4559 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[231]} 0 0
[junit4:junit4]   2> 4561 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[232]} 0 0
[junit4:junit4]   2> 4564 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[233]} 0 1
[junit4:junit4]   2> 4566 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[234]} 0 0
[junit4:junit4]   2> 4568 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[235]} 0 0
[junit4:junit4]   2> 4570 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[236]} 0 0
[junit4:junit4]   2> 4572 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[237]} 0 0
[junit4:junit4]   2> 4574 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[238]} 0 0
[junit4:junit4]   2> 4576 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[239]} 0 0
[junit4:junit4]   2> 4578 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[240]} 0 0
[junit4:junit4]   2> 4580 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[241]} 0 0
[junit4:junit4]   2> 4582 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[242]} 0 0
[junit4:junit4]   2> 4584 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[243]} 0 0
[junit4:junit4]   2> 4586 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[244]} 0 0
[junit4:junit4]   2> 4589 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[245]} 0 1
[junit4:junit4]   2> 4591 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[246]} 0 0
[junit4:junit4]   2> 4593 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[247]} 0 0
[junit4:junit4]   2> 4595 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[248]} 0 0
[junit4:junit4]   2> 4597 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[249]} 0 0
[junit4:junit4]   2> 4599 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[250]} 0 0
[junit4:junit4]   2> 4601 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[251]} 0 0
[junit4:junit4]   2> 4603 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[252]} 0 0
[junit4:junit4]   2> 4605 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[253]} 0 0
[junit4:junit4]   2> 4607 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[254]} 0 0
[junit4:junit4]   2> 4610 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[255]} 0 1
[junit4:junit4]   2> 4612 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[256]} 0 0
[junit4:junit4]   2> 4614 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[257]} 0 0
[junit4:junit4]   2> 4616 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[258]} 0 0
[junit4:junit4]   2> 4618 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[259]} 0 0
[junit4:junit4]   2> 4620 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[260]} 0 0
[junit4:junit4]   2> 4622 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[261]} 0 0
[junit4:junit4]   2> 4624 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[262]} 0 0
[junit4:junit4]   2> 4626 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[263]} 0 0
[junit4:junit4]   2> 4629 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[264]} 0 1
[junit4:junit4]   2> 4631 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[265]} 0 1
[junit4:junit4]   2> 4633 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[266]} 0 1
[junit4:junit4]   2> 4635 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[267]} 0 0
[junit4:junit4]   2> 4637 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[268]} 0 0
[junit4:junit4]   2> 4639 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[269]} 0 0
[junit4:junit4]   2> 4641 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[270]} 0 0
[junit4:junit4]   2> 4643 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[271]} 0 0
[junit4:junit4]   2> 4645 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[272]} 0 0
[junit4:junit4]   2> 4647 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[273]} 0 0
[junit4:junit4]   2> 4649 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[274]} 0 0
[junit4:junit4]   2> 4651 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[275]} 0 0
[junit4:junit4]   2> 4653 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[276]} 0 0
[junit4:junit4]   2> 4655 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[277]} 0 0
[junit4:junit4]   2> 4657 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[278]} 0 0
[junit4:junit4]   2> 4659 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[279]} 0 0
[junit4:junit4]   2> 4662 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[280]} 0 1
[junit4:junit4]   2> 4664 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[281]} 0 1
[junit4:junit4]   2> 4666 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[282]} 0 0
[junit4:junit4]   2> 4668 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[283]} 0 0
[junit4:junit4]   2> 4670 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[284]} 0 0
[junit4:junit4]   2> 4672 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[285]} 0 0
[junit4:junit4]   2> 4675 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[286]} 0 1
[junit4:junit4]   2> 4677 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[287]} 0 0
[junit4:junit4]   2> 4680 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[288]} 0 0
[junit4:junit4]   2> 4682 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[289]} 0 0
[junit4:junit4]   2> 4685 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[290]} 0 1
[junit4:junit4]   2> 4687 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[291]} 0 0
[junit4:junit4]   2> 4689 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[292]} 0 0
[junit4:junit4]   2> 4691 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[293]} 0 0
[junit4:junit4]   2> 4693 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[294]} 0 0
[junit4:junit4]   2> 4695 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[295]} 0 0
[junit4:junit4]   2> 4698 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[296]} 0 1
[junit4:junit4]   2> 4700 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[297]} 0 0
[junit4:junit4]   2> 4702 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[298]} 0 0
[junit4:junit4]   2> 4704 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[299]} 0 0
[junit4:junit4]   2> 4706 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[300]} 0 0
[junit4:junit4]   2> 4708 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[301]} 0 0
[junit4:junit4]   2> 4710 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[302]} 0 0
[junit4:junit4]   2> 4712 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[303]} 0 0
[junit4:junit4]   2> 4715 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[304]} 0 1
[junit4:junit4]   2> 4717 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[305]} 0 0
[junit4:junit4]   2> 4719 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[306]} 0 0
[junit4:junit4]   2> 4721 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[307]} 0 0
[junit4:junit4]   2> 4723 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[308]} 0 0
[junit4:junit4]   2> 4725 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[309]} 0 0
[junit4:junit4]   2> 4728 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[310]} 0 1
[junit4:junit4]   2> 4730 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[311]} 0 0
[junit4:junit4]   2> 4732 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[312]} 0 0
[junit4:junit4]   2> 4734 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[313]} 0 0
[junit4:junit4]   2> 4736 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[314]} 0 0
[junit4:junit4]   2> 4749 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[315]} 0 1
[junit4:junit4]   2> 4752 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[316]} 0 0
[junit4:junit4]   2> 4755 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[317]} 0 1
[junit4:junit4]   2> 4757 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[318]} 0 0
[junit4:junit4]   2> 4760 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[319]} 0 1
[junit4:junit4]   2> 4762 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[320]} 0 0
[junit4:junit4]   2> 4764 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[321]} 0 0
[junit4:junit4]   2> 4766 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[322]} 0 0
[junit4:junit4]   2> 4770 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[323]} 0 1
[junit4:junit4]   2> 4772 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[324]} 0 0
[junit4:junit4]   2> 4774 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[325]} 0 0
[junit4:junit4]   2> 4777 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[326]} 0 1
[junit4:junit4]   2> 4779 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[327]} 0 0
[junit4:junit4]   2> 4781 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[328]} 0 0
[junit4:junit4]   2> 4783 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[329]} 0 0
[junit4:junit4]   2> 4786 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[330]} 0 1
[junit4:junit4]   2> 4788 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[331]} 0 1
[junit4:junit4]   2> 4791 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=indexversion&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4791 T141 oash.SnapPuller.fetchLatestIndex Slave in sync with master.
[junit4:junit4]   2> 4792 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[332]} 0 2
[junit4:junit4]   2> 4795 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[333]} 0 1
[junit4:junit4]   2> 4797 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[334]} 0 0
[junit4:junit4]   2> 4799 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[335]} 0 0
[junit4:junit4]   2> 4802 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[336]} 0 1
[junit4:junit4]   2> 4804 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[337]} 0 0
[junit4:junit4]   2> 4806 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[338]} 0 0
[junit4:junit4]   2> 4809 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[339]} 0 1
[junit4:junit4]   2> 4811 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[340]} 0 0
[junit4:junit4]   2> 4813 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[341]} 0 0
[junit4:junit4]   2> 4815 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[342]} 0 0
[junit4:junit4]   2> 4818 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[343]} 0 1
[junit4:junit4]   2> 4820 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[344]} 0 0
[junit4:junit4]   2> 4822 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[345]} 0 0
[junit4:junit4]   2> 4824 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[346]} 0 0
[junit4:junit4]   2> 4826 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[347]} 0 0
[junit4:junit4]   2> 4828 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[348]} 0 0
[junit4:junit4]   2> 4831 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[349]} 0 1
[junit4:junit4]   2> 4833 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[350]} 0 1
[junit4:junit4]   2> 4835 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[351]} 0 0
[junit4:junit4]   2> 4837 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[352]} 0 0
[junit4:junit4]   2> 4839 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[353]} 0 0
[junit4:junit4]   2> 4841 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[354]} 0 0
[junit4:junit4]   2> 4844 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[355]} 0 1
[junit4:junit4]   2> 4846 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[356]} 0 0
[junit4:junit4]   2> 4848 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[357]} 0 0
[junit4:junit4]   2> 4850 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[358]} 0 0
[junit4:junit4]   2> 4852 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[359]} 0 0
[junit4:junit4]   2> 4855 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[360]} 0 1
[junit4:junit4]   2> 4857 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[361]} 0 1
[junit4:junit4]   2> 4859 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[362]} 0 0
[junit4:junit4]   2> 4861 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[363]} 0 0
[junit4:junit4]   2> 4863 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[364]} 0 0
[junit4:junit4]   2> 4866 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[365]} 0 1
[junit4:junit4]   2> 4868 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[366]} 0 0
[junit4:junit4]   2> 4870 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[367]} 0 0
[junit4:junit4]   2> 4872 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[368]} 0 0
[junit4:junit4]   2> 4874 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[369]} 0 0
[junit4:junit4]   2> 4877 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[370]} 0 0
[junit4:junit4]   2> 4879 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[371]} 0 0
[junit4:junit4]   2> 4881 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[372]} 0 0
[junit4:junit4]   2> 4883 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[373]} 0 0
[junit4:junit4]   2> 4885 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[374]} 0 0
[junit4:junit4]   2> 4888 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[375]} 0 1
[junit4:junit4]   2> 4890 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[376]} 0 1
[junit4:junit4]   2> 4892 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[377]} 0 1
[junit4:junit4]   2> 4894 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[378]} 0 0
[junit4:junit4]   2> 4896 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[379]} 0 0
[junit4:junit4]   2> 4899 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[380]} 0 1
[junit4:junit4]   2> 4901 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[381]} 0 1
[junit4:junit4]   2> 4903 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[382]} 0 0
[junit4:junit4]   2> 4905 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[383]} 0 0
[junit4:junit4]   2> 4907 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[384]} 0 0
[junit4:junit4]   2> 4909 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[385]} 0 0
[junit4:junit4]   2> 4911 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[386]} 0 0
[junit4:junit4]   2> 4914 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[387]} 0 1
[junit4:junit4]   2> 4916 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[388]} 0 0
[junit4:junit4]   2> 4927 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[389]} 0 0
[junit4:junit4]   2> 4930 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[390]} 0 0
[junit4:junit4]   2> 4932 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[391]} 0 0
[junit4:junit4]   2> 4934 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[392]} 0 0
[junit4:junit4]   2> 4936 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[393]} 0 0
[junit4:junit4]   2> 4939 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[394]} 0 1
[junit4:junit4]   2> 4941 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[395]} 0 1
[junit4:junit4]   2> 4943 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[396]} 0 0
[junit4:junit4]   2> 4945 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[397]} 0 0
[junit4:junit4]   2> 4947 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[398]} 0 0
[junit4:junit4]   2> 4949 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[399]} 0 0
[junit4:junit4]   2> 4951 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[400]} 0 0
[junit4:junit4]   2> 4954 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[401]} 0 1
[junit4:junit4]   2> 4956 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[402]} 0 1
[junit4:junit4]   2> 4958 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[403]} 0 0
[junit4:junit4]   2> 4960 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[404]} 0 0
[junit4:junit4]   2> 4962 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[405]} 0 0
[junit4:junit4]   2> 4964 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[406]} 0 0
[junit4:junit4]   2> 4966 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[407]} 0 0
[junit4:junit4]   2> 4968 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[408]} 0 0
[junit4:junit4]   2> 4971 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[409]} 0 1
[junit4:junit4]   2> 4973 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[410]} 0 1
[junit4:junit4]   2> 4975 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[411]} 0 0
[junit4:junit4]   2> 4977 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[412]} 0 0
[junit4:junit4]   2> 4979 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[413]} 0 0
[junit4:junit4]   2> 4981 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[414]} 0 0
[junit4:junit4]   2> 4983 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[415]} 0 0
[junit4:junit4]   2> 4986 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[416]} 0 1
[junit4:junit4]   2> 4988 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[417]} 0 1
[junit4:junit4]   2> 4990 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[418]} 0 0
[junit4:junit4]   2> 4992 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[419]} 0 0
[junit4:junit4]   2> 4994 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[420]} 0 0
[junit4:junit4]   2> 4996 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[421]} 0 0
[junit4:junit4]   2> 4999 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[422]} 0 1
[junit4:junit4]   2> 5001 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[423]} 0 0
[junit4:junit4]   2> 5003 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[424]} 0 0
[junit4:junit4]   2> 5006 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[425]} 0 0
[junit4:junit4]   2> 5008 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[426]} 0 0
[junit4:junit4]   2> 5011 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[427]} 0 1
[junit4:junit4]   2> 5013 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[428]} 0 0
[junit4:junit4]   2> 5015 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[429]} 0 0
[junit4:junit4]   2> 5017 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[430]} 0 0
[junit4:junit4]   2> 5019 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[431]} 0 0
[junit4:junit4]   2> 5021 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[432]} 0 0
[junit4:junit4]   2> 5024 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[433]} 0 1
[junit4:junit4]   2> 5026 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[434]} 0 0
[junit4:junit4]   2> 5028 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[435]} 0 0
[junit4:junit4]   2> 5030 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[436]} 0 0
[junit4:junit4]   2> 5032 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[437]} 0 0
[junit4:junit4]   2> 5034 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[438]} 0 0
[junit4:junit4]   2> 5036 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[439]} 0 0
[junit4:junit4]   2> 5038 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[440]} 0 0
[junit4:junit4]   2> 5041 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[441]} 0 1
[junit4:junit4]   2> 5043 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[442]} 0 1
[junit4:junit4]   2> 5045 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[443]} 0 1
[junit4:junit4]   2> 5047 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[444]} 0 0
[junit4:junit4]   2> 5049 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[445]} 0 0
[junit4:junit4]   2> 5052 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[446]} 0 0
[junit4:junit4]   2> 5060 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[447]} 0 0
[junit4:junit4]   2> 5063 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[448]} 0 0
[junit4:junit4]   2> 5065 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[449]} 0 0
[junit4:junit4]   2> 5067 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[450]} 0 0
[junit4:junit4]   2> 5069 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[451]} 0 0
[junit4:junit4]   2> 5071 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[452]} 0 0
[junit4:junit4]   2> 5073 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[453]} 0 0
[junit4:junit4]   2> 5075 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[454]} 0 0
[junit4:junit4]   2> 5077 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[455]} 0 0
[junit4:junit4]   2> 5079 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[456]} 0 0
[junit4:junit4]   2> 5082 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[457]} 0 1
[junit4:junit4]   2> 5084 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[458]} 0 1
[junit4:junit4]   2> 5086 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[459]} 0 1
[junit4:junit4]   2> 5088 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[460]} 0 0
[junit4:junit4]   2> 5090 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[461]} 0 0
[junit4:junit4]   2> 5092 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[462]} 0 0
[junit4:junit4]   2> 5094 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[463]} 0 0
[junit4:junit4]   2> 5096 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[464]} 0 0
[junit4:junit4]   2> 5098 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[465]} 0 0
[junit4:junit4]   2> 5100 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[466]} 0 0
[junit4:junit4]   2> 5102 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[467]} 0 0
[junit4:junit4]   2> 5104 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[468]} 0 0
[junit4:junit4]   2> 5106 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[469]} 0 0
[junit4:junit4]   2> 5108 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[470]} 0 0
[junit4:junit4]   2> 5110 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[471]} 0 0
[junit4:junit4]   2> 5112 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[472]} 0 0
[junit4:junit4]   2> 5114 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[473]} 0 0
[junit4:junit4]   2> 5116 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[474]} 0 0
[junit4:junit4]   2> 5118 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[475]} 0 0
[junit4:junit4]   2> 5120 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[476]} 0 0
[junit4:junit4]   2> 5122 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[477]} 0 0
[junit4:junit4]   2> 5124 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[478]} 0 0
[junit4:junit4]   2> 5126 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[479]} 0 0
[junit4:junit4]   2> 5128 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[480]} 0 0
[junit4:junit4]   2> 5130 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[481]} 0 0
[junit4:junit4]   2> 5132 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[482]} 0 0
[junit4:junit4]   2> 5134 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[483]} 0 0
[junit4:junit4]   2> 5136 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[484]} 0 0
[junit4:junit4]   2> 5138 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[485]} 0 0
[junit4:junit4]   2> 5140 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[486]} 0 0
[junit4:junit4]   2> 5142 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[487]} 0 0
[junit4:junit4]   2> 5144 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[488]} 0 0
[junit4:junit4]   2> 5146 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[489]} 0 0
[junit4:junit4]   2> 5148 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[490]} 0 0
[junit4:junit4]   2> 5150 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[491]} 0 0
[junit4:junit4]   2> 5152 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[492]} 0 0
[junit4:junit4]   2> 5154 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[493]} 0 0
[junit4:junit4]   2> 5156 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[494]} 0 0
[junit4:junit4]   2> 5158 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[495]} 0 0
[junit4:junit4]   2> 5160 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[496]} 0 0
[junit4:junit4]   2> 5162 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[497]} 0 0
[junit4:junit4]   2> 5164 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[498]} 0 0
[junit4:junit4]   2> 5173 T125 C9 REQ [collection1] webapp=/solr path=/replication params={command=disableReplication} status=0 QTime=1 
[junit4:junit4]   2> 5177 T123 C9 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5252 T123 C9 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_5,generation=5,filenames=[_2.fnm, _2.fdt, _2.fdx, segments_5, _2_SimpleText_0.pst, _2_nrm.cfs, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 5253 T123 C9 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 5256 T123 C9 oass.SolrIndexSearcher.<init> Opening Searcher@159c304 main
[junit4:junit4]   2> 5256 T123 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5256 T130 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@159c304 main{StandardDirectoryReader(segments_5:9 _2(4.1):C499)}
[junit4:junit4]   2> 5257 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 80
[junit4:junit4]   2> 5259 T123 C9 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=499 status=0 QTime=0 
[junit4:junit4]   2> 5363 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[551]} 0 1
[junit4:junit4]   2> 5365 T133 C10 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5382 T133 C10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_5,generation=5,filenames=[_2.fnm, _2.fdt, _2.fdx, segments_5, _2_SimpleText_0.pst, _2_nrm.cfs, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 5383 T133 C10 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 5384 T133 C10 oass.SolrIndexSearcher.<init> Opening Searcher@1c0f4c7 main
[junit4:junit4]   2> 5385 T133 C10 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5385 T140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c0f4c7 main{StandardDirectoryReader(segments_5:9:nrt _2(4.1):C1)}
[junit4:junit4]   2> 5385 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 20
[junit4:junit4]   2> 5388 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[552]} 0 1
[junit4:junit4]   2> 5390 T133 C10 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5412 T133 C10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_5,generation=5,filenames=[_2.fnm, _2.fdt, _2.fdx, segments_5, _2_SimpleText_0.pst, _2_nrm.cfs, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_6,generation=6,filenames=[_2.fnm, _3_nrm.cfe, _2.fdt, _3.fdx, _3_SimpleText_0.pst, _3.fnm, _2_nrm.cfs, segments_6, _2.fdx, _3_nrm.cfs, _3.fdt, _2_SimpleText_0.pst, _3.si, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 5413 T133 C10 oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4]   2> 5414 T133 C10 oass.SolrIndexSearcher.<init> Opening Searcher@86dbbe main
[junit4:junit4]   2> 5414 T133 C10 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5414 T140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@86dbbe main{StandardDirectoryReader(segments_6:11:nrt _2(4.1):C1 _3(4.1):C1)}
[junit4:junit4]   2> 5415 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 25
[junit4:junit4]   2> 5418 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[553]} 0 1
[junit4:junit4]   2> 5420 T133 C10 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5442 T133 C10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_6,generation=6,filenames=[_2.fnm, _3_nrm.cfe, _2.fdt, _3.fdx, _3_SimpleText_0.pst, _3.fnm, _2_nrm.cfs, segments_6, _2.fdx, _3_nrm.cfs, _3.fdt, _2_SimpleText_0.pst, _3.si, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_7,generation=7,filenames=[_2.fnm, _3_nrm.cfe, _2.fdt, _3.fdx, _4_SimpleText_0.pst, _3_SimpleText_0.pst, segments_7, _3.fnm, _4.fdt, _2_nrm.cfs, _4.si, _4.fnm, _4_nrm.cfs, _4_nrm.cfe, _2.fdx, _3_nrm.cfs, _4.fdx, _3.fdt, _2_SimpleText_0.pst, _3.si, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 5443 T133 C10 oasc.SolrDeletionPolicy.updateCommits newest commit = 7
[junit4:junit4]   2> 5444 T133 C10 oass.SolrIndexSearcher.<init> Opening Searcher@135924c main
[junit4:junit4]   2> 5444 T133 C10 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5444 T140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@135924c main{StandardDirectoryReader(segments_7:13:nrt _2(4.1):C1 _3(4.1):C1 _4(4.1):C1)}
[junit4:junit4]   2> 5445 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 25
[junit4:junit4]   2> 5448 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[554]} 0 1
[junit4:junit4]   2> 5450 T133 C10 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5475 T133 C10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_7,generation=7,filenames=[_2.fnm, _3_nrm.cfe, _2.fdt, _3.fdx, _4_SimpleText_0.pst, _3_SimpleText_0.pst, segments_7, _3.fnm, _4.fdt, _2_nrm.cfs, _4.si, _4.fnm, _4_nrm.cfs, _4_nrm.cfe, _2.fdx, _3_nrm.cfs, _4.fdx, _3.fdt, _2_SimpleText_0.pst, _3.si, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_8,generation=8,filenames=[_3_nrm.cfe, segments_8, _5_nrm.cfs, _2.fdt, _4_SimpleText_0.pst, _3.fnm, _2_nrm.cfs, _4.fdt, _5_nrm.cfe, _3_nrm.cfs, _5.fnm, _5_SimpleText_0.pst, _3.si, _2_nrm.cfe, _2.fnm, _3.fdx, _3_SimpleText_0.pst, _5.fdx, _4.si, _4.fnm, _5.si, _4_nrm.cfs, _4_nrm.cfe, _2.fdx, _3.fdt, _4.fdx, _2_SimpleText_0.pst, _5.fdt, _2.si]
[junit4:junit4]   2> 5476 T133 C10 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
[junit4:junit4]   2> 5478 T133 C10 oass.SolrIndexSearcher.<init> Opening Searcher@bcf083 main
[junit4:junit4]   2> 5478 T140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bcf083 main{StandardDirectoryReader(segments_8:15:nrt _2(4.1):C1 _3(4.1):C1 _4(4.1):C1 _5(4.1):C1)}
[junit4:junit4]   2> 5478 T133 C10 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5481 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 31
[junit4:junit4]   2> 5485 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[555]} 0 2
[junit4:junit4]   2> 5487 T133 C10 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5512 T133 C10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_8,generation=8,filenames=[_3_nrm.cfe, segments_8, _5_nrm.cfs, _2.fdt, _4_SimpleText_0.pst, _3.fnm, _2_nrm.cfs, _4.fdt, _5_nrm.cfe, _3_nrm.cfs, _5.fnm, _5_SimpleText_0.pst, _3.si, _2_nrm.cfe, _2.fnm, _3.fdx, _3_SimpleText_0.pst, _5.fdx, _4.si, _4.fnm, _5.si, _4_nrm.cfs, _4_nrm.cfe, _2.fdx, _3.fdt, _4.fdx, _2_SimpleText_0.pst, _5.fdt, _2.si]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index,segFN=segments_9,generation=9,filenames=[_3_nrm.cfe, _5_nrm.cfs, _2.fdt, _4_SimpleText_0.pst, _3.fnm, _2_nrm.cfs, _4.fdt, _5_nrm.cfe, _6_nrm.cfe, _6.fdt, _3_nrm.cfs, _5.fnm, _5_SimpleText_0.pst, _3.si, _2_nrm.cfe, _2.fnm, segments_9, _3.fdx, _3_SimpleText_0.pst, _6_nrm.cfs, _5.fdx, _4.si, _4.fnm, _5.si, _6.fnm, _4_nrm.cfs, _4_nrm.cfe, _6.si, _2.fdx, _3.fdt, _4.fdx, _2_SimpleText_0.pst, _6_SimpleText_0.pst, _5.fdt, _2.si, _6.fdx]
[junit4:junit4]   2> 5513 T133 C10 oasc.SolrDeletionPolicy.updateCommits newest commit = 9
[junit4:junit4]   2> 5514 T133 C10 oass.SolrIndexSearcher.<init> Opening Searcher@2c2ddd main
[junit4:junit4]   2> 5515 T133 C10 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5515 T140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c2ddd main{StandardDirectoryReader(segments_9:17:nrt _2(4.1):C1 _3(4.1):C1 _4(4.1):C1 _5(4.1):C1 _6(4.1):C1)}
[junit4:junit4]   2> 5515 T133 C10 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 28
[junit4:junit4]   2> 5518 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=id:555} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 5619 T125 C9 REQ [collection1] webapp=/solr path=/replication params={command=enableReplication} status=0 QTime=0 
[junit4:junit4]   2> 5622 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=id:555} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 5725 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=id:555} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 5791 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=indexversion&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5791 T141 oash.SnapPuller.fetchLatestIndex Master's generation: 5
[junit4:junit4]   2> 5792 T141 oash.SnapPuller.fetchLatestIndex Slave's generation: 9
[junit4:junit4]   2> 5792 T141 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 5795 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&generation=5&command=filelist&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5795 T141 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 8
[junit4:junit4]   2> 5796 T141 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114115007 fullCopy=true
[junit4:junit4]   2> 5799 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=5&command=filecontent&qt=/replication&file=_2.fnm} status=0 QTime=0 
[junit4:junit4]   2> 5803 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=5&command=filecontent&qt=/replication&file=_2.fdt} status=0 QTime=1 
[junit4:junit4]   2> 5806 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=5&command=filecontent&qt=/replication&file=segments_5} status=0 QTime=0 
[junit4:junit4]   2> 5810 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=5&command=filecontent&qt=/replication&file=_2.fdx} status=0 QTime=0 
[junit4:junit4]   2> 5813 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=5&command=filecontent&qt=/replication&file=_2_nrm.cfs} status=0 QTime=0 
[junit4:junit4]   2> 5817 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=5&command=filecontent&qt=/replication&file=_2_SimpleText_0.pst} status=0 QTime=0 
[junit4:junit4]   2> 5821 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=5&command=filecontent&qt=/replication&file=_2.si} status=0 QTime=0 
[junit4:junit4]   2> 5824 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=5&command=filecontent&qt=/replication&file=_2_nrm.cfe} status=0 QTime=0 
[junit4:junit4]   2> 5826 T141 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 5826 T141 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20120926114115007
[junit4:junit4]   2> 5828 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=id:555} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 5829 T141 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 5829 T141 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 5829 T141 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 5833 T141 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index/ new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114115007
[junit4:junit4]   2> 5834 T141 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114115007 forceNew:true
[junit4:junit4]   2> 5837 T141 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114115007,segFN=segments_5,generation=5,filenames=[_2.fnm, _2.fdt, _2.fdx, segments_5, _2_nrm.cfs, _2_SimpleText_0.pst, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 5837 T141 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 5837 T141 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 5839 T141 oass.SolrIndexSearcher.<init> Opening Searcher@28c141 main
[junit4:junit4]   2> 5839 T140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28c141 main{StandardDirectoryReader(segments_5:9:nrt _2(4.1):C499)}
[junit4:junit4]   2> 5845 T140 oasc.CachingDirectoryFactory.close Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index
[junit4:junit4]   2> 5845 T140 oash.SnapPuller$2.onClose removing old index directory ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index
[junit4:junit4]   2> 5852 T141 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5852 T141 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5949 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=id:555} hits=0 status=0 QTime=19 
[junit4:junit4]   2> 6053 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=499 status=0 QTime=1 
[junit4:junit4]   2> 6156 T123 C9 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=499 status=0 QTime=1 
[junit4:junit4]   2> 6158 T123 C9 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 6159 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4:junit4]   2> 6161 T123 C9 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 6262 T123 C9 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=3
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_5,generation=5,filenames=[_2.fnm, _2.fdt, _2.fdx, segments_5, _2_SimpleText_0.pst, _2_nrm.cfs, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_6,generation=6,filenames=[segments_6]
[junit4:junit4]   2> 6263 T123 C9 oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4]   2> 6265 T123 C9 oass.SolrIndexSearcher.<init> Opening Searcher@1cc3faa main
[junit4:junit4]   2> 6265 T123 C9 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 6265 T130 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cc3faa main{StandardDirectoryReader(segments_6:10)}
[junit4:junit4]   2> 6266 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 105
[junit4:junit4]   2> 6268 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=499 status=0 QTime=0 
[junit4:junit4]   2> 6371 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=499 status=0 QTime=0 
[junit4:junit4]   2> 6474 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=499 status=0 QTime=0 
[junit4:junit4]   2> 6577 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=499 status=0 QTime=0 
[junit4:junit4]   2> 6680 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=499 status=0 QTime=0 
[junit4:junit4]   2> 6783 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=499 status=0 QTime=0 
[junit4:junit4]   2> 6791 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&command=indexversion&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6791 T141 oash.SnapPuller.fetchLatestIndex Master's generation: 6
[junit4:junit4]   2> 6792 T141 oash.SnapPuller.fetchLatestIndex Slave's generation: 5
[junit4:junit4]   2> 6792 T141 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 6794 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=javabin&generation=6&command=filelist&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6794 T141 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 6795 T141 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114116006 fullCopy=false
[junit4:junit4]   2> 6797 T124 C9 REQ [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&generation=6&command=filecontent&qt=/replication&file=segments_6} status=0 QTime=0 
[junit4:junit4]   2> 6798 T141 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 6823 T141 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 6823 T141 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 6823 T141 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4]   2> 6824 T141 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114115007 forceNew:true
[junit4:junit4]   2> 6826 T141 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114115007,segFN=segments_5,generation=5,filenames=[_2.fnm, _2.fdt, _2.fdx, segments_5, _2_nrm.cfs, _2_SimpleText_0.pst, _2.si, _2_nrm.cfe]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114115007,segFN=segments_6,generation=6,filenames=[segments_6]
[junit4:junit4]   2> 6827 T141 oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4]   2> 6827 T141 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 6828 T141 oass.SolrIndexSearcher.<init> Opening Searcher@14401c0 main
[junit4:junit4]   2> 6828 T140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14401c0 main{StandardDirectoryReader(segments_6:10:nrt)}
[junit4:junit4]   2> 6828 T140 oasc.CachingDirectoryFactory.close Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114115007
[junit4:junit4]   2> 6829 T141 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 6829 T141 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 6829 T141 oash.SnapPuller.fetchLatestIndex removing temporary index download directory ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869496/slave/collection1/data/index.20120926114116006
[junit4:junit4]   2> 6886 T133 C10 REQ [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 6990 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4:junit4]   2> 6992 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4:junit4]   2> 6994 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4:junit4]   2> 6996 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4:junit4]   2> 6998 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4:junit4]   2> 7000 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4:junit4]   2> 7003 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4:junit4]   2> 7004 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4:junit4]   2> 7007 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 1
[junit4:junit4]   2> 7009 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 1
[junit4:junit4]   2> 7011 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4:junit4]   2> 7013 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4:junit4]   2> 7015 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4:junit4]   2> 7017 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4:junit4]   2> 7019 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4:junit4]   2> 7021 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 0
[junit4:junit4]   2> 7024 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 1
[junit4:junit4]   2> 7026 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4:junit4]   2> 7028 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
[junit4:junit4]   2> 7030 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4:junit4]   2> 7032 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4:junit4]   2> 7034 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 0
[junit4:junit4]   2> 7037 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 1
[junit4:junit4]   2> 7039 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 0
[junit4:junit4]   2> 7041 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 0
[junit4:junit4]   2> 7043 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 0
[junit4:junit4]   2> 7045 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 0
[junit4:junit4]   2> 7047 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
[junit4:junit4]   2> 7049 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 0
[junit4:junit4]   2> 7051 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4:junit4]   2> 7054 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 1
[junit4:junit4]   2> 7056 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 1
[junit4:junit4]   2> 7058 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 0
[junit4:junit4]   2> 7060 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
[junit4:junit4]   2> 7062 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
[junit4:junit4]   2> 7064 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
[junit4:junit4]   2> 7066 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4:junit4]   2> 7068 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4:junit4]   2> 7071 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 1
[junit4:junit4]   2> 7073 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 1
[junit4:junit4]   2> 7075 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 1
[junit4:junit4]   2> 7081 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4:junit4]   2> 7083 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4:junit4]   2> 7085 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4:junit4]   2> 7087 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 0
[junit4:junit4]   2> 7089 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 0
[junit4:junit4]   2> 7091 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4:junit4]   2> 7093 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4:junit4]   2> 7096 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 1
[junit4:junit4]   2> 7098 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 1
[junit4:junit4]   2> 7100 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 1
[junit4:junit4]   2> 7102 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 1
[junit4:junit4]   2> 7104 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 1
[junit4:junit4]   2> 7106 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 1
[junit4:junit4]   2> 7108 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 1
[junit4:junit4]   2> 7110 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 1
[junit4:junit4]   2> 7112 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 1
[junit4:junit4]   2> 7114 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 1
[junit4:junit4]   2> 7116 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4:junit4]   2> 7118 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 0
[junit4:junit4]   2> 7120 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 1
[junit4:junit4]   2> 7122 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 1
[junit4:junit4]   2> 7124 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 0
[junit4:junit4]   2> 7126 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 0
[junit4:junit4]   2> 7128 T123 C9 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 1
[junit4:junit4

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

: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_i,generation=18,filenames=[_a.fdt, _a.si, _a_SimpleText_0.pst, _a.fdx, _a_nrm.cfe, _a.fnm, segments_i, _a_nrm.cfs, _a_Memory_0.ram]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1348630869212/master/collection1/data/index,segFN=segments_j,generation=19,filenames=[_b.fdt, _b_nrm.cfs, _b_nrm.cfe, _b_SimpleText_0.pst, _b.fnm, segments_j, _b.fdx, _b.si]
[junit4:junit4]   2> 26524 T281 C22 oasc.SolrDeletionPolicy.updateCommits newest commit = 19
[junit4:junit4]   2> 26533 T281 C22 oass.SolrIndexSearcher.<init> Opening Searcher@247329 main
[junit4:junit4]   2> 26534 T281 C22 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 26534 T288 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@247329 main{StandardDirectoryReader(segments_j:35 _b(4.1):C493)}
[junit4:junit4]   2> 26535 T281 C22 UPDATE [collection1] webapp=/solr path=/update params={softCommit=false&wt=javabin&commit=true&waitSearcher=true&version=2} {commit=} 0 54
[junit4:junit4]   2> 26541 T283 C22 REQ [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=1 
[junit4:junit4]   2> 26546 T282 C22 REQ [collection1] webapp=/solr path=/replication params={command=backup} status=0 QTime=7 
[junit4:junit4]   2> 26746 T282 C22 REQ [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=2 
[junit4:junit4]   2> 26789 T277 oash.SnapPuller.fetchLatestIndex SEVERE Master at: http://127.0.0.1:57395/solr is not available. Index fetch failed. Exception: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:57395/solr
[junit4:junit4]   2> 27753 T282 C22 REQ [collection1] webapp=/solr path=/replication params={command=backup} status=0 QTime=0 
[junit4:junit4]   2> 27755 T283 C22 REQ [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=2 
[junit4:junit4]   2> 27789 T277 oash.SnapPuller.fetchLatestIndex SEVERE Master at: http://127.0.0.1:57395/solr is not available. Index fetch failed. Exception: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:57395/solr
[junit4:junit4]   2> 27957 T283 C22 REQ [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=1 
[junit4:junit4]   2> 27960 T75 oas.SolrTestCaseJ4.tearDown ###Ending test
[junit4:junit4]   1> replicate slave to master
[junit4:junit4]   2> 27963 T75 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2048570
[junit4:junit4]   2> 27964 T75 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@c26ba
[junit4:junit4]   2> 27964 T75 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 27964 T75 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=493,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 27965 T75 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 27966 T75 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 27967 T75 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 28019 T75 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=6016426
[junit4:junit4]   2> 28019 T75 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@f72340
[junit4:junit4]   2> 28020 T75 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 28020 T75 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 28020 T75 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 28021 T75 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 28022 T75 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 28078 T75 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 149092 T75 oas.SolrTestCaseJ4.endTrackingSearchers SEVERE ERROR: SolrIndexSearcher opens=61 closes=60
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {name=PostingsFormat(name=SimpleText), newname=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=SimpleText)}, sim=DefaultSimilarity, locale=ca_ES, timezone=Asia/Ulaanbaatar
[junit4:junit4]   2> NOTE: Linux 3.2.0-31-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=188713368,total=324009984
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestSort, TestFoldingMultitermQuery, TestStressLucene, EchoParamsTest, IndexReaderFactoryTest, StandardRequestHandlerTest, TestBinaryField, DirectSolrSpellCheckerTest, TestReplicationHandler]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestReplicationHandler -Dtests.seed=AA3D9D6756B8420D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ca_ES -Dtests.timezone=Asia/Ulaanbaatar -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   0.00s J1 | TestReplicationHandler (suite) <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=61 closes=60
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([AA3D9D6756B8420D]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:241)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:96)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] Completed on J1 in 149.15s, 1 test, 1 failure <<< FAILURES!

[...truncated 551 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:32: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:179: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1142: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:815: There were test failures: 231 suites, 955 tests, 1 suite-level error, 8 ignored (3 assumptions)

Total time: 23 minutes 39 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b51 -client -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message