You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/05/30 16:54:51 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0-ea-b91) - Build #
5903 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/5903/
Java: 64bit/jdk1.8.0-ea-b91 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Thu May 30 22:48:24 CIT 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Thu May 30 22:48:24 CIT 2013
at __randomizedtesting.SeedInfo.seed([28FFF99E409E632D:F354F95845B60A9E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1473)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:777)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 9605 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4:junit4] 2> 689010 T2868 oas.SolrTestCaseJ4.setUp ###Starting doTestDetails
[junit4:junit4] 2> 689014 T2868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 689019 T2868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:36291
[junit4:junit4] 2> 689020 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 689020 T2868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 689020 T2868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master
[junit4:junit4] 2> 689020 T2868 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/solr.xml
[junit4:junit4] 2> 689021 T2868 oasc.CoreContainer.<init> New CoreContainer 1723799319
[junit4:junit4] 2> 689021 T2868 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 689021 T2868 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/'
[junit4:junit4] 2> 689021 T2868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/'
[junit4:junit4] 2> 689069 T2868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 689069 T2868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 689070 T2868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 689070 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 689070 T2868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 689070 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 689070 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 689071 T2868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 689071 T2868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 689071 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 689074 T2868 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 689076 T2878 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1
[junit4:junit4] 2> 689077 T2878 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/'
[junit4:junit4] 2> 689097 T2878 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 689122 T2878 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 689122 T2878 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 689124 T2878 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 689133 T2878 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 689134 T2878 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 689134 T2878 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data/
[junit4:junit4] 2> 689134 T2878 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 689134 T2878 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 689135 T2878 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data
[junit4:junit4] 2> 689135 T2878 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data/index/
[junit4:junit4] 2> 689135 T2878 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 689135 T2878 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data/index
[junit4:junit4] 2> 689136 T2878 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d371b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc37fcb),segFN=segments_1,generation=1}
[junit4:junit4] 2> 689136 T2878 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 689137 T2878 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 689137 T2878 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 689137 T2878 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 689137 T2878 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 689137 T2878 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 689137 T2878 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 689139 T2878 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 689140 T2878 oass.SolrIndexSearcher.<init> Opening Searcher@181987f4 main
[junit4:junit4] 2> 689141 T2878 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 689141 T2878 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 689141 T2878 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4] 2> 689141 T2878 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 689141 T2878 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 689142 T2879 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@181987f4 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 689142 T2868 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 689143 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 689143 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 689148 T2868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 689150 T2868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:37797
[junit4:junit4] 2> 689150 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 689150 T2868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 689151 T2868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave
[junit4:junit4] 2> 689151 T2868 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/solr.xml
[junit4:junit4] 2> 689151 T2868 oasc.CoreContainer.<init> New CoreContainer 190545480
[junit4:junit4] 2> 689151 T2868 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 689152 T2868 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/'
[junit4:junit4] 2> 689152 T2868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/'
[junit4:junit4] 2> 689185 T2868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 689185 T2868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 689185 T2868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 689185 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 689186 T2868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 689186 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 689186 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 689186 T2868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 689186 T2868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 689187 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 689189 T2868 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 689192 T2890 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1
[junit4:junit4] 2> 689192 T2890 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/'
[junit4:junit4] 2> 689218 T2890 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 689255 T2890 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 689256 T2890 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 689257 T2890 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 689266 T2890 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 689266 T2890 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 689267 T2890 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/
[junit4:junit4] 2> 689267 T2890 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 689267 T2890 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 689267 T2890 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data
[junit4:junit4] 2> 689267 T2890 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index/
[junit4:junit4] 2> 689268 T2890 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 689268 T2890 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index
[junit4:junit4] 2> 689269 T2890 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bad17fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@287c51d7),segFN=segments_1,generation=1}
[junit4:junit4] 2> 689269 T2890 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 689269 T2890 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 689270 T2890 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 689270 T2890 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 689270 T2890 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 689270 T2890 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 689270 T2890 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 689271 T2890 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 689273 T2890 oass.SolrIndexSearcher.<init> Opening Searcher@634f4339 main
[junit4:junit4] 2> 689273 T2890 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 689273 T2890 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 689274 T2890 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4] 2> 689274 T2890 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 689275 T2891 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@634f4339 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 689275 T2868 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 689275 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 689276 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C2070 name=collection1 org.apache.solr.core.SolrCore@6dcdeeb7
[junit4:junit4] 2> 689286 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=3
[junit4:junit4] 2> 689294 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&version=2&wt=javabin&command=details} status=0 QTime=1
[junit4:junit4] 2> 689300 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&version=2&wt=javabin&command=details} status=0 QTime=0
[junit4:junit4] 2> ASYNC NEW_CORE C2071 name=collection1 org.apache.solr.core.SolrCore@2535ea3
[junit4:junit4] 2> 689301 T2886 C2071 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&version=2&wt=javabin&command=details} status=0 QTime=3
[junit4:junit4] 2> 689304 T2877 C2070 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d371b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc37fcb),segFN=segments_1,generation=1}
[junit4:junit4] 2> 689305 T2877 C2070 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 689305 T2877 C2070 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0]} 0 2
[junit4:junit4] 2> 689308 T2874 C2070 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 689309 T2874 C2070 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d371b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc37fcb),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d371b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc37fcb),segFN=segments_2,generation=2}
[junit4:junit4] 2> 689309 T2874 C2070 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 689310 T2874 C2070 oass.SolrIndexSearcher.<init> Opening Searcher@22a69df8 main
[junit4:junit4] 2> 689310 T2874 C2070 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 689310 T2879 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@22a69df8 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4] 2> 689310 T2874 C2070 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&softCommit=false&version=2&wt=javabin&commit=true} {commit=} 0 2
[junit4:junit4] 2> 689313 T2894 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4:junit4] 2> 689313 T2894 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4] 2> 689315 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&wt=javabin&command=indexversion} status=0 QTime=0
[junit4:junit4] 2> 689315 T2894 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4] 2> 689315 T2894 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4] 2> 689315 T2894 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 689319 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&version=2&wt=javabin&command=filelist} status=0 QTime=2
[junit4:junit4] 2> 689319 T2894 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4:junit4] 2> 689320 T2894 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751714
[junit4:junit4] 2> 689320 T2894 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@7e2b569f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ad82692) fullCopy=false
[junit4:junit4] 2> 689322 T2877 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689324 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689327 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689330 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=1
[junit4:junit4] 2> 689332 T2877 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Asserting_0.doc&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689335 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Asserting_0.tim&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689337 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689340 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvm&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689342 T2877 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvd&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689345 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Asserting_0.tip&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689346 T2894 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 689347 T2894 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 689347 T2894 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 689348 T2894 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bad17fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@287c51d7),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bad17fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@287c51d7),segFN=segments_2,generation=2}
[junit4:junit4] 2> 689348 T2894 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 689348 T2894 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 689349 T2894 oass.SolrIndexSearcher.<init> Opening Searcher@6adb3709 main
[junit4:junit4] 2> 689349 T2891 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6adb3709 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4] 2> 689349 T2894 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751714 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751714;done=true>>]
[junit4:junit4] 2> 689349 T2894 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751714
[junit4:junit4] 2> 689349 T2894 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751714
[junit4:junit4] 2> 689349 T2887 C2071 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&command=fetchindex&masterUrl=http://127.0.0.1:36291/solr/replication} status=0 QTime=37
[junit4:junit4] 2> 689355 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&version=2&wt=javabin&command=details} status=0 QTime=1
[junit4:junit4] 2> 689355 T2888 C2071 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&version=2&wt=javabin&command=details} status=0 QTime=3
[junit4:junit4] 2> 689358 T2876 C2070 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1]} 0 1
[junit4:junit4] 2> 689360 T2877 C2070 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 689363 T2877 C2070 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d371b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc37fcb),segFN=segments_2,generation=2}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d371b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc37fcb),segFN=segments_3,generation=3}
[junit4:junit4] 2> 689363 T2877 C2070 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4:junit4] 2> 689364 T2877 C2070 oass.SolrIndexSearcher.<init> Opening Searcher@1cc06160 main
[junit4:junit4] 2> 689364 T2877 C2070 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 689364 T2879 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cc06160 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4] 2> 689364 T2877 C2070 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&softCommit=false&version=2&wt=javabin&commit=true} {commit=} 0 4
[junit4:junit4] 2> 689366 T2897 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4:junit4] 2> 689366 T2897 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4] 2> 689368 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&wt=javabin&command=indexversion} status=0 QTime=1
[junit4:junit4] 2> 689368 T2897 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4:junit4] 2> 689368 T2897 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4:junit4] 2> 689368 T2897 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 689371 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&version=2&wt=javabin&command=filelist} status=0 QTime=1
[junit4:junit4] 2> 689371 T2897 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 19
[junit4:junit4] 2> 689372 T2897 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751767
[junit4:junit4] 2> 689372 T2897 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d5ac7d4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26dea621) fullCopy=false
[junit4:junit4] 2> 689374 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Asserting_0.doc&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689375 T2897 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Asserting_0.doc because it already exists
[junit4:junit4] 2> 689375 T2897 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Asserting_0.tim because it already exists
[junit4:junit4] 2> 689377 T2877 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.nvd&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689377 T2897 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Asserting_0.tip because it already exists
[junit4:junit4] 2> 689377 T2897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvd because it already exists
[junit4:junit4] 2> 689379 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689382 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Asserting_0.tip&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689385 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fnm&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689388 T2877 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdx&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=1
[junit4:junit4] 2> 689388 T2897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4:junit4] 2> 689388 T2897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4:junit4] 2> 689389 T2897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4:junit4] 2> 689391 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Asserting_0.tim&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=1
[junit4:junit4] 2> 689392 T2897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4:junit4] 2> 689394 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.nvm&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689396 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689399 T2877 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdt&generation=3&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689399 T2897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvm because it already exists
[junit4:junit4] 2> 689399 T2897 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 689401 T2897 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 689401 T2897 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 689402 T2897 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4] 2> 689403 T2897 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bad17fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@287c51d7),segFN=segments_2,generation=2}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bad17fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@287c51d7),segFN=segments_3,generation=3}
[junit4:junit4] 2> 689404 T2897 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4:junit4] 2> 689404 T2897 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 689404 T2897 oass.SolrIndexSearcher.<init> Opening Searcher@69293144 main
[junit4:junit4] 2> 689405 T2891 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@69293144 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4] 2> 689405 T2897 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751767 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751767;done=true>>]
[junit4:junit4] 2> 689405 T2897 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751767
[junit4:junit4] 2> 689405 T2897 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751767
[junit4:junit4] 2> 689405 T2889 C2071 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&command=fetchindex&masterUrl=http://127.0.0.1:36291/solr/replication} status=0 QTime=40
[junit4:junit4] 2> 689411 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&version=2&wt=javabin&command=details} status=0 QTime=1
[junit4:junit4] 2> 689411 T2886 C2071 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&version=2&wt=javabin&command=details} status=0 QTime=3
[junit4:junit4] 2> 689414 T2875 C2070 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2]} 0 1
[junit4:junit4] 2> 689416 T2876 C2070 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 689417 T2876 C2070 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d371b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc37fcb),segFN=segments_2,generation=2}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d371b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc37fcb),segFN=segments_3,generation=3}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d371b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc37fcb),segFN=segments_4,generation=4}
[junit4:junit4] 2> 689417 T2876 C2070 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4:junit4] 2> 689418 T2876 C2070 oass.SolrIndexSearcher.<init> Opening Searcher@3572e1a4 main
[junit4:junit4] 2> 689418 T2876 C2070 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 689418 T2879 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3572e1a4 main{StandardDirectoryReader(segments_4:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4:junit4] 2> 689418 T2876 C2070 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&softCommit=false&version=2&wt=javabin&commit=true} {commit=} 0 2
[junit4:junit4] 2> 689420 T2899 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4:junit4] 2> 689420 T2899 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4] 2> 689422 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&wt=javabin&command=indexversion} status=0 QTime=1
[junit4:junit4] 2> 689422 T2899 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4:junit4] 2> 689422 T2899 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4:junit4] 2> 689422 T2899 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 689424 T2877 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&version=2&wt=javabin&command=filelist} status=0 QTime=0
[junit4:junit4] 2> 689425 T2899 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 28
[junit4:junit4] 2> 689426 T2899 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751820
[junit4:junit4] 2> 689426 T2899 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@2b1eb0cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@545d4de) fullCopy=false
[junit4:junit4] 2> 689428 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdt&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689429 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Asserting_0.doc because it already exists
[junit4:junit4] 2> 689429 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Asserting_0.tim because it already exists
[junit4:junit4] 2> 689429 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvd because it already exists
[junit4:junit4] 2> 689429 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Asserting_0.tip because it already exists
[junit4:junit4] 2> 689429 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Asserting_0.tip because it already exists
[junit4:junit4] 2> 689429 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdx because it already exists
[junit4:junit4] 2> 689429 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4:junit4] 2> 689431 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Asserting_0.tim&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689433 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Asserting_0.tip&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689434 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Asserting_0.tim because it already exists
[junit4:junit4] 2> 689436 T2877 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.nvd&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689436 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.nvm because it already exists
[junit4:junit4] 2> 689437 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.si because it already exists
[junit4:junit4] 2> 689437 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvm because it already exists
[junit4:junit4] 2> 689439 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.nvm&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689441 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fnm&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689443 T2874 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Asserting_0.doc&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689444 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Asserting_0.doc because it already exists
[junit4:junit4] 2> 689444 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.nvd because it already exists
[junit4:junit4] 2> 689444 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fnm because it already exists
[junit4:junit4] 2> 689444 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4:junit4] 2> 689444 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4:junit4] 2> 689444 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4:junit4] 2> 689446 T2877 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdx&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689447 T2899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdt because it already exists
[junit4:junit4] 2> 689449 T2876 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_4&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689451 T2875 C2070 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.si&generation=4&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 689452 T2899 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 689453 T2899 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 689453 T2899 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 689453 T2899 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4] 2> 689455 T2899 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bad17fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@287c51d7),segFN=segments_3,generation=3}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bad17fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@287c51d7),segFN=segments_4,generation=4}
[junit4:junit4] 2> 689455 T2899 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4:junit4] 2> 689455 T2899 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 689456 T2899 oass.SolrIndexSearcher.<init> Opening Searcher@70737b4e main
[junit4:junit4] 2> 689456 T2891 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@70737b4e main{StandardDirectoryReader(segments_4:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4:junit4] 2> 689456 T2899 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751820 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751820;done=true>>]
[junit4:junit4] 2> 689456 T2899 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751820
[junit4:junit4] 2> 689456 T2899 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index.20130530224751820
[junit4:junit4] 2> 689457 T2887 C2071 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&command=fetchindex&masterUrl=http://127.0.0.1:36291/solr/replication} status=0 QTime=38
[junit4:junit4] 2> 689460 T2868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 689461 T2868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50502
[junit4:junit4] 2> 689462 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 689462 T2868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 689462 T2868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater
[junit4:junit4] 2> 689463 T2868 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/solr.xml
[junit4:junit4] 2> 689463 T2868 oasc.CoreContainer.<init> New CoreContainer 557193527
[junit4:junit4] 2> 689463 T2868 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 689463 T2868 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/'
[junit4:junit4] 2> 689463 T2868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/'
[junit4:junit4] 2> 689505 T2868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 689506 T2868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 689506 T2868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 689506 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 689506 T2868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 689506 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 689507 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 689507 T2868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 689507 T2868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 689507 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 689509 T2868 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 689512 T2910 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1
[junit4:junit4] 2> 689513 T2910 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/'
[junit4:junit4] 2> 689533 T2910 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 689558 T2910 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 689558 T2910 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 689560 T2910 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 689568 T2910 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 689569 T2910 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 689569 T2910 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data/
[junit4:junit4] 2> 689570 T2910 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 689570 T2910 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 689570 T2910 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data
[junit4:junit4] 2> 689570 T2910 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data/index/
[junit4:junit4] 2> 689570 T2910 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 689571 T2910 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data/index
[junit4:junit4] 2> 689571 T2910 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@381c9c3a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@63a9250d),segFN=segments_1,generation=1}
[junit4:junit4] 2> 689572 T2910 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 689572 T2910 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 689572 T2910 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 689572 T2910 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 689573 T2910 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 689573 T2910 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 689573 T2910 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 689574 T2910 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 689575 T2910 oass.SolrIndexSearcher.<init> Opening Searcher@3f6d148c main
[junit4:junit4] 2> 689576 T2910 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 689576 T2910 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 689576 T2910 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4:junit4] 2> 689576 T2910 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4] 2> 689576 T2910 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4] 2> 689576 T2910 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 689577 T2910 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 689577 T2911 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3f6d148c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 689577 T2868 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 689578 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 689578 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C2072 name=collection1 org.apache.solr.core.SolrCore@6dcdeeb7
[junit4:junit4] 2> 689587 T2874 C2072 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&version=2&wt=javabin&command=details} status=0 QTime=0
[junit4:junit4] 2> ASYNC NEW_CORE C2073 name=collection1 org.apache.solr.core.SolrCore@3375099b
[junit4:junit4] 2> 689588 T2906 C2073 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&version=2&wt=javabin&command=details} status=0 QTime=4
[junit4:junit4] 2> 689607 T2877 C2072 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&wt=javabin&command=indexversion} status=0 QTime=0
[junit4:junit4] 2> 689608 T2892 oash.SnapPuller.fetchLatestIndex Slave in sync with master.
[junit4:junit4] 2> 689609 T2868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=557193527
[junit4:junit4] 2> 689610 T2868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3375099b
[junit4:junit4] 2> 689610 T2868 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> 689610 T2868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 689610 T2868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 689611 T2868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 689611 T2868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 689611 T2868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 689612 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data;done=false>>]
[junit4:junit4] 2> 689612 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data
[junit4:junit4] 2> 689612 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data/index;done=false>>]
[junit4:junit4] 2> 689612 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271852/repeater/collection1/data/index
[junit4:junit4] 2> 689613 T2868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 689665 T2868 oas.SolrTestCaseJ4.tearDown ###Ending doTestDetails
[junit4:junit4] 2> 689686 T2868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1723799319
[junit4:junit4] 2> 689687 T2868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6dcdeeb7
[junit4:junit4] 2> 689688 T2868 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 689688 T2868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 689688 T2868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 689688 T2868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 689689 T2868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 689689 T2868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 689690 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data;done=false>>]
[junit4:junit4] 2> 689690 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data
[junit4:junit4] 2> 689690 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data/index;done=false>>]
[junit4:junit4] 2> 689690 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271405/master/collection1/data/index
[junit4:junit4] 2> 689691 T2868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 689762 T2868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=190545480
[junit4:junit4] 2> 689763 T2868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2535ea3
[junit4:junit4] 2> 689764 T2868 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> 689764 T2868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 689764 T2868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 689765 T2868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 689765 T2868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 689766 T2868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 689766 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index;done=false>>]
[junit4:junit4] 2> 689766 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data/index
[junit4:junit4] 2> 689766 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data;done=false>>]
[junit4:junit4] 2> 689767 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925271540/slave/collection1/data
[junit4:junit4] 2> 689767 T2868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 689824 T2868 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterStartup
[junit4:junit4] 2> 689827 T2868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 689828 T2868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51372
[junit4:junit4] 2> 689829 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 689829 T2868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 689830 T2868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master
[junit4:junit4] 2> 689830 T2868 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/solr.xml
[junit4:junit4] 2> 689830 T2868 oasc.CoreContainer.<init> New CoreContainer 1830988550
[junit4:junit4] 2> 689830 T2868 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 689831 T2868 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/'
[junit4:junit4] 2> 689831 T2868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/'
[junit4:junit4] 2> 689867 T2868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 689867 T2868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 689868 T2868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 689868 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 689868 T2868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 689868 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 689868 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 689868 T2868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 689869 T2868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 689869 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 689872 T2868 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 689874 T2922 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1
[junit4:junit4] 2> 689875 T2922 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/'
[junit4:junit4] 2> 689895 T2922 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 689928 T2922 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 689928 T2922 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 689930 T2922 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 689939 T2922 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 689940 T2922 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 689940 T2922 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/
[junit4:junit4] 2> 689940 T2922 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 689940 T2922 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 689940 T2922 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data
[junit4:junit4] 2> 689941 T2922 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/index/
[junit4:junit4] 2> 689941 T2922 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 689941 T2922 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/index
[junit4:junit4] 2> 689942 T2922 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@24ccab1f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@708ba73d),segFN=segments_1,generation=1}
[junit4:junit4] 2> 689942 T2922 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 689943 T2922 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 689943 T2922 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 689943 T2922 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 689943 T2922 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 689943 T2922 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 689943 T2922 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 689945 T2922 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 689947 T2922 oass.SolrIndexSearcher.<init> Opening Searcher@4e747f57 main
[junit4:junit4] 2> 689947 T2922 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 689947 T2922 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 689948 T2922 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4] 2> 689948 T2922 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 689948 T2922 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 689948 T2923 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e747f57 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 689949 T2868 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 689949 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 689949 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 689953 T2868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 689955 T2868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57276
[junit4:junit4] 2> 689956 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 689956 T2868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 689956 T2868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave
[junit4:junit4] 2> 689957 T2868 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/solr.xml
[junit4:junit4] 2> 689957 T2868 oasc.CoreContainer.<init> New CoreContainer 1570911302
[junit4:junit4] 2> 689957 T2868 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 689957 T2868 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/'
[junit4:junit4] 2> 689957 T2868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/'
[junit4:junit4] 2> 689989 T2868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 689990 T2868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 689990 T2868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 689990 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 689990 T2868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 689991 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 689991 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 689991 T2868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 689991 T2868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 689991 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 689994 T2868 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 689996 T2934 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1
[junit4:junit4] 2> 689996 T2934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/'
[junit4:junit4] 2> 690017 T2934 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 690041 T2934 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 690042 T2934 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 690043 T2934 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 690052 T2934 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 690053 T2934 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 690053 T2934 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/
[junit4:junit4] 2> 690053 T2934 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 690053 T2934 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 690053 T2934 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data
[junit4:junit4] 2> 690054 T2934 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index/
[junit4:junit4] 2> 690054 T2934 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 690054 T2934 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index
[junit4:junit4] 2> 690055 T2934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7a2f3ce8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d8b9b26),segFN=segments_1,generation=1}
[junit4:junit4] 2> 690055 T2934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 690055 T2934 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 690056 T2934 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 690056 T2934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 690056 T2934 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 690056 T2934 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 690056 T2934 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 690057 T2934 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 690059 T2934 oass.SolrIndexSearcher.<init> Opening Searcher@427c3990 main
[junit4:junit4] 2> 690059 T2934 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 690059 T2934 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 690059 T2934 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4] 2> 690059 T2934 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 690060 T2935 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@427c3990 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 690060 T2868 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 690060 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 690061 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 690083 T2868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1570911302
[junit4:junit4] 2> 690084 T2868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7ac8f153
[junit4:junit4] 2> 690085 T2868 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> 690085 T2868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 690085 T2868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 690086 T2868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 690086 T2868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 690086 T2868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 690087 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index;done=false>>]
[junit4:junit4] 2> 690087 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index
[junit4:junit4] 2> 690087 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data;done=false>>]
[junit4:junit4] 2> 690087 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data
[junit4:junit4] 2> 690088 T2868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> ASYNC NEW_CORE C2074 name=collection1 org.apache.solr.core.SolrCore@5a963e9e
[junit4:junit4] 2> 690144 T2918 C2074 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 690145 T2918 C2074 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@24ccab1f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@708ba73d),segFN=segments_1,generation=1}
[junit4:junit4] 2> 690146 T2918 C2074 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 690147 T2918 C2074 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:*} 0 3
[junit4:junit4] 2> 690149 T2919 C2074 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 690150 T2919 C2074 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@24ccab1f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@708ba73d),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@24ccab1f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@708ba73d),segFN=segments_2,generation=2}
[junit4:junit4] 2> 690150 T2919 C2074 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 690151 T2919 C2074 oass.SolrIndexSearcher.<init> Opening Searcher@57118ffd main
[junit4:junit4] 2> 690151 T2919 C2074 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 690151 T2923 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57118ffd main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4:junit4] 2> 690151 T2919 C2074 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&softCommit=false&version=2&wt=javabin&commit=true} {commit=} 0 2
[junit4:junit4] 2> 690173 T2868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1830988550
[junit4:junit4] 2> 690174 T2868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5a963e9e
[junit4:junit4] 2> 690174 T2868 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4] 2> 690175 T2868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 690175 T2868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 690175 T2868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 690176 T2868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 690176 T2868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 690177 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/index;done=false>>]
[junit4:junit4] 2> 690177 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/index
[junit4:junit4] 2> 690177 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data;done=false>>]
[junit4:junit4] 2> 690178 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data
[junit4:junit4] 2> 690178 T2868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 690231 T2868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 690233 T2868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56595
[junit4:junit4] 2> 690234 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 690234 T2868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 690235 T2868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master
[junit4:junit4] 2> 690235 T2868 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/solr.xml
[junit4:junit4] 2> 690235 T2868 oasc.CoreContainer.<init> New CoreContainer 1860721667
[junit4:junit4] 2> 690235 T2868 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 690235 T2868 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/'
[junit4:junit4] 2> 690236 T2868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/'
[junit4:junit4] 2> 690276 T2868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 690277 T2868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 690277 T2868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 690277 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 690277 T2868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 690277 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 690278 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 690278 T2868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 690278 T2868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 690278 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 690281 T2868 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 690284 T2947 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1
[junit4:junit4] 2> 690284 T2947 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/'
[junit4:junit4] 2> 690304 T2947 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 690331 T2947 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 690331 T2947 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 690333 T2947 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 690341 T2947 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 690342 T2947 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 690342 T2947 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/
[junit4:junit4] 2> 690342 T2947 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 690342 T2947 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 690343 T2947 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data
[junit4:junit4] 2> 690343 T2947 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/index/
[junit4:junit4] 2> 690343 T2947 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 690343 T2947 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272220/master/collection1/data/index
[junit4:junit4] 2> 690344 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7ffc609f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a79cbfc),segFN=segments_1,generation=1}
[junit4:junit4] 2> 690344 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 690345 T2947 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 690345 T2947 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 690345 T2947 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 690345 T2947 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 690345 T2947 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 690345 T2947 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 690346 T2947 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 690348 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@2f5835a main
[junit4:junit4] 2> 690348 T2947 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 690348 T2947 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 690348 T2947 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4] 2> 690349 T2947 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7ffc609f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a79cbfc),segFN=segments_1,generation=1}
[junit4:junit4] 2> 690349 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 690349 T2947 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 690350 T2947 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 690350 T2948 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2f5835a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 690350 T2868 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 690351 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 690351 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C2075 name=collection1 org.apache.solr.core.SolrCore@48a18ccf
[junit4:junit4] 2> 690358 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0]} 0 0
[junit4:junit4] 2> 690361 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1]} 0 0
[junit4:junit4] 2> 690364 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2]} 0 0
[junit4:junit4] 2> 690367 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[3]} 0 1
[junit4:junit4] 2> 690369 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[4]} 0 0
[junit4:junit4] 2> 690372 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[5]} 0 0
[junit4:junit4] 2> 690375 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[6]} 0 1
[junit4:junit4] 2> 690377 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[7]} 0 0
[junit4:junit4] 2> 690379 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[8]} 0 0
[junit4:junit4] 2> 690381 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[9]} 0 0
[junit4:junit4] 2> 690383 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[10]} 0 0
[junit4:junit4] 2> 690385 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[11]} 0 0
[junit4:junit4] 2> 690387 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[12]} 0 0
[junit4:junit4] 2> 690389 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[13]} 0 0
[junit4:junit4] 2> 690392 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[14]} 0 1
[junit4:junit4] 2> 690394 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[15]} 0 0
[junit4:junit4] 2> 690396 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[16]} 0 0
[junit4:junit4] 2> 690398 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[17]} 0 0
[junit4:junit4] 2> 690400 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[18]} 0 0
[junit4:junit4] 2> 690402 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[19]} 0 0
[junit4:junit4] 2> 690404 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[20]} 0 0
[junit4:junit4] 2> 690406 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[21]} 0 0
[junit4:junit4] 2> 690408 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[22]} 0 0
[junit4:junit4] 2> 690410 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[23]} 0 0
[junit4:junit4] 2> 690412 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[24]} 0 0
[junit4:junit4] 2> 690415 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[25]} 0 1
[junit4:junit4] 2> 690418 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[26]} 0 0
[junit4:junit4] 2> 690421 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[27]} 0 1
[junit4:junit4] 2> 690424 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[28]} 0 1
[junit4:junit4] 2> 690426 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[29]} 0 0
[junit4:junit4] 2> 690429 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[30]} 0 0
[junit4:junit4] 2> 690432 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[31]} 0 0
[junit4:junit4] 2> 690434 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[32]} 0 0
[junit4:junit4] 2> 690437 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[33]} 0 0
[junit4:junit4] 2> 690440 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[34]} 0 1
[junit4:junit4] 2> 690443 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[35]} 0 1
[junit4:junit4] 2> 690446 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[36]} 0 1
[junit4:junit4] 2> 690449 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[37]} 0 1
[junit4:junit4] 2> 690451 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[38]} 0 0
[junit4:junit4] 2> 690454 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[39]} 0 0
[junit4:junit4] 2> 690456 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[40]} 0 0
[junit4:junit4] 2> 690458 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[41]} 0 0
[junit4:junit4] 2> 690460 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[42]} 0 0
[junit4:junit4] 2> 690462 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[43]} 0 0
[junit4:junit4] 2> 690464 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[44]} 0 0
[junit4:junit4] 2> 690466 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[45]} 0 0
[junit4:junit4] 2> 690469 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[46]} 0 1
[junit4:junit4] 2> 690471 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[47]} 0 1
[junit4:junit4] 2> 690473 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[48]} 0 1
[junit4:junit4] 2> 690475 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[49]} 0 0
[junit4:junit4] 2> 690477 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[50]} 0 0
[junit4:junit4] 2> 690479 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[51]} 0 0
[junit4:junit4] 2> 690481 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[52]} 0 0
[junit4:junit4] 2> 690483 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[53]} 0 0
[junit4:junit4] 2> 690485 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[54]} 0 0
[junit4:junit4] 2> 690487 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[55]} 0 0
[junit4:junit4] 2> 690489 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[56]} 0 0
[junit4:junit4] 2> 690492 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[57]} 0 1
[junit4:junit4] 2> 690494 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[58]} 0 0
[junit4:junit4] 2> 690496 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[59]} 0 0
[junit4:junit4] 2> 690498 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[60]} 0 0
[junit4:junit4] 2> 690501 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[61]} 0 1
[junit4:junit4] 2> 690503 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[62]} 0 0
[junit4:junit4] 2> 690505 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[63]} 0 0
[junit4:junit4] 2> 690507 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[64]} 0 0
[junit4:junit4] 2> 690510 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[65]} 0 1
[junit4:junit4] 2> 690512 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[66]} 0 0
[junit4:junit4] 2> 690514 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[67]} 0 0
[junit4:junit4] 2> 690517 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[68]} 0 0
[junit4:junit4] 2> 690519 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[69]} 0 0
[junit4:junit4] 2> 690522 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[70]} 0 1
[junit4:junit4] 2> 690524 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[71]} 0 0
[junit4:junit4] 2> 690527 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[72]} 0 1
[junit4:junit4] 2> 690529 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[73]} 0 0
[junit4:junit4] 2> 690532 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[74]} 0 1
[junit4:junit4] 2> 690534 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[75]} 0 0
[junit4:junit4] 2> 690536 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[76]} 0 0
[junit4:junit4] 2> 690539 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[77]} 0 0
[junit4:junit4] 2> 690541 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[78]} 0 0
[junit4:junit4] 2> 690544 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[79]} 0 1
[junit4:junit4] 2> 690546 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[80]} 0 0
[junit4:junit4] 2> 690548 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[81]} 0 0
[junit4:junit4] 2> 690551 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[82]} 0 0
[junit4:junit4] 2> 690553 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[83]} 0 0
[junit4:junit4] 2> 690556 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[84]} 0 1
[junit4:junit4] 2> 690558 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[85]} 0 1
[junit4:junit4] 2> 690560 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[86]} 0 0
[junit4:junit4] 2> 690562 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[87]} 0 0
[junit4:junit4] 2> 690564 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[88]} 0 0
[junit4:junit4] 2> 690566 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[89]} 0 0
[junit4:junit4] 2> 690569 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[90]} 0 1
[junit4:junit4] 2> 690571 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[91]} 0 0
[junit4:junit4] 2> 690573 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[92]} 0 0
[junit4:junit4] 2> 690575 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[93]} 0 0
[junit4:junit4] 2> 690577 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[94]} 0 0
[junit4:junit4] 2> 690579 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[95]} 0 0
[junit4:junit4] 2> 690581 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[96]} 0 0
[junit4:junit4] 2> 690584 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[97]} 0 1
[junit4:junit4] 2> 690586 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[98]} 0 0
[junit4:junit4] 2> 690588 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[99]} 0 0
[junit4:junit4] 2> 690590 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[100]} 0 0
[junit4:junit4] 2> 690592 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[101]} 0 0
[junit4:junit4] 2> 690595 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[102]} 0 1
[junit4:junit4] 2> 690597 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[103]} 0 1
[junit4:junit4] 2> 690599 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[104]} 0 0
[junit4:junit4] 2> 690601 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[105]} 0 0
[junit4:junit4] 2> 690603 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[106]} 0 0
[junit4:junit4] 2> 690605 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[107]} 0 0
[junit4:junit4] 2> 690607 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[108]} 0 0
[junit4:junit4] 2> 690610 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[109]} 0 1
[junit4:junit4] 2> 690612 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[110]} 0 0
[junit4:junit4] 2> 690614 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[111]} 0 0
[junit4:junit4] 2> 690616 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[112]} 0 0
[junit4:junit4] 2> 690618 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[113]} 0 0
[junit4:junit4] 2> 690620 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[114]} 0 0
[junit4:junit4] 2> 690622 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[115]} 0 0
[junit4:junit4] 2> 690625 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[116]} 0 1
[junit4:junit4] 2> 690627 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[117]} 0 1
[junit4:junit4] 2> 690629 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[118]} 0 0
[junit4:junit4] 2> 690631 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[119]} 0 0
[junit4:junit4] 2> 690633 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[120]} 0 0
[junit4:junit4] 2> 690635 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[121]} 0 0
[junit4:junit4] 2> 690637 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[122]} 0 0
[junit4:junit4] 2> 690640 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[123]} 0 1
[junit4:junit4] 2> 690642 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[124]} 0 1
[junit4:junit4] 2> 690644 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[125]} 0 0
[junit4:junit4] 2> 690646 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[126]} 0 0
[junit4:junit4] 2> 690648 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[127]} 0 0
[junit4:junit4] 2> 690651 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[128]} 0 1
[junit4:junit4] 2> 690653 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[129]} 0 1
[junit4:junit4] 2> 690655 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[130]} 0 0
[junit4:junit4] 2> 690657 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[131]} 0 0
[junit4:junit4] 2> 690659 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[132]} 0 0
[junit4:junit4] 2> 690662 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[133]} 0 0
[junit4:junit4] 2> 690664 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[134]} 0 0
[junit4:junit4] 2> 690666 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[135]} 0 0
[junit4:junit4] 2> 690668 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[136]} 0 0
[junit4:junit4] 2> 690670 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[137]} 0 0
[junit4:junit4] 2> 690672 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[138]} 0 0
[junit4:junit4] 2> 690674 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[139]} 0 0
[junit4:junit4] 2> 690676 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[140]} 0 0
[junit4:junit4] 2> 690679 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[141]} 0 1
[junit4:junit4] 2> 690681 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[142]} 0 1
[junit4:junit4] 2> 690683 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[143]} 0 0
[junit4:junit4] 2> 690685 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[144]} 0 0
[junit4:junit4] 2> 690687 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[145]} 0 0
[junit4:junit4] 2> 690689 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[146]} 0 0
[junit4:junit4] 2> 690691 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[147]} 0 0
[junit4:junit4] 2> 690693 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[148]} 0 0
[junit4:junit4] 2> 690695 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[149]} 0 0
[junit4:junit4] 2> 690697 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[150]} 0 0
[junit4:junit4] 2> 690699 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[151]} 0 0
[junit4:junit4] 2> 690701 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[152]} 0 0
[junit4:junit4] 2> 690703 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[153]} 0 0
[junit4:junit4] 2> 690705 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[154]} 0 0
[junit4:junit4] 2> 690707 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[155]} 0 0
[junit4:junit4] 2> 690709 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[156]} 0 0
[junit4:junit4] 2> 690711 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[157]} 0 0
[junit4:junit4] 2> 690713 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[158]} 0 0
[junit4:junit4] 2> 690715 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[159]} 0 0
[junit4:junit4] 2> 690717 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[160]} 0 0
[junit4:junit4] 2> 690720 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[161]} 0 1
[junit4:junit4] 2> 690722 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[162]} 0 1
[junit4:junit4] 2> 690724 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[163]} 0 0
[junit4:junit4] 2> 690726 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[164]} 0 0
[junit4:junit4] 2> 690728 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[165]} 0 0
[junit4:junit4] 2> 690731 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[166]} 0 1
[junit4:junit4] 2> 690733 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[167]} 0 1
[junit4:junit4] 2> 690735 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[168]} 0 1
[junit4:junit4] 2> 690737 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[169]} 0 0
[junit4:junit4] 2> 690739 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[170]} 0 0
[junit4:junit4] 2> 690741 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[171]} 0 0
[junit4:junit4] 2> 690743 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[172]} 0 0
[junit4:junit4] 2> 690745 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[173]} 0 0
[junit4:junit4] 2> 690747 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[174]} 0 0
[junit4:junit4] 2> 690749 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[175]} 0 0
[junit4:junit4] 2> 690751 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[176]} 0 0
[junit4:junit4] 2> 690753 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[177]} 0 0
[junit4:junit4] 2> 690756 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[178]} 0 1
[junit4:junit4] 2> 690758 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[179]} 0 0
[junit4:junit4] 2> 690760 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[180]} 0 0
[junit4:junit4] 2> 690762 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[181]} 0 0
[junit4:junit4] 2> 690764 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[182]} 0 0
[junit4:junit4] 2> 690766 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[183]} 0 0
[junit4:junit4] 2> 690768 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[184]} 0 1
[junit4:junit4] 2> 690770 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[185]} 0 0
[junit4:junit4] 2> 690772 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[186]} 0 0
[junit4:junit4] 2> 690774 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[187]} 0 0
[junit4:junit4] 2> 690776 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[188]} 0 0
[junit4:junit4] 2> 690778 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[189]} 0 0
[junit4:junit4] 2> 690780 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[190]} 0 0
[junit4:junit4] 2> 690782 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[191]} 0 0
[junit4:junit4] 2> 690784 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[192]} 0 0
[junit4:junit4] 2> 690787 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[193]} 0 1
[junit4:junit4] 2> 690789 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[194]} 0 1
[junit4:junit4] 2> 690791 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[195]} 0 1
[junit4:junit4] 2> 690793 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[196]} 0 1
[junit4:junit4] 2> 690795 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[197]} 0 0
[junit4:junit4] 2> 690797 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[198]} 0 0
[junit4:junit4] 2> 690799 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[199]} 0 0
[junit4:junit4] 2> 690801 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[200]} 0 0
[junit4:junit4] 2> 690803 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[201]} 0 0
[junit4:junit4] 2> 690805 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[202]} 0 0
[junit4:junit4] 2> 690807 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[203]} 0 0
[junit4:junit4] 2> 690809 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[204]} 0 0
[junit4:junit4] 2> 690811 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[205]} 0 0
[junit4:junit4] 2> 690813 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[206]} 0 0
[junit4:junit4] 2> 690815 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[207]} 0 0
[junit4:junit4] 2> 690817 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[208]} 0 0
[junit4:junit4] 2> 690819 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[209]} 0 0
[junit4:junit4] 2> 690822 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[210]} 0 1
[junit4:junit4] 2> 690823 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[211]} 0 0
[junit4:junit4] 2> 690825 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[212]} 0 0
[junit4:junit4] 2> 690828 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[213]} 0 1
[junit4:junit4] 2> 690830 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[214]} 0 0
[junit4:junit4] 2> 690832 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[215]} 0 0
[junit4:junit4] 2> 690834 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[216]} 0 0
[junit4:junit4] 2> 690836 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[217]} 0 0
[junit4:junit4] 2> 690838 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[218]} 0 0
[junit4:junit4] 2> 690840 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[219]} 0 0
[junit4:junit4] 2> 690842 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[220]} 0 0
[junit4:junit4] 2> 690844 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[221]} 0 0
[junit4:junit4] 2> 690846 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[222]} 0 0
[junit4:junit4] 2> 690849 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[223]} 0 1
[junit4:junit4] 2> 690851 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[224]} 0 1
[junit4:junit4] 2> 690853 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[225]} 0 0
[junit4:junit4] 2> 690855 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[226]} 0 0
[junit4:junit4] 2> 690857 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[227]} 0 0
[junit4:junit4] 2> 690859 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[228]} 0 0
[junit4:junit4] 2> 690861 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[229]} 0 0
[junit4:junit4] 2> 690863 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[230]} 0 0
[junit4:junit4] 2> 690865 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[231]} 0 0
[junit4:junit4] 2> 690867 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[232]} 0 0
[junit4:junit4] 2> 690869 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[233]} 0 0
[junit4:junit4] 2> 690872 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[234]} 0 1
[junit4:junit4] 2> 690874 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[235]} 0 1
[junit4:junit4] 2> 690876 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[236]} 0 1
[junit4:junit4] 2> 690878 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[237]} 0 0
[junit4:junit4] 2> 690880 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[238]} 0 0
[junit4:junit4] 2> 690882 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[239]} 0 0
[junit4:junit4] 2> 690884 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[240]} 0 0
[junit4:junit4] 2> 690886 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[241]} 0 0
[junit4:junit4] 2> 690888 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[242]} 0 0
[junit4:junit4] 2> 690890 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[243]} 0 0
[junit4:junit4] 2> 690892 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[244]} 0 0
[junit4:junit4] 2> 690894 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[245]} 0 0
[junit4:junit4] 2> 690896 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[246]} 0 0
[junit4:junit4] 2> 690898 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[247]} 0 0
[junit4:junit4] 2> 690900 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[248]} 0 0
[junit4:junit4] 2> 690902 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[249]} 0 0
[junit4:junit4] 2> 690904 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[250]} 0 0
[junit4:junit4] 2> 690907 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[251]} 0 1
[junit4:junit4] 2> 690909 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[252]} 0 0
[junit4:junit4] 2> 690911 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[253]} 0 0
[junit4:junit4] 2> 690913 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[254]} 0 0
[junit4:junit4] 2> 690915 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[255]} 0 0
[junit4:junit4] 2> 690917 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[256]} 0 0
[junit4:junit4] 2> 690919 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[257]} 0 0
[junit4:junit4] 2> 690921 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[258]} 0 0
[junit4:junit4] 2> 690924 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[259]} 0 1
[junit4:junit4] 2> 690926 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[260]} 0 1
[junit4:junit4] 2> 690928 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[261]} 0 1
[junit4:junit4] 2> 690930 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[262]} 0 1
[junit4:junit4] 2> 690932 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[263]} 0 1
[junit4:junit4] 2> 690934 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[264]} 0 1
[junit4:junit4] 2> 690936 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[265]} 0 0
[junit4:junit4] 2> 690938 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[266]} 0 0
[junit4:junit4] 2> 690940 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[267]} 0 0
[junit4:junit4] 2> 690942 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[268]} 0 0
[junit4:junit4] 2> 690944 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[269]} 0 0
[junit4:junit4] 2> 690946 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[270]} 0 0
[junit4:junit4] 2> 690948 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[271]} 0 0
[junit4:junit4] 2> 690950 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[272]} 0 0
[junit4:junit4] 2> 690952 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[273]} 0 0
[junit4:junit4] 2> 690954 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[274]} 0 0
[junit4:junit4] 2> 690956 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[275]} 0 0
[junit4:junit4] 2> 690958 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[276]} 0 0
[junit4:junit4] 2> 690961 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[277]} 0 0
[junit4:junit4] 2> 690964 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[278]} 0 0
[junit4:junit4] 2> 690967 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[279]} 0 0
[junit4:junit4] 2> 690969 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[280]} 0 0
[junit4:junit4] 2> 690971 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[281]} 0 0
[junit4:junit4] 2> 690973 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[282]} 0 0
[junit4:junit4] 2> 690975 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[283]} 0 0
[junit4:junit4] 2> 690977 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[284]} 0 0
[junit4:junit4] 2> 690979 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[285]} 0 0
[junit4:junit4] 2> 690981 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[286]} 0 0
[junit4:junit4] 2> 690984 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[287]} 0 1
[junit4:junit4] 2> 690986 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[288]} 0 1
[junit4:junit4] 2> 690988 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[289]} 0 0
[junit4:junit4] 2> 690990 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[290]} 0 0
[junit4:junit4] 2> 690992 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[291]} 0 0
[junit4:junit4] 2> 690994 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[292]} 0 0
[junit4:junit4] 2> 690996 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[293]} 0 0
[junit4:junit4] 2> 690999 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[294]} 0 1
[junit4:junit4] 2> 691001 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[295]} 0 1
[junit4:junit4] 2> 691003 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[296]} 0 1
[junit4:junit4] 2> 691005 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[297]} 0 0
[junit4:junit4] 2> 691007 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[298]} 0 0
[junit4:junit4] 2> 691009 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[299]} 0 0
[junit4:junit4] 2> 691012 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[300]} 0 1
[junit4:junit4] 2> 691014 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[301]} 0 0
[junit4:junit4] 2> 691016 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[302]} 0 0
[junit4:junit4] 2> 691018 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[303]} 0 0
[junit4:junit4] 2> 691020 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[304]} 0 0
[junit4:junit4] 2> 691022 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[305]} 0 0
[junit4:junit4] 2> 691024 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[306]} 0 0
[junit4:junit4] 2> 691026 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[307]} 0 0
[junit4:junit4] 2> 691029 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[308]} 0 1
[junit4:junit4] 2> 691031 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[309]} 0 1
[junit4:junit4] 2> 691033 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[310]} 0 1
[junit4:junit4] 2> 691035 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[311]} 0 0
[junit4:junit4] 2> 691037 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[312]} 0 0
[junit4:junit4] 2> 691039 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[313]} 0 0
[junit4:junit4] 2> 691041 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[314]} 0 0
[junit4:junit4] 2> 691043 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[315]} 0 0
[junit4:junit4] 2> 691046 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[316]} 0 1
[junit4:junit4] 2> 691048 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[317]} 0 0
[junit4:junit4] 2> 691050 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[318]} 0 0
[junit4:junit4] 2> 691052 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[319]} 0 0
[junit4:junit4] 2> 691054 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[320]} 0 0
[junit4:junit4] 2> 691056 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[321]} 0 0
[junit4:junit4] 2> 691059 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[322]} 0 1
[junit4:junit4] 2> 691061 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[323]} 0 0
[junit4:junit4] 2> 691063 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[324]} 0 0
[junit4:junit4] 2> 691065 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[325]} 0 0
[junit4:junit4] 2> 691067 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[326]} 0 0
[junit4:junit4] 2> 691070 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[327]} 0 1
[junit4:junit4] 2> 691072 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[328]} 0 0
[junit4:junit4] 2> 691074 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[329]} 0 0
[junit4:junit4] 2> 691076 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[330]} 0 0
[junit4:junit4] 2> 691078 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[331]} 0 0
[junit4:junit4] 2> 691080 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[332]} 0 0
[junit4:junit4] 2> 691082 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[333]} 0 0
[junit4:junit4] 2> 691085 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[334]} 0 1
[junit4:junit4] 2> 691087 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[335]} 0 1
[junit4:junit4] 2> 691089 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[336]} 0 0
[junit4:junit4] 2> 691091 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[337]} 0 0
[junit4:junit4] 2> 691093 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[338]} 0 0
[junit4:junit4] 2> 691095 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[339]} 0 0
[junit4:junit4] 2> 691097 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[340]} 0 0
[junit4:junit4] 2> 691100 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[341]} 0 1
[junit4:junit4] 2> 691102 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[342]} 0 0
[junit4:junit4] 2> 691104 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[343]} 0 0
[junit4:junit4] 2> 691106 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[344]} 0 0
[junit4:junit4] 2> 691108 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[345]} 0 0
[junit4:junit4] 2> 691110 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[346]} 0 0
[junit4:junit4] 2> 691112 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[347]} 0 0
[junit4:junit4] 2> 691114 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[348]} 0 0
[junit4:junit4] 2> 691117 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[349]} 0 1
[junit4:junit4] 2> 691119 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[350]} 0 1
[junit4:junit4] 2> 691121 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[351]} 0 0
[junit4:junit4] 2> 691123 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[352]} 0 0
[junit4:junit4] 2> 691125 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[353]} 0 0
[junit4:junit4] 2> 691128 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[354]} 0 1
[junit4:junit4] 2> 691130 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[355]} 0 0
[junit4:junit4] 2> 691132 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[356]} 0 0
[junit4:junit4] 2> 691134 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[357]} 0 0
[junit4:junit4] 2> 691136 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[358]} 0 0
[junit4:junit4] 2> 691138 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[359]} 0 0
[junit4:junit4] 2> 691140 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[360]} 0 0
[junit4:junit4] 2> 691142 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[361]} 0 0
[junit4:junit4] 2> 691145 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[362]} 0 1
[junit4:junit4] 2> 691147 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[363]} 0 0
[junit4:junit4] 2> 691149 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[364]} 0 0
[junit4:junit4] 2> 691152 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[365]} 0 1
[junit4:junit4] 2> 691154 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[366]} 0 0
[junit4:junit4] 2> 691156 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[367]} 0 0
[junit4:junit4] 2> 691158 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[368]} 0 0
[junit4:junit4] 2> 691160 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[369]} 0 0
[junit4:junit4] 2> 691162 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[370]} 0 0
[junit4:junit4] 2> 691165 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[371]} 0 1
[junit4:junit4] 2> 691167 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[372]} 0 0
[junit4:junit4] 2> 691169 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[373]} 0 0
[junit4:junit4] 2> 691171 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[374]} 0 0
[junit4:junit4] 2> 691173 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[375]} 0 0
[junit4:junit4] 2> 691176 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[376]} 0 1
[junit4:junit4] 2> 691178 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[377]} 0 0
[junit4:junit4] 2> 691180 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[378]} 0 0
[junit4:junit4] 2> 691182 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[379]} 0 0
[junit4:junit4] 2> 691184 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[380]} 0 0
[junit4:junit4] 2> 691187 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[381]} 0 1
[junit4:junit4] 2> 691189 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[382]} 0 0
[junit4:junit4] 2> 691191 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[383]} 0 0
[junit4:junit4] 2> 691193 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[384]} 0 0
[junit4:junit4] 2> 691195 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[385]} 0 0
[junit4:junit4] 2> 691198 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[386]} 0 1
[junit4:junit4] 2> 691200 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[387]} 0 1
[junit4:junit4] 2> 691202 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[388]} 0 0
[junit4:junit4] 2> 691204 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[389]} 0 0
[junit4:junit4] 2> 691206 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[390]} 0 0
[junit4:junit4] 2> 691208 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[391]} 0 0
[junit4:junit4] 2> 691210 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[392]} 0 0
[junit4:junit4] 2> 691212 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[393]} 0 0
[junit4:junit4] 2> 691214 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[394]} 0 0
[junit4:junit4] 2> 691216 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[395]} 0 0
[junit4:junit4] 2> 691219 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[396]} 0 1
[junit4:junit4] 2> 691221 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[397]} 0 0
[junit4:junit4] 2> 691223 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[398]} 0 0
[junit4:junit4] 2> 691225 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[399]} 0 0
[junit4:junit4] 2> 691227 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[400]} 0 0
[junit4:junit4] 2> 691229 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[401]} 0 0
[junit4:junit4] 2> 691231 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[402]} 0 0
[junit4:junit4] 2> 691233 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[403]} 0 0
[junit4:junit4] 2> 691236 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[404]} 0 1
[junit4:junit4] 2> 691238 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[405]} 0 0
[junit4:junit4] 2> 691240 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[406]} 0 0
[junit4:junit4] 2> 691242 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[407]} 0 0
[junit4:junit4] 2> 691244 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[408]} 0 0
[junit4:junit4] 2> 691246 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[409]} 0 0
[junit4:junit4] 2> 691249 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[410]} 0 1
[junit4:junit4] 2> 691251 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[411]} 0 1
[junit4:junit4] 2> 691253 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[412]} 0 0
[junit4:junit4] 2> 691255 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[413]} 0 0
[junit4:junit4] 2> 691257 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[414]} 0 0
[junit4:junit4] 2> 691259 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[415]} 0 0
[junit4:junit4] 2> 691261 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[416]} 0 0
[junit4:junit4] 2> 691263 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[417]} 0 0
[junit4:junit4] 2> 691266 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[418]} 0 1
[junit4:junit4] 2> 691268 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[419]} 0 1
[junit4:junit4] 2> 691270 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[420]} 0 0
[junit4:junit4] 2> 691272 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[421]} 0 0
[junit4:junit4] 2> 691274 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[422]} 0 0
[junit4:junit4] 2> 691276 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[423]} 0 0
[junit4:junit4] 2> 691278 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[424]} 0 0
[junit4:junit4] 2> 691280 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[425]} 0 0
[junit4:junit4] 2> 691282 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[426]} 0 0
[junit4:junit4] 2> 691284 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[427]} 0 0
[junit4:junit4] 2> 691286 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[428]} 0 0
[junit4:junit4] 2> 691288 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[429]} 0 0
[junit4:junit4] 2> 691290 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[430]} 0 0
[junit4:junit4] 2> 691293 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[431]} 0 1
[junit4:junit4] 2> 691295 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[432]} 0 1
[junit4:junit4] 2> 691297 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[433]} 0 0
[junit4:junit4] 2> 691299 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[434]} 0 0
[junit4:junit4] 2> 691301 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[435]} 0 0
[junit4:junit4] 2> 691303 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[436]} 0 0
[junit4:junit4] 2> 691305 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[437]} 0 0
[junit4:junit4] 2> 691308 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[438]} 0 1
[junit4:junit4] 2> 691310 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[439]} 0 0
[junit4:junit4] 2> 691312 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[440]} 0 0
[junit4:junit4] 2> 691315 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[441]} 0 0
[junit4:junit4] 2> 691317 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[442]} 0 0
[junit4:junit4] 2> 691320 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[443]} 0 0
[junit4:junit4] 2> 691323 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[444]} 0 0
[junit4:junit4] 2> 691328 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[445]} 0 1
[junit4:junit4] 2> 691331 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[446]} 0 1
[junit4:junit4] 2> 691333 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[447]} 0 0
[junit4:junit4] 2> 691335 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[448]} 0 0
[junit4:junit4] 2> 691338 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[449]} 0 0
[junit4:junit4] 2> 691340 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[450]} 0 0
[junit4:junit4] 2> 691343 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[451]} 0 1
[junit4:junit4] 2> 691345 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[452]} 0 0
[junit4:junit4] 2> 691347 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[453]} 0 0
[junit4:junit4] 2> 691349 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[454]} 0 0
[junit4:junit4] 2> 691352 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[455]} 0 1
[junit4:junit4] 2> 691354 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[456]} 0 1
[junit4:junit4] 2> 691356 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[457]} 0 0
[junit4:junit4] 2> 691358 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[458]} 0 0
[junit4:junit4] 2> 691360 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[459]} 0 0
[junit4:junit4] 2> 691362 T2946 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[460]} 0 0
[junit4:junit4] 2> 691364 T2943 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[461]} 0 0
[junit4:junit4] 2> 691367 T2944 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[462]} 0 1
[junit4:junit4] 2> 691369 T2945 C2075 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[463]} 0 0
[junit4:junit4] 2> ASYNC NEW_CORE C2076 name=collection1 org.apache.solr.core.SolrCore@48a18ccf
[junit4:junit4] 2> 691377 T2946 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[464]} 0 6
[junit4:junit4] 2> 691380 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[465]} 0 0
[junit4:junit4] 2> 691383 T2944 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[466]} 0 1
[junit4:junit4] 2> 691385 T2945 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[467]} 0 0
[junit4:junit4] 2> 691388 T2946 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[468]} 0 1
[junit4:junit4] 2> 691390 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[469]} 0 0
[junit4:junit4] 2> 691392 T2944 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[470]} 0 0
[junit4:junit4] 2> 691394 T2945 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[471]} 0 0
[junit4:junit4] 2> 691397 T2946 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[472]} 0 1
[junit4:junit4] 2> 691399 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[473]} 0 0
[junit4:junit4] 2> 691401 T2944 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[474]} 0 0
[junit4:junit4] 2> 691404 T2945 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[475]} 0 1
[junit4:junit4] 2> 691406 T2946 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[476]} 0 1
[junit4:junit4] 2> 691408 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[477]} 0 0
[junit4:junit4] 2> 691410 T2944 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[478]} 0 0
[junit4:junit4] 2> 691412 T2945 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[479]} 0 0
[junit4:junit4] 2> 691414 T2946 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[480]} 0 0
[junit4:junit4] 2> 691417 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[481]} 0 1
[junit4:junit4] 2> 691419 T2944 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[482]} 0 0
[junit4:junit4] 2> 691421 T2945 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[483]} 0 0
[junit4:junit4] 2> 691423 T2946 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[484]} 0 0
[junit4:junit4] 2> 691425 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[485]} 0 0
[junit4:junit4] 2> 691427 T2944 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[486]} 0 0
[junit4:junit4] 2> 691429 T2945 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[487]} 0 0
[junit4:junit4] 2> 691432 T2946 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[488]} 0 1
[junit4:junit4] 2> 691434 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[489]} 0 1
[junit4:junit4] 2> 691436 T2945 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[490]} 0 0
[junit4:junit4] 2> 691438 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[491]} 0 0
[junit4:junit4] 2> 691440 T2944 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[492]} 0 0
[junit4:junit4] 2> 691442 T2946 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[493]} 0 0
[junit4:junit4] 2> 691444 T2945 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[494]} 0 0
[junit4:junit4] 2> 691447 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[495]} 0 1
[junit4:junit4] 2> 691449 T2944 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[496]} 0 0
[junit4:junit4] 2> 691451 T2946 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[497]} 0 0
[junit4:junit4] 2> 691453 T2945 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[498]} 0 0
[junit4:junit4] 2> 691455 T2943 C2076 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 691465 T2943 C2076 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7ffc609f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a79cbfc),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7ffc609f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a79cbfc),segFN=segments_2,generation=2}
[junit4:junit4] 2> 691466 T2943 C2076 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 691467 T2943 C2076 oass.SolrIndexSearcher.<init> Opening Searcher@3c0339de main
[junit4:junit4] 2> 691467 T2943 C2076 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 691467 T2948 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c0339de main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C499)}
[junit4:junit4] 2> 691468 T2943 C2076 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&softCommit=false&version=2&wt=javabin&commit=true} {commit=} 0 13
[junit4:junit4] 2> 691477 T2944 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=499 status=0 QTime=7
[junit4:junit4] 2> 691479 T2868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 691481 T2868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:33096
[junit4:junit4] 2> 691481 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 691482 T2868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 691482 T2868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave
[junit4:junit4] 2> 691482 T2868 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/solr.xml
[junit4:junit4] 2> 691482 T2868 oasc.CoreContainer.<init> New CoreContainer 1600010437
[junit4:junit4] 2> 691483 T2868 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 691483 T2868 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/'
[junit4:junit4] 2> 691483 T2868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/'
[junit4:junit4] 2> 691515 T2868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 691516 T2868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 691516 T2868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 691516 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 691516 T2868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 691516 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 691517 T2868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 691517 T2868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 691517 T2868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 691517 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 691520 T2868 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 691524 T2959 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1
[junit4:junit4] 2> 691525 T2959 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/'
[junit4:junit4] 2> 691556 T2959 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 691589 T2959 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 691589 T2959 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 691591 T2959 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 691600 T2959 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 691601 T2959 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 691601 T2959 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/
[junit4:junit4] 2> 691601 T2959 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 691601 T2959 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 691601 T2959 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data
[junit4:junit4] 2> 691602 T2959 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index/
[junit4:junit4] 2> 691602 T2959 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 691602 T2959 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index
[junit4:junit4] 2> 691603 T2959 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2eb6c68a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c3b61e),segFN=segments_1,generation=1}
[junit4:junit4] 2> 691603 T2959 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 691604 T2959 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 691604 T2959 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 691604 T2959 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 691604 T2959 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 691604 T2959 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 691604 T2959 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 691605 T2959 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 691607 T2959 oass.SolrIndexSearcher.<init> Opening Searcher@3bf7455a main
[junit4:junit4] 2> 691607 T2959 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 691607 T2959 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 691608 T2959 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4] 2> 691608 T2959 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 691608 T2960 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3bf7455a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 691609 T2868 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 691609 T2868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 691609 T2868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C2077 name=collection1 org.apache.solr.core.SolrCore@59147ce2
[junit4:junit4] 2> 691619 T2955 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 2> 691619 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 691723 T2956 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 2> 691723 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 691826 T2957 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 2> 691826 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 691929 T2955 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 2> 691930 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 692032 T2957 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 2> 692033 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 692136 T2958 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 2> 692137 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 692239 T2956 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 2> 692240 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 692342 T2955 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 2> 692343 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 692445 T2958 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 2> 692446 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 692548 T2956 C2077 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&version=2&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 2> 692549 T2868 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 692607 T2944 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&wt=javabin&command=indexversion} status=0 QTime=0
[junit4:junit4] 2> 692608 T2961 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4] 2> 692608 T2961 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4] 2> 692608 T2961 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 692610 T2946 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&version=2&wt=javabin&command=filelist} status=0 QTime=0
[junit4:junit4] 2> 692611 T2961 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4:junit4] 2> 692612 T2961 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925272346/slave/collection1/data/index.20130530224755006
[junit4:junit4] 2> 692612 T2961 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@1913f453 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d074367) fullCopy=false
[junit4:junit4] 2> 692614 T2945 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=_0.fnm&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 692618 T2943 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=_0.fdx&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=1
[junit4:junit4] 2> 692620 T2944 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=_0.si&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 692624 T2946 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=_0.fdt&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=1
[junit4:junit4] 2> 692626 T2945 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=_0_Asserting_0.doc&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 692629 T2943 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=_0_Asserting_0.tim&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 692632 T2944 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=segments_2&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 692635 T2946 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=_0.nvm&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 692638 T2945 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=_0.nvd&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 692641 T2944 C2076 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={compression=true&file=_0_Asserting_0.tip&generation=2&qt=/replication&wt=filestream&command=filecontent&checksum=true} status=0 QTime=1
[junit4:junit4] 2> 692641 T2961 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 692643 T2961 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 692643 T2961 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 692644 T2961 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2eb6c68a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c3b61e),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2eb6c68a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c3b61e),segFN=segments_2,generation=2}
[junit4:junit4] 2> 692645 T2961 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 692645 T2961 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 692645 T2961 oass.SolrIndexSearcher.<init> Opening Searcher@adfe8f9 main
[junit4:junit4] 2> 692645 T2960 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@adfe8f9 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C499)}
[junit4:junit4] 2> 692646 T2961 oasc.CachingDirectoryFactory.closeCacheValue
[...truncated too long message...]
s=0 QTime=0
[junit4:junit4] 2> 752662 T3460 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={core=collection1&action=status&version=2&wt=javabin} status=0 QTime=0
[junit4:junit4] 2> 752865 T3462 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={core=collection1&action=status&version=2&wt=javabin} status=0 QTime=1
[junit4:junit4] 2> 753068 T3461 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={core=collection1&action=status&version=2&wt=javabin} status=0 QTime=1
[junit4:junit4] 2> 753269 T2868 oas.SolrTestCaseJ4.tearDown ###Ending doTestStressReplication
[junit4:junit4] 2> 753289 T2868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2090451595
[junit4:junit4] 2> 753290 T2868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@544d861
[junit4:junit4] 2> 753290 T2868 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=6,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=667,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4] 2> 753291 T2868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 753291 T2868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 753291 T2868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 753292 T2868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 753293 T2868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 753293 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301223/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301223/master/collection1/data;done=false>>]
[junit4:junit4] 2> 753294 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301223/master/collection1/data
[junit4:junit4] 2> 753294 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301223/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301223/master/collection1/data/index;done=false>>]
[junit4:junit4] 2> 753295 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301223/master/collection1/data/index
[junit4:junit4] 2> 753295 T2868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 753366 T2868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1848546266
[junit4:junit4] 2> 753366 T2868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3863084a
[junit4:junit4] 2> 753367 T2868 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=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 753367 T2868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 753367 T2868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 753368 T2868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 753369 T2868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 753369 T2868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 753369 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301353/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301353/slave/collection1/data;done=false>>]
[junit4:junit4] 2> 753369 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301353/slave/collection1/data
[junit4:junit4] 2> 753370 T2868 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301353/slave/collection1/data/index.20130530224824857 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301353/slave/collection1/data/index.20130530224824857;done=false>>]
[junit4:junit4] 2> 753370 T2868 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369925301353/slave/collection1/data/index.20130530224824857
[junit4:junit4] 2> 753370 T2868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandler -Dtests.method=doTestStressReplication -Dtests.seed=28FFF99E409E632D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fi_FI -Dtests.timezone=Asia/Makassar -Dtests.file.encoding=UTF-8
[junit4:junit4] FAILURE 34.6s J0 | TestReplicationHandler.doTestStressReplication <<<
[junit4:junit4] > Throwable #1: java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Thu May 30 22:48:24 CIT 2013
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([28FFF99E409E632D:F354F95845B60A9E]:0)
[junit4:junit4] > at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1473)
[junit4:junit4] > at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:777)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4:junit4] 2> 753449 T2868 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Lucene42: {id=PostingsFormat(name=Asserting), newname=PostingsFormat(name=Asserting), name=PostingsFormat(name=Asserting)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=fi_FI, timezone=Asia/Makassar
[junit4:junit4] 2> NOTE: Linux 3.2.0-44-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=300116432,total=518979584
[junit4:junit4] 2> NOTE: All tests run in this JVM: [UnloadDistributedZkTest, LoggingHandlerTest, ExternalFileFieldSortTest, LukeRequestHandlerTest, DirectSolrConnectionTest, DateFieldTest, TestReversedWildcardFilterFactory, StatelessScriptUpdateProcessorFactoryTest, TestFaceting, DirectUpdateHandlerOptimizeTest, SearchHandlerTest, ShardRoutingTest, TestExtendedDismaxParser, CachingDirectoryFactoryTest, TestStressRecovery, LeaderElectionTest, TestPseudoReturnFields, UpdateRequestProcessorFactoryTest, ScriptEngineTest, AlternateDirectoryTest, LegacyHTMLStripCharFilterTest, TestFunctionQuery, TestTrie, QueryElevationComponentTest, SliceStateTest, TestShardHandlerFactory, SuggesterTest, SolrTestCaseJ4Test, SpatialFilterTest, SyncSliceTest, TestDocSet, CoreAdminHandlerTest, TestSurroundQueryParser, DocumentBuilderTest, BadComponentTest, TestUniqueKeyFieldResource, FileBasedSpellCheckerTest, TestConfig, TestSchemaNameResource, ShowFileRequestHandlerTest, TestCharFilters, MinimalSchemaTest, TestSchemaResource, TestPhraseSuggestions, FastVectorHighlighterTest, ZkCLITest, TestSerializedLuceneMatchVersion, AnalysisAfterCoreReloadTest, SliceStateUpdateTest, DistributedQueryElevationComponentTest, TestIBSimilarityFactory, ZkControllerTest, DistributedTermsComponentTest, TermsComponentTest, TestPluginEnable, PluginInfoTest, DocValuesTest, TestQueryUtils, IndexSchemaTest, JsonLoaderTest, TestSolrDeletionPolicy2, SolrCoreTest, SystemInfoHandlerTest, BinaryUpdateRequestHandlerTest, TestSort, TestSweetSpotSimilarityFactory, TestValueSourceCache, ClusterStateUpdateTest, TestClassNameShortening, OverseerCollectionProcessorTest, RecoveryZkTest, TestSolrIndexConfig, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, OverseerTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4:junit4] Completed on J0 in 64.46s, 13 tests, 1 failure <<< FAILURES!
[...truncated 433 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:380: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:360: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:437: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1240: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:884: There were test failures: 295 suites, 1232 tests, 1 failure, 16 ignored (7 assumptions)
Total time: 37 minutes 45 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b91 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure