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/29 22:59:15 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.6.0_45) - Build # 5827
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/5827/
Java: 64bit/jdk1.6.0_45 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Wed May 29 16:50:11 AST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Wed May 29 16:50:11 AST 2013
at __randomizedtesting.SeedInfo.seed([85A4A4A3B24E0A2:FF29A412FDCC4F44]: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.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1222)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java: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:662)
Build Log:
[...truncated 9136 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4:junit4] 2> 725591 T2666 oas.SolrTestCaseJ4.setUp ###Starting doTestIndexAndConfigReplication
[junit4:junit4] 2> 725594 T2666 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 725596 T2666 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:41529
[junit4:junit4] 2> 725597 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 725598 T2666 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 725598 T2666 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master
[junit4:junit4] 2> 725598 T2666 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/solr.xml
[junit4:junit4] 2> 725599 T2666 oasc.CoreContainer.<init> New CoreContainer 2019816104
[junit4:junit4] 2> 725599 T2666 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 725600 T2666 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/'
[junit4:junit4] 2> 725600 T2666 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/'
[junit4:junit4] 2> 725661 T2666 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 725661 T2666 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 725662 T2666 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 725662 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 725662 T2666 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 725663 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 725663 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 725663 T2666 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 725663 T2666 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 725664 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 725667 T2666 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 725669 T2676 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1
[junit4:junit4] 2> 725670 T2676 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/'
[junit4:junit4] 2> 725692 T2676 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4:junit4] 2> 725720 T2676 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 725721 T2676 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 725722 T2676 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 725732 T2676 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 725733 T2676 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 725733 T2676 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/
[junit4:junit4] 2> 725734 T2676 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 725734 T2676 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 725734 T2676 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data
[junit4:junit4] 2> 725734 T2676 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index/
[junit4:junit4] 2> 725734 T2676 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 725735 T2676 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index
[junit4:junit4] 2> 725736 T2676 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@130eff16 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62399a23),segFN=segments_1,generation=1}
[junit4:junit4] 2> 725736 T2676 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 725736 T2676 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 725737 T2676 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 725737 T2676 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 725737 T2676 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 725737 T2676 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 725737 T2676 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 725739 T2676 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 725741 T2676 oass.SolrIndexSearcher.<init> Opening Searcher@2c9115ac main
[junit4:junit4] 2> 725741 T2676 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 725741 T2676 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 725742 T2676 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4] 2> 725742 T2676 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 725742 T2676 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 725742 T2677 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c9115ac main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 725742 T2666 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 725743 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 725743 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 725748 T2666 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 725749 T2666 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53642
[junit4:junit4] 2> 725750 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 725750 T2666 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 725751 T2666 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave
[junit4:junit4] 2> 725751 T2666 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/solr.xml
[junit4:junit4] 2> 725751 T2666 oasc.CoreContainer.<init> New CoreContainer 1261518365
[junit4:junit4] 2> 725752 T2666 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 725752 T2666 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/'
[junit4:junit4] 2> 725752 T2666 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/'
[junit4:junit4] 2> 725788 T2666 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 725789 T2666 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 725789 T2666 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 725789 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 725790 T2666 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 725790 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 725790 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 725790 T2666 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 725791 T2666 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 725791 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 725794 T2666 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 725796 T2688 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1
[junit4:junit4] 2> 725796 T2688 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/'
[junit4:junit4] 2> 725819 T2688 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4:junit4] 2> 725847 T2688 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 725847 T2688 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 725849 T2688 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 725858 T2688 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 725859 T2688 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 725860 T2688 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/
[junit4:junit4] 2> 725860 T2688 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 725860 T2688 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 725860 T2688 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data
[junit4:junit4] 2> 725860 T2688 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index/
[junit4:junit4] 2> 725861 T2688 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 725861 T2688 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index
[junit4:junit4] 2> 725862 T2688 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@373e8ad9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@242aac18),segFN=segments_1,generation=1}
[junit4:junit4] 2> 725862 T2688 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 725868 T2688 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 725868 T2688 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 725868 T2688 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 725868 T2688 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 725869 T2688 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 725869 T2688 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 725870 T2688 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 725872 T2688 oass.SolrIndexSearcher.<init> Opening Searcher@5d2f34b2 main
[junit4:junit4] 2> 725872 T2688 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 725872 T2688 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 725874 T2688 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4] 2> 725874 T2688 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 725874 T2689 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d2f34b2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 725875 T2666 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 725875 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 725875 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C1934 name=collection1 org.apache.solr.core.SolrCore@ae46d8b
[junit4:junit4] 2> 725884 T2672 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 725888 T2673 C1934 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@130eff16 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62399a23),segFN=segments_1,generation=1}
[junit4:junit4] 2> 725889 T2673 C1934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 725889 T2674 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 725889 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 3
[junit4:junit4] 2> 725892 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4:junit4] 2> 725894 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4:junit4] 2> 725896 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4:junit4] 2> 725897 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4:junit4] 2> 725899 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4:junit4] 2> 725901 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
[junit4:junit4] 2> 725903 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4:junit4] 2> 725905 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4:junit4] 2> 725907 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 1
[junit4:junit4] 2> 725910 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4:junit4] 2> 725912 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4:junit4] 2> 725914 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4:junit4] 2> 725916 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4:junit4] 2> 725918 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4:junit4] 2> 725920 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 0
[junit4:junit4] 2> 725922 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4:junit4] 2> 725924 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4:junit4] 2> 725926 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
[junit4:junit4] 2> 725928 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4:junit4] 2> 725930 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4:junit4] 2> 725932 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 0
[junit4:junit4] 2> 725934 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4:junit4] 2> 725937 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 1
[junit4:junit4] 2> 725939 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 1
[junit4:junit4] 2> 725941 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 1
[junit4:junit4] 2> 725943 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 0
[junit4:junit4] 2> 725945 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
[junit4:junit4] 2> 725947 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 0
[junit4:junit4] 2> 725949 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4:junit4] 2> 725951 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 0
[junit4:junit4] 2> 725954 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 1
[junit4:junit4] 2> 725956 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 1
[junit4:junit4] 2> 725958 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
[junit4:junit4] 2> 725960 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
[junit4:junit4] 2> 725962 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
[junit4:junit4] 2> 725965 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 1
[junit4:junit4] 2> 725967 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4:junit4] 2> 725969 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 0
[junit4:junit4] 2> 725971 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 0
[junit4:junit4] 2> 725974 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 0
[junit4:junit4] 2> 725976 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4:junit4] 2> 725978 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4:junit4] 2> 725980 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4:junit4] 2> 725983 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 1
[junit4:junit4] 2> 725985 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 0
[junit4:junit4] 2> 725987 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4:junit4] 2> 725989 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4:junit4] 2> 725991 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 0
[junit4:junit4] 2> 725994 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 1
[junit4:junit4] 2> 725996 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 0
[junit4:junit4] 2> 725998 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 0
[junit4:junit4] 2> 726000 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 0
[junit4:junit4] 2> 726002 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4:junit4] 2> 726004 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 0
[junit4:junit4] 2> 726006 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 0
[junit4:junit4] 2> 726008 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 0
[junit4:junit4] 2> 726010 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 0
[junit4:junit4] 2> 726012 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4:junit4] 2> 726014 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 0
[junit4:junit4] 2> 726016 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 0
[junit4:junit4] 2> 726018 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 0
[junit4:junit4] 2> 726020 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 0
[junit4:junit4] 2> 726023 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 1
[junit4:junit4] 2> 726025 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 0
[junit4:junit4] 2> 726028 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 1
[junit4:junit4] 2> 726030 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 0
[junit4:junit4] 2> 726032 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 0
[junit4:junit4] 2> 726035 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 1
[junit4:junit4] 2> 726037 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4:junit4] 2> 726039 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 0
[junit4:junit4] 2> 726042 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 1
[junit4:junit4] 2> 726044 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 0
[junit4:junit4] 2> 726046 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 0
[junit4:junit4] 2> 726049 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 0
[junit4:junit4] 2> 726051 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 0
[junit4:junit4] 2> 726054 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 1
[junit4:junit4] 2> 726056 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 0
[junit4:junit4] 2> 726058 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 0
[junit4:junit4] 2> 726060 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 0
[junit4:junit4] 2> 726063 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 0
[junit4:junit4] 2> 726065 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 0
[junit4:junit4] 2> 726068 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 1
[junit4:junit4] 2> 726070 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 0
[junit4:junit4] 2> 726072 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4:junit4] 2> 726074 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 0
[junit4:junit4] 2> 726076 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 0
[junit4:junit4] 2> 726078 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 0
[junit4:junit4] 2> 726080 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 0
[junit4:junit4] 2> 726082 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 0
[junit4:junit4] 2> 726084 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 0
[junit4:junit4] 2> 726086 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 0
[junit4:junit4] 2> 726088 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4:junit4] 2> 726090 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 0
[junit4:junit4] 2> 726092 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 0
[junit4:junit4] 2> 726095 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 0
[junit4:junit4] 2> 726097 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4:junit4] 2> 726099 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 0
[junit4:junit4] 2> 726101 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 0
[junit4:junit4] 2> 726103 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 0
[junit4:junit4] 2> 726106 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 1
[junit4:junit4] 2> 726108 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 1
[junit4:junit4] 2> 726110 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 0
[junit4:junit4] 2> 726112 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4:junit4] 2> 726114 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4:junit4] 2> 726116 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 0
[junit4:junit4] 2> 726118 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 0
[junit4:junit4] 2> 726120 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 0
[junit4:junit4] 2> 726122 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 0
[junit4:junit4] 2> 726124 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 0
[junit4:junit4] 2> 726127 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 1
[junit4:junit4] 2> 726129 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 0
[junit4:junit4] 2> 726131 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 0
[junit4:junit4] 2> 726133 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 0
[junit4:junit4] 2> 726135 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 0
[junit4:junit4] 2> 726138 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 1
[junit4:junit4] 2> 726140 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 0
[junit4:junit4] 2> 726142 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 0
[junit4:junit4] 2> 726145 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 1
[junit4:junit4] 2> 726147 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4:junit4] 2> 726149 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 0
[junit4:junit4] 2> 726151 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 0
[junit4:junit4] 2> 726154 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 1
[junit4:junit4] 2> 726156 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 0
[junit4:junit4] 2> 726158 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 0
[junit4:junit4] 2> 726161 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 1
[junit4:junit4] 2> 726163 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 0
[junit4:junit4] 2> 726166 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 1
[junit4:junit4] 2> 726168 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128]} 0 0
[junit4:junit4] 2> 726171 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 1
[junit4:junit4] 2> 726173 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4:junit4] 2> 726175 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 0
[junit4:junit4] 2> 726178 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132]} 0 0
[junit4:junit4] 2> 726180 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133]} 0 0
[junit4:junit4] 2> 726182 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 0
[junit4:junit4] 2> 726185 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135]} 0 1
[junit4:junit4] 2> 726187 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 0
[junit4:junit4] 2> 726189 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137]} 0 0
[junit4:junit4] 2> 726191 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138]} 0 0
[junit4:junit4] 2> 726193 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 0
[junit4:junit4] 2> 726196 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 1
[junit4:junit4] 2> 726198 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 1
[junit4:junit4] 2> 726200 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 0
[junit4:junit4] 2> 726203 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 0
[junit4:junit4] 2> 726205 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 0
[junit4:junit4] 2> 726207 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 0
[junit4:junit4] 2> 726210 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 0
[junit4:junit4] 2> 726212 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 0
[junit4:junit4] 2> 726214 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 0
[junit4:junit4] 2> 726216 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149]} 0 0
[junit4:junit4] 2> 726218 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[150]} 0 0
[junit4:junit4] 2> 726221 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[151]} 0 1
[junit4:junit4] 2> 726223 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[152]} 0 0
[junit4:junit4] 2> 726225 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[153]} 0 0
[junit4:junit4] 2> 726227 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[154]} 0 0
[junit4:junit4] 2> 726229 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[155]} 0 0
[junit4:junit4] 2> 726232 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[156]} 0 0
[junit4:junit4] 2> 726234 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[157]} 0 0
[junit4:junit4] 2> 726237 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[158]} 0 0
[junit4:junit4] 2> 726239 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[159]} 0 0
[junit4:junit4] 2> 726241 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[160]} 0 0
[junit4:junit4] 2> 726246 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[161]} 0 0
[junit4:junit4] 2> 726249 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[162]} 0 0
[junit4:junit4] 2> 726251 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[163]} 0 0
[junit4:junit4] 2> 726253 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[164]} 0 0
[junit4:junit4] 2> 726255 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[165]} 0 0
[junit4:junit4] 2> 726257 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[166]} 0 0
[junit4:junit4] 2> 726259 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[167]} 0 0
[junit4:junit4] 2> 726261 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[168]} 0 0
[junit4:junit4] 2> 726263 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[169]} 0 0
[junit4:junit4] 2> 726265 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[170]} 0 0
[junit4:junit4] 2> 726267 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[171]} 0 0
[junit4:junit4] 2> 726269 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[172]} 0 0
[junit4:junit4] 2> 726271 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[173]} 0 0
[junit4:junit4] 2> 726273 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[174]} 0 0
[junit4:junit4] 2> 726275 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[175]} 0 0
[junit4:junit4] 2> 726277 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[176]} 0 0
[junit4:junit4] 2> 726279 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[177]} 0 0
[junit4:junit4] 2> 726281 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[178]} 0 0
[junit4:junit4] 2> 726283 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[179]} 0 0
[junit4:junit4] 2> 726285 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[180]} 0 0
[junit4:junit4] 2> 726287 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[181]} 0 0
[junit4:junit4] 2> 726289 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[182]} 0 0
[junit4:junit4] 2> 726291 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[183]} 0 0
[junit4:junit4] 2> 726293 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[184]} 0 0
[junit4:junit4] 2> 726295 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[185]} 0 0
[junit4:junit4] 2> 726297 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[186]} 0 0
[junit4:junit4] 2> 726299 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[187]} 0 0
[junit4:junit4] 2> 726301 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[188]} 0 0
[junit4:junit4] 2> 726303 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[189]} 0 0
[junit4:junit4] 2> 726305 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[190]} 0 1
[junit4:junit4] 2> 726306 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[191]} 0 0
[junit4:junit4] 2> 726308 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[192]} 0 0
[junit4:junit4] 2> 726310 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[193]} 0 0
[junit4:junit4] 2> 726312 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[194]} 0 0
[junit4:junit4] 2> 726315 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[195]} 0 0
[junit4:junit4] 2> 726317 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[196]} 0 0
[junit4:junit4] 2> 726319 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[197]} 0 0
[junit4:junit4] 2> 726321 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[198]} 0 0
[junit4:junit4] 2> 726324 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[199]} 0 1
[junit4:junit4] 2> 726326 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[200]} 0 1
[junit4:junit4] 2> 726327 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[201]} 0 0
[junit4:junit4] 2> 726329 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[202]} 0 0
[junit4:junit4] 2> 726332 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[203]} 0 1
[junit4:junit4] 2> 726334 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[204]} 0 0
[junit4:junit4] 2> 726336 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[205]} 0 0
[junit4:junit4] 2> 726338 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[206]} 0 0
[junit4:junit4] 2> 726340 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[207]} 0 0
[junit4:junit4] 2> 726342 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[208]} 0 0
[junit4:junit4] 2> 726344 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[209]} 0 0
[junit4:junit4] 2> 726346 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[210]} 0 0
[junit4:junit4] 2> 726348 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[211]} 0 1
[junit4:junit4] 2> 726350 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[212]} 0 0
[junit4:junit4] 2> 726352 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[213]} 0 1
[junit4:junit4] 2> 726354 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[214]} 0 1
[junit4:junit4] 2> 726355 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[215]} 0 0
[junit4:junit4] 2> 726357 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[216]} 0 0
[junit4:junit4] 2> 726359 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[217]} 0 0
[junit4:junit4] 2> 726361 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[218]} 0 0
[junit4:junit4] 2> 726363 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[219]} 0 0
[junit4:junit4] 2> 726365 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[220]} 0 0
[junit4:junit4] 2> 726367 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[221]} 0 0
[junit4:junit4] 2> 726369 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222]} 0 0
[junit4:junit4] 2> 726372 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[223]} 0 0
[junit4:junit4] 2> 726374 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[224]} 0 0
[junit4:junit4] 2> 726376 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[225]} 0 0
[junit4:junit4] 2> 726378 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[226]} 0 0
[junit4:junit4] 2> 726380 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[227]} 0 0
[junit4:junit4] 2> 726382 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[228]} 0 1
[junit4:junit4] 2> 726384 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[229]} 0 1
[junit4:junit4] 2> 726386 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[230]} 0 1
[junit4:junit4] 2> 726388 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[231]} 0 1
[junit4:junit4] 2> 726390 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[232]} 0 1
[junit4:junit4] 2> 726392 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[233]} 0 1
[junit4:junit4] 2> 726393 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[234]} 0 0
[junit4:junit4] 2> 726395 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[235]} 0 0
[junit4:junit4] 2> 726397 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[236]} 0 0
[junit4:junit4] 2> 726399 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[237]} 0 0
[junit4:junit4] 2> 726401 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[238]} 0 0
[junit4:junit4] 2> 726403 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[239]} 0 0
[junit4:junit4] 2> 726405 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[240]} 0 0
[junit4:junit4] 2> 726408 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[241]} 0 1
[junit4:junit4] 2> 726410 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[242]} 0 0
[junit4:junit4] 2> 726412 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[243]} 0 0
[junit4:junit4] 2> 726414 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[244]} 0 0
[junit4:junit4] 2> 726416 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[245]} 0 0
[junit4:junit4] 2> 726418 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[246]} 0 0
[junit4:junit4] 2> 726420 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[247]} 0 0
[junit4:junit4] 2> 726422 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[248]} 0 0
[junit4:junit4] 2> 726424 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[249]} 0 0
[junit4:junit4] 2> 726426 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[250]} 0 0
[junit4:junit4] 2> 726429 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[251]} 0 1
[junit4:junit4] 2> 726430 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[252]} 0 0
[junit4:junit4] 2> 726432 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[253]} 0 0
[junit4:junit4] 2> 726434 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[254]} 0 0
[junit4:junit4] 2> 726437 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[255]} 0 1
[junit4:junit4] 2> 726438 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[256]} 0 0
[junit4:junit4] 2> 726440 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[257]} 0 0
[junit4:junit4] 2> 726442 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[258]} 0 0
[junit4:junit4] 2> 726444 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[259]} 0 0
[junit4:junit4] 2> 726446 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[260]} 0 0
[junit4:junit4] 2> 726448 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[261]} 0 0
[junit4:junit4] 2> 726450 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[262]} 0 0
[junit4:junit4] 2> 726452 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[263]} 0 0
[junit4:junit4] 2> 726454 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[264]} 0 0
[junit4:junit4] 2> 726456 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[265]} 0 0
[junit4:junit4] 2> 726458 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[266]} 0 0
[junit4:junit4] 2> 726460 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[267]} 0 0
[junit4:junit4] 2> 726462 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[268]} 0 0
[junit4:junit4] 2> 726464 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[269]} 0 1
[junit4:junit4] 2> 726465 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[270]} 0 0
[junit4:junit4] 2> 726467 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[271]} 0 0
[junit4:junit4] 2> 726469 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[272]} 0 0
[junit4:junit4] 2> 726471 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[273]} 0 0
[junit4:junit4] 2> 726473 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[274]} 0 0
[junit4:junit4] 2> 726475 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[275]} 0 0
[junit4:junit4] 2> 726477 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[276]} 0 0
[junit4:junit4] 2> 726479 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[277]} 0 0
[junit4:junit4] 2> 726481 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[278]} 0 1
[junit4:junit4] 2> 726482 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[279]} 0 0
[junit4:junit4] 2> 726484 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[280]} 0 0
[junit4:junit4] 2> 726486 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[281]} 0 0
[junit4:junit4] 2> 726488 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[282]} 0 0
[junit4:junit4] 2> 726490 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[283]} 0 0
[junit4:junit4] 2> 726492 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[284]} 0 1
[junit4:junit4] 2> 726493 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[285]} 0 0
[junit4:junit4] 2> 726495 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[286]} 0 0
[junit4:junit4] 2> 726497 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[287]} 0 0
[junit4:junit4] 2> 726499 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[288]} 0 0
[junit4:junit4] 2> 726501 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[289]} 0 0
[junit4:junit4] 2> 726503 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[290]} 0 0
[junit4:junit4] 2> 726505 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[291]} 0 0
[junit4:junit4] 2> 726507 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[292]} 0 0
[junit4:junit4] 2> 726509 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[293]} 0 0
[junit4:junit4] 2> 726511 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[294]} 0 0
[junit4:junit4] 2> 726513 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[295]} 0 0
[junit4:junit4] 2> 726515 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[296]} 0 0
[junit4:junit4] 2> 726517 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[297]} 0 0
[junit4:junit4] 2> 726519 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[298]} 0 0
[junit4:junit4] 2> 726521 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[299]} 0 0
[junit4:junit4] 2> 726523 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[300]} 0 0
[junit4:junit4] 2> 726525 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[301]} 0 0
[junit4:junit4] 2> 726527 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[302]} 0 0
[junit4:junit4] 2> 726529 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[303]} 0 0
[junit4:junit4] 2> 726531 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[304]} 0 0
[junit4:junit4] 2> 726533 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[305]} 0 0
[junit4:junit4] 2> 726535 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[306]} 0 0
[junit4:junit4] 2> 726537 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[307]} 0 0
[junit4:junit4] 2> 726539 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[308]} 0 0
[junit4:junit4] 2> 726541 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[309]} 0 1
[junit4:junit4] 2> 726542 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[310]} 0 0
[junit4:junit4] 2> 726544 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[311]} 0 0
[junit4:junit4] 2> 726546 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[312]} 0 0
[junit4:junit4] 2> 726548 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[313]} 0 0
[junit4:junit4] 2> 726550 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[314]} 0 0
[junit4:junit4] 2> 726552 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[315]} 0 1
[junit4:junit4] 2> 726554 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[316]} 0 1
[junit4:junit4] 2> 726555 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[317]} 0 0
[junit4:junit4] 2> 726557 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[318]} 0 0
[junit4:junit4] 2> 726559 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[319]} 0 0
[junit4:junit4] 2> 726561 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[320]} 0 0
[junit4:junit4] 2> 726563 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[321]} 0 0
[junit4:junit4] 2> 726565 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[322]} 0 0
[junit4:junit4] 2> 726567 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[323]} 0 0
[junit4:junit4] 2> 726569 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[324]} 0 0
[junit4:junit4] 2> 726571 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[325]} 0 0
[junit4:junit4] 2> 726573 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[326]} 0 1
[junit4:junit4] 2> 726575 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[327]} 0 1
[junit4:junit4] 2> 726577 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[328]} 0 1
[junit4:junit4] 2> 726579 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[329]} 0 1
[junit4:junit4] 2> 726580 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[330]} 0 0
[junit4:junit4] 2> 726582 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[331]} 0 0
[junit4:junit4] 2> 726584 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[332]} 0 0
[junit4:junit4] 2> 726586 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[333]} 0 0
[junit4:junit4] 2> 726588 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[334]} 0 0
[junit4:junit4] 2> 726590 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[335]} 0 0
[junit4:junit4] 2> 726592 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[336]} 0 1
[junit4:junit4] 2> 726594 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[337]} 0 1
[junit4:junit4] 2> 726596 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[338]} 0 1
[junit4:junit4] 2> 726597 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[339]} 0 0
[junit4:junit4] 2> 726599 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[340]} 0 0
[junit4:junit4] 2> 726601 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[341]} 0 0
[junit4:junit4] 2> 726603 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[342]} 0 0
[junit4:junit4] 2> 726605 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[343]} 0 0
[junit4:junit4] 2> 726607 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[344]} 0 0
[junit4:junit4] 2> 726609 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[345]} 0 0
[junit4:junit4] 2> 726611 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[346]} 0 0
[junit4:junit4] 2> 726613 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[347]} 0 0
[junit4:junit4] 2> 726615 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[348]} 0 1
[junit4:junit4] 2> 726617 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[349]} 0 1
[junit4:junit4] 2> 726618 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[350]} 0 0
[junit4:junit4] 2> 726620 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[351]} 0 0
[junit4:junit4] 2> 726622 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[352]} 0 0
[junit4:junit4] 2> 726624 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[353]} 0 0
[junit4:junit4] 2> 726626 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[354]} 0 0
[junit4:junit4] 2> 726628 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[355]} 0 0
[junit4:junit4] 2> 726630 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[356]} 0 0
[junit4:junit4] 2> 726632 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[357]} 0 0
[junit4:junit4] 2> 726634 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[358]} 0 1
[junit4:junit4] 2> 726635 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[359]} 0 0
[junit4:junit4] 2> 726637 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[360]} 0 0
[junit4:junit4] 2> 726639 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[361]} 0 0
[junit4:junit4] 2> 726641 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[362]} 0 0
[junit4:junit4] 2> 726643 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[363]} 0 0
[junit4:junit4] 2> 726645 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[364]} 0 0
[junit4:junit4] 2> 726646 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[365]} 0 0
[junit4:junit4] 2> 726648 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[366]} 0 0
[junit4:junit4] 2> 726650 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[367]} 0 0
[junit4:junit4] 2> 726652 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[368]} 0 0
[junit4:junit4] 2> 726654 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[369]} 0 0
[junit4:junit4] 2> 726656 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[370]} 0 0
[junit4:junit4] 2> 726658 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[371]} 0 0
[junit4:junit4] 2> 726660 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[372]} 0 0
[junit4:junit4] 2> 726662 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[373]} 0 0
[junit4:junit4] 2> 726664 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[374]} 0 1
[junit4:junit4] 2> 726665 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[375]} 0 0
[junit4:junit4] 2> 726667 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[376]} 0 0
[junit4:junit4] 2> 726669 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[377]} 0 0
[junit4:junit4] 2> 726671 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[378]} 0 0
[junit4:junit4] 2> 726673 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[379]} 0 0
[junit4:junit4] 2> 726675 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[380]} 0 0
[junit4:junit4] 2> 726677 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[381]} 0 0
[junit4:junit4] 2> 726679 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[382]} 0 1
[junit4:junit4] 2> 726681 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[383]} 0 1
[junit4:junit4] 2> 726683 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[384]} 0 0
[junit4:junit4] 2> 726685 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[385]} 0 0
[junit4:junit4] 2> 726687 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[386]} 0 0
[junit4:junit4] 2> 726689 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[387]} 0 0
[junit4:junit4] 2> 726691 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[388]} 0 0
[junit4:junit4] 2> 726693 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[389]} 0 0
[junit4:junit4] 2> 726695 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[390]} 0 0
[junit4:junit4] 2> 726697 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[391]} 0 0
[junit4:junit4] 2> 726699 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[392]} 0 0
[junit4:junit4] 2> 726701 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[393]} 0 0
[junit4:junit4] 2> 726703 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[394]} 0 0
[junit4:junit4] 2> 726705 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[395]} 0 0
[junit4:junit4] 2> 726707 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[396]} 0 0
[junit4:junit4] 2> 726709 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[397]} 0 0
[junit4:junit4] 2> 726711 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[398]} 0 0
[junit4:junit4] 2> 726714 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[399]} 0 1
[junit4:junit4] 2> 726715 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[400]} 0 0
[junit4:junit4] 2> 726717 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[401]} 0 0
[junit4:junit4] 2> 726720 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[402]} 0 1
[junit4:junit4] 2> 726722 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[403]} 0 0
[junit4:junit4] 2> 726725 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[404]} 0 0
[junit4:junit4] 2> 726727 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[405]} 0 0
[junit4:junit4] 2> 726729 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[406]} 0 0
[junit4:junit4] 2> 726731 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[407]} 0 0
[junit4:junit4] 2> 726733 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[408]} 0 0
[junit4:junit4] 2> 726735 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[409]} 0 0
[junit4:junit4] 2> 726737 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[410]} 0 0
[junit4:junit4] 2> 726739 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[411]} 0 0
[junit4:junit4] 2> 726741 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[412]} 0 0
[junit4:junit4] 2> 726743 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[413]} 0 0
[junit4:junit4] 2> 726745 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[414]} 0 0
[junit4:junit4] 2> 726747 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[415]} 0 0
[junit4:junit4] 2> 726749 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[416]} 0 1
[junit4:junit4] 2> 726750 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[417]} 0 0
[junit4:junit4] 2> 726752 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[418]} 0 0
[junit4:junit4] 2> 726754 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[419]} 0 0
[junit4:junit4] 2> 726756 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[420]} 0 0
[junit4:junit4] 2> 726758 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[421]} 0 0
[junit4:junit4] 2> 726760 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[422]} 0 0
[junit4:junit4] 2> 726762 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[423]} 0 0
[junit4:junit4] 2> 726764 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[424]} 0 0
[junit4:junit4] 2> 726766 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[425]} 0 0
[junit4:junit4] 2> 726768 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[426]} 0 0
[junit4:junit4] 2> 726770 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[427]} 0 0
[junit4:junit4] 2> 726772 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[428]} 0 0
[junit4:junit4] 2> 726774 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[429]} 0 0
[junit4:junit4] 2> 726776 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[430]} 0 0
[junit4:junit4] 2> 726778 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[431]} 0 0
[junit4:junit4] 2> 726780 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[432]} 0 0
[junit4:junit4] 2> 726782 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[433]} 0 0
[junit4:junit4] 2> 726784 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[434]} 0 0
[junit4:junit4] 2> 726787 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[435]} 0 1
[junit4:junit4] 2> 726789 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[436]} 0 1
[junit4:junit4] 2> 726791 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[437]} 0 0
[junit4:junit4] 2> 726793 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[438]} 0 0
[junit4:junit4] 2> 726795 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[439]} 0 0
[junit4:junit4] 2> 726797 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[440]} 0 1
[junit4:junit4] 2> 726799 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[441]} 0 1
[junit4:junit4] 2> 726801 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[442]} 0 1
[junit4:junit4] 2> 726803 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[443]} 0 1
[junit4:junit4] 2> 726804 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[444]} 0 0
[junit4:junit4] 2> 726806 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[445]} 0 0
[junit4:junit4] 2> 726808 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[446]} 0 0
[junit4:junit4] 2> 726810 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[447]} 0 0
[junit4:junit4] 2> 726812 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[448]} 0 0
[junit4:junit4] 2> 726814 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[449]} 0 0
[junit4:junit4] 2> 726816 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[450]} 0 0
[junit4:junit4] 2> 726818 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[451]} 0 0
[junit4:junit4] 2> 726820 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[452]} 0 0
[junit4:junit4] 2> 726822 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[453]} 0 0
[junit4:junit4] 2> 726824 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[454]} 0 0
[junit4:junit4] 2> 726826 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[455]} 0 0
[junit4:junit4] 2> 726828 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[456]} 0 0
[junit4:junit4] 2> 726830 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[457]} 0 0
[junit4:junit4] 2> 726833 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[458]} 0 1
[junit4:junit4] 2> 726835 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[459]} 0 0
[junit4:junit4] 2> 726837 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[460]} 0 0
[junit4:junit4] 2> 726839 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[461]} 0 0
[junit4:junit4] 2> 726842 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[462]} 0 1
[junit4:junit4] 2> 726844 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[463]} 0 1
[junit4:junit4] 2> 726846 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[464]} 0 1
[junit4:junit4] 2> 726847 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[465]} 0 0
[junit4:junit4] 2> 726850 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[466]} 0 1
[junit4:junit4] 2> 726851 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[467]} 0 0
[junit4:junit4] 2> 726853 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[468]} 0 0
[junit4:junit4] 2> 726855 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[469]} 0 0
[junit4:junit4] 2> 726857 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[470]} 0 0
[junit4:junit4] 2> 726859 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[471]} 0 0
[junit4:junit4] 2> 726861 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[472]} 0 0
[junit4:junit4] 2> 726863 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[473]} 0 1
[junit4:junit4] 2> 726864 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[474]} 0 0
[junit4:junit4] 2> 726866 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[475]} 0 0
[junit4:junit4] 2> 726868 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[476]} 0 0
[junit4:junit4] 2> 726870 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[477]} 0 0
[junit4:junit4] 2> 726872 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[478]} 0 0
[junit4:junit4] 2> 726873 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[479]} 0 0
[junit4:junit4] 2> 726875 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[480]} 0 0
[junit4:junit4] 2> 726877 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[481]} 0 0
[junit4:junit4] 2> 726879 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[482]} 0 0
[junit4:junit4] 2> 726881 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[483]} 0 0
[junit4:junit4] 2> 726882 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[484]} 0 0
[junit4:junit4] 2> 726884 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[485]} 0 0
[junit4:junit4] 2> 726886 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[486]} 0 0
[junit4:junit4] 2> 726888 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[487]} 0 0
[junit4:junit4] 2> 726890 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[488]} 0 0
[junit4:junit4] 2> 726890 T2673 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 726892 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[489]} 0 1
[junit4:junit4] 2> 726894 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[490]} 0 1
[junit4:junit4] 2> 726895 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[491]} 0 0
[junit4:junit4] 2> 726897 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[492]} 0 0
[junit4:junit4] 2> 726899 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[493]} 0 0
[junit4:junit4] 2> 726901 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[494]} 0 0
[junit4:junit4] 2> 726903 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[495]} 0 0
[junit4:junit4] 2> 726905 T2673 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[496]} 0 0
[junit4:junit4] 2> 726907 T2672 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[497]} 0 0
[junit4:junit4] 2> 726909 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[498]} 0 1
[junit4:junit4] 2> 726910 T2675 C1934 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 726918 T2675 C1934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@130eff16 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62399a23),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@130eff16 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62399a23),segFN=segments_2,generation=2}
[junit4:junit4] 2> 726918 T2675 C1934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 726919 T2675 C1934 oass.SolrIndexSearcher.<init> Opening Searcher@62458b61 main
[junit4:junit4] 2> 726919 T2675 C1934 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 726919 T2677 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62458b61 main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C499)}
[junit4:junit4] 2> 726920 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4] 2> 726925 T2673 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=499 status=0 QTime=3
[junit4:junit4] 2> ASYNC NEW_CORE C1935 name=collection1 org.apache.solr.core.SolrCore@1b26ec25
[junit4:junit4] 2> 726932 T2687 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 726932 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727035 T2685 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 727035 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727138 T2686 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 727139 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727242 T2687 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 727243 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727345 T2684 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 727346 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727448 T2687 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 727448 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727551 T2685 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 727552 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727654 T2684 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 727654 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727757 T2686 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 727757 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727860 T2685 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 727860 T2666 oash.TestReplicationHandler.rQuery Waiting for 499 docs
[junit4:junit4] 2> 727889 T2675 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 727890 T2690 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4] 2> 727891 T2690 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4] 2> 727891 T2690 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 727895 T2672 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=3
[junit4:junit4] 2> 727896 T2690 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 8
[junit4:junit4] 2> 727897 T2690 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164941009
[junit4:junit4] 2> 727897 T2690 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@13f2c67b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17249727) fullCopy=false
[junit4:junit4] 2> 727899 T2674 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 727902 T2673 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Memory_0.ram&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1
[junit4:junit4] 2> 727904 T2675 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 727907 T2672 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1
[junit4:junit4] 2> 727909 T2674 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 727912 T2673 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 727914 T2675 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 727917 T2672 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 727918 T2690 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 727919 T2690 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 727920 T2690 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 727920 T2690 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@373e8ad9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@242aac18),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@373e8ad9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@242aac18),segFN=segments_2,generation=2}
[junit4:junit4] 2> 727921 T2690 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 727921 T2690 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 727922 T2690 oass.SolrIndexSearcher.<init> Opening Searcher@51cc1b24 main
[junit4:junit4] 2> 727922 T2689 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@51cc1b24 main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C499)}
[junit4:junit4] 2> 727922 T2690 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164941009 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164941009;done=true>>]
[junit4:junit4] 2> 727922 T2690 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164941009
[junit4:junit4] 2> 727923 T2690 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164941009
[junit4:junit4] 2> 727965 T2684 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=499 status=0 QTime=3
[junit4:junit4] 2> 727968 T2674 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 727970 T2675 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 727975 T2672 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4:junit4] 2> 727976 T2687 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4:junit4] 2> 727977 T2673 C1934 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 727979 T2686 C1935 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 727981 T2674 C1934 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 727983 T2674 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 2
[junit4:junit4] 2> 727985 T2675 C1934 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 727985 T2675 C1934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@130eff16 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62399a23),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@130eff16 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62399a23),segFN=segments_2,generation=2}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@130eff16 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62399a23),segFN=segments_3,generation=3}
[junit4:junit4] 2> 727986 T2675 C1934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4:junit4] 2> 727986 T2675 C1934 oass.SolrIndexSearcher.<init> Opening Searcher@19c4105c main
[junit4:junit4] 2> 727987 T2675 C1934 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 727987 T2677 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19c4105c main{StandardDirectoryReader(segments_3:4:nrt)}
[junit4:junit4] 2> 727987 T2675 C1934 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 2
[junit4:junit4] 2> 728010 T2666 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2019816104
[junit4:junit4] 2> 728011 T2666 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@ae46d8b
[junit4:junit4] 2> 728011 T2666 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=499,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4] 2> 728012 T2666 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 728012 T2666 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 728013 T2666 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 728013 T2666 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 728014 T2666 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 728014 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data;done=false>>]
[junit4:junit4] 2> 728014 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data
[junit4:junit4] 2> 728015 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index;done=false>>]
[junit4:junit4] 2> 728015 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index
[junit4:junit4] 2> 728016 T2666 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 728068 T2666 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 728070 T2666 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60008
[junit4:junit4] 2> 728070 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 728071 T2666 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 728071 T2666 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master
[junit4:junit4] 2> 728071 T2666 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/solr.xml
[junit4:junit4] 2> 728072 T2666 oasc.CoreContainer.<init> New CoreContainer 488334200
[junit4:junit4] 2> 728072 T2666 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 728072 T2666 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/'
[junit4:junit4] 2> 728073 T2666 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/'
[junit4:junit4] 2> 728112 T2666 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 728112 T2666 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 728112 T2666 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 728113 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 728113 T2666 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 728113 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 728114 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 728114 T2666 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 728114 T2666 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 728114 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 728117 T2666 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 728122 T2702 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1
[junit4:junit4] 2> 728122 T2702 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/'
[junit4:junit4] 2> 728148 T2702 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4:junit4] 2> 728182 T2702 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 728183 T2702 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 728184 T2702 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 728194 T2702 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 728194 T2702 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 728195 T2702 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/
[junit4:junit4] 2> 728195 T2702 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 728195 T2702 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 728195 T2702 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data
[junit4:junit4] 2> 728196 T2702 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index/
[junit4:junit4] 2> 728196 T2702 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 728196 T2702 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index
[junit4:junit4] 2> 728197 T2702 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@137a5ec8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a1871df),segFN=segments_1,generation=1}
[junit4:junit4] 2> 728197 T2702 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 728198 T2702 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 728198 T2702 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 728198 T2702 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 728199 T2702 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 728199 T2702 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 728199 T2702 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 728200 T2702 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 728202 T2702 oass.SolrIndexSearcher.<init> Opening Searcher@2a377619 main
[junit4:junit4] 2> 728202 T2702 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 728202 T2702 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 728202 T2702 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4] 2> 728203 T2702 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 728203 T2702 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 728203 T2703 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2a377619 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 728203 T2666 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 728204 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 728204 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 728229 T2666 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1261518365
[junit4:junit4] 2> 728230 T2666 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1b26ec25
[junit4:junit4] 2> 728230 T2666 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> 728231 T2666 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 728231 T2666 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 728232 T2666 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 728232 T2666 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 728233 T2666 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 728233 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index;done=false>>]
[junit4:junit4] 2> 728233 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index
[junit4:junit4] 2> 728234 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data;done=false>>]
[junit4:junit4] 2> 728234 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data
[junit4:junit4] 2> 728234 T2666 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 728288 T2666 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 728289 T2666 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:36178
[junit4:junit4] 2> 728290 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 728290 T2666 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 728291 T2666 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave
[junit4:junit4] 2> 728291 T2666 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/solr.xml
[junit4:junit4] 2> 728291 T2666 oasc.CoreContainer.<init> New CoreContainer 1221836094
[junit4:junit4] 2> 728292 T2666 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 728292 T2666 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/'
[junit4:junit4] 2> 728292 T2666 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/'
[junit4:junit4] 2> 728329 T2666 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 728330 T2666 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 728330 T2666 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 728330 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 728331 T2666 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 728331 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 728331 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 728332 T2666 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 728332 T2666 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 728332 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 728335 T2666 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 728338 T2714 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1
[junit4:junit4] 2> 728338 T2714 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/'
[junit4:junit4] 2> 728362 T2714 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4:junit4] 2> 728390 T2714 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 728390 T2714 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 728392 T2714 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 728401 T2714 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 728402 T2714 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 728403 T2714 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/
[junit4:junit4] 2> 728403 T2714 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 728403 T2714 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 728403 T2714 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data
[junit4:junit4] 2> 728403 T2714 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index/
[junit4:junit4] 2> 728404 T2714 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 728404 T2714 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index
[junit4:junit4] 2> 728405 T2714 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2bbb63e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62b3d4ff),segFN=segments_1,generation=1}
[junit4:junit4] 2> 728405 T2714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 728406 T2714 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 728406 T2714 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 728406 T2714 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 728407 T2714 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 728407 T2714 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 728407 T2714 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 728408 T2714 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 728410 T2714 oass.SolrIndexSearcher.<init> Opening Searcher@5190f65c main
[junit4:junit4] 2> 728410 T2714 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 728411 T2714 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 728411 T2714 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4] 2> 728411 T2714 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 728411 T2715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5190f65c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 728412 T2666 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 728412 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 728413 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C1936 name=collection1 org.apache.solr.core.SolrCore@48c6f1e8
[junit4:junit4] 2> 728421 T2698 C1936 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@137a5ec8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a1871df),segFN=segments_1,generation=1}
[junit4:junit4] 2> 728422 T2698 C1936 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 728422 T2698 C1936 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2000]} 0 2
[junit4:junit4] 2> 728424 T2699 C1936 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 728426 T2699 C1936 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@137a5ec8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a1871df),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@137a5ec8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a1871df),segFN=segments_2,generation=2}
[junit4:junit4] 2> 728427 T2699 C1936 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 728428 T2699 C1936 oass.SolrIndexSearcher.<init> Opening Searcher@6c351b08 main
[junit4:junit4] 2> 728428 T2699 C1936 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 728428 T2703 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6c351b08 main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C1)}
[junit4:junit4] 2> 728429 T2699 C1936 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4:junit4] 2> 728431 T2700 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 728433 T2701 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 728435 T2698 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 728441 T2699 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> ASYNC NEW_CORE C1937 name=collection1 org.apache.solr.core.SolrCore@6ee7baeb
[junit4:junit4] 2> 728442 T2710 C1937 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=2
[junit4:junit4] 2> 728444 T2700 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 728446 T2711 C1937 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 728448 T2712 C1937 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 728449 T2666 oash.TestReplicationHandler.rQuery Waiting for 1 docs
[junit4:junit4] 2> 728551 T2713 C1937 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 728552 T2666 oash.TestReplicationHandler.rQuery Waiting for 1 docs
[junit4:junit4] 2> 728654 T2710 C1937 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 728655 T2666 oash.TestReplicationHandler.rQuery Waiting for 1 docs
[junit4:junit4] 2> 728757 T2712 C1937 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 728758 T2666 oash.TestReplicationHandler.rQuery Waiting for 1 docs
[junit4:junit4] 2> 728860 T2710 C1937 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 2> 728860 T2666 oash.TestReplicationHandler.rQuery Waiting for 1 docs
[junit4:junit4] 2> 728889 T2699 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 728889 T2716 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4] 2> 728890 T2716 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4] 2> 728890 T2716 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 728892 T2700 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=1
[junit4:junit4] 2> 728893 T2716 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 8
[junit4:junit4] 2> 728894 T2716 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164942006
[junit4:junit4] 2> 728894 T2716 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@1f6499 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ba9ca2f) fullCopy=false
[junit4:junit4] 2> 728896 T2701 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1
[junit4:junit4] 2> 728898 T2698 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Memory_0.ram&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 728901 T2699 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvd&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 728904 T2700 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 728907 T2701 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 728909 T2698 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvm&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 728912 T2699 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 728914 T2700 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 728915 T2716 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 728916 T2716 oash.SnapPuller.downloadConfFiles Starting download of configuration files from master: [{name=schema.xml, checksum=1505963717, size=1634}, {name=xslt/dummy.xsl, checksum=1, size=0}]
[junit4:junit4] 2> 728921 T2701 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&compression=true&wt=filestream&cf=schema.xml&qt=/replication&generation=2} status=0 QTime=1
[junit4:junit4] 2> 728926 T2698 C1936 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&compression=true&wt=filestream&cf=xslt/dummy.xsl&qt=/replication&generation=2} status=0 QTime=0
[junit4:junit4] 2> 728928 T2716 oash.SnapPuller$LocalFsFileFetcher.fetchPackets WARN No content recieved for file: {name=xslt/dummy.xsl, checksum=1, size=0}
[junit4:junit4] 2> 728962 T2711 C1937 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 728963 T2666 oash.TestReplicationHandler.rQuery Waiting for 1 docs
[junit4:junit4] 2> 729065 T2716 oash.SnapPuller.fetchLatestIndex Configuration files are modified, core will be reloaded
[junit4:junit4] 2> 729065 T2713 C1937 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 729066 T2666 oash.TestReplicationHandler.rQuery Waiting for 1 docs
[junit4:junit4] 2> 729066 T2716 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 729067 T2719 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1
[junit4:junit4] 2> 729067 T2716 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 729067 T2719 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/'
[junit4:junit4] 2> 729068 T2716 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2bbb63e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62b3d4ff),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2bbb63e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62b3d4ff),segFN=segments_2,generation=2}
[junit4:junit4] 2> 729068 T2716 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 729068 T2716 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 729069 T2716 oass.SolrIndexSearcher.<init> Opening Searcher@15c2a24a main
[junit4:junit4] 2> 729069 T2715 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15c2a24a main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C1)}
[junit4:junit4] 2> 729070 T2716 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164942006 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164942006;done=true>>]
[junit4:junit4] 2> 729070 T2716 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164942006
[junit4:junit4] 2> 729070 T2716 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index.20130529164942006
[junit4:junit4] 2> 729100 T2719 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4:junit4] 2> 729127 T2719 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 729128 T2719 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 729129 T2719 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 729139 T2719 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 729140 T2719 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/
[junit4:junit4] 2> 729141 T2719 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 729141 T2719 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index/
[junit4:junit4] 2> 729141 T2719 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 729142 T2719 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 729142 T2719 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 729142 T2719 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 729142 T2719 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 729142 T2719 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 729144 T2719 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 729146 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@6b049834 main
[junit4:junit4] 2> 729147 T2719 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 729147 T2719 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 729147 T2719 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4] 2> 729147 T2719 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 729147 T2720 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6b049834 main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C1)}
[junit4:junit4] 2> 729148 T2719 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 729148 T2719 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4] 2> 729149 T2719 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2bbb63e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62b3d4ff),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2bbb63e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62b3d4ff),segFN=segments_2,generation=2}
[junit4:junit4] 2> 729149 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 729149 T2719 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 729150 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@71e89139 main
[junit4:junit4] 2> 729150 T2719 oasc.CoreContainer.registerCore replacing core: collection1
[junit4:junit4] 2> 729150 T2720 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@71e89139 main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C1)}
[junit4:junit4] 2> 729150 T2719 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6ee7baeb
[junit4:junit4] 2> 729151 T2719 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> 729151 T2719 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> ASYNC NEW_CORE C1938 name=collection1 org.apache.solr.core.SolrCore@5b966f9b
[junit4:junit4] 2> 729172 T2712 C1938 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 729173 T2666 oas.SolrTestCaseJ4.tearDown ###Ending doTestIndexAndConfigReplication
[junit4:junit4] 2> 729196 T2666 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=488334200
[junit4:junit4] 2> 729196 T2666 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@48c6f1e8
[junit4:junit4] 2> 729197 T2666 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=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 729198 T2666 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 729198 T2666 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 729199 T2666 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 729200 T2666 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 729201 T2666 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 729201 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index;done=false>>]
[junit4:junit4] 2> 729202 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data/index
[junit4:junit4] 2> 729202 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data;done=false>>]
[junit4:junit4] 2> 729203 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578704/master/collection1/data
[junit4:junit4] 2> 729203 T2666 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 729277 T2666 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1221836094
[junit4:junit4] 2> 729278 T2666 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5b966f9b
[junit4:junit4] 2> 729278 T2666 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> 729279 T2666 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 729279 T2666 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 729279 T2666 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 729280 T2666 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 729281 T2666 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 729281 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data;done=false>>]
[junit4:junit4] 2> 729281 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data
[junit4:junit4] 2> 729281 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index;done=false>>]
[junit4:junit4] 2> 729282 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860578859/slave/collection1/data/index
[junit4:junit4] 2> 729282 T2666 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 729343 T2666 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
[junit4:junit4] 2> 729346 T2666 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 729348 T2666 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53328
[junit4:junit4] 2> 729348 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 729349 T2666 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 729349 T2666 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master
[junit4:junit4] 2> 729349 T2666 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/solr.xml
[junit4:junit4] 2> 729349 T2666 oasc.CoreContainer.<init> New CoreContainer 748734593
[junit4:junit4] 2> 729350 T2666 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 729350 T2666 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/'
[junit4:junit4] 2> 729350 T2666 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/'
[junit4:junit4] 2> 729392 T2666 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 729392 T2666 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 729393 T2666 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 729393 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 729393 T2666 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 729394 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 729394 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 729394 T2666 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 729395 T2666 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 729395 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 729398 T2666 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 729401 T2731 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1
[junit4:junit4] 2> 729401 T2731 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/'
[junit4:junit4] 2> 729425 T2731 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4:junit4] 2> 729454 T2731 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 729454 T2731 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 729456 T2731 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 729466 T2731 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 729467 T2731 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 729467 T2731 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/
[junit4:junit4] 2> 729467 T2731 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 729467 T2731 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 729468 T2731 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data
[junit4:junit4] 2> 729468 T2731 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/index/
[junit4:junit4] 2> 729468 T2731 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 729468 T2731 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/index
[junit4:junit4] 2> 729469 T2731 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@d6e5779 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@76f2790f),segFN=segments_1,generation=1}
[junit4:junit4] 2> 729469 T2731 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 729470 T2731 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 729470 T2731 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 729471 T2731 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 729471 T2731 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 729471 T2731 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 729471 T2731 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 729473 T2731 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 729475 T2731 oass.SolrIndexSearcher.<init> Opening Searcher@3c00019 main
[junit4:junit4] 2> 729475 T2731 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 729475 T2731 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 729475 T2731 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4] 2> 729476 T2731 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 729476 T2731 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 729476 T2732 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c00019 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 729477 T2666 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 729477 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 729478 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 729483 T2666 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 729485 T2666 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58148
[junit4:junit4] 2> 729486 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 729486 T2666 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 729487 T2666 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave
[junit4:junit4] 2> 729487 T2666 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/solr.xml
[junit4:junit4] 2> 729488 T2666 oasc.CoreContainer.<init> New CoreContainer 786819070
[junit4:junit4] 2> 729488 T2666 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 729489 T2666 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/'
[junit4:junit4] 2> 729489 T2666 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/'
[junit4:junit4] 2> 729551 T2666 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 729552 T2666 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 729552 T2666 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 729553 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 729553 T2666 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 729554 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 729554 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 729555 T2666 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 729555 T2666 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 729556 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 729559 T2666 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 729563 T2743 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1
[junit4:junit4] 2> 729564 T2743 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/'
[junit4:junit4] 2> 729592 T2743 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4:junit4] 2> 729619 T2743 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 729620 T2743 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 729621 T2743 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 729631 T2743 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 729632 T2743 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 729632 T2743 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/
[junit4:junit4] 2> 729632 T2743 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 729632 T2743 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 729633 T2743 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data
[junit4:junit4] 2> 729633 T2743 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/index/
[junit4:junit4] 2> 729633 T2743 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 729634 T2743 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/index
[junit4:junit4] 2> 729635 T2743 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1af92780 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@149e0dc3),segFN=segments_1,generation=1}
[junit4:junit4] 2> 729635 T2743 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 729635 T2743 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 729636 T2743 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 729636 T2743 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 729636 T2743 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 729636 T2743 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 729636 T2743 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 729637 T2743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 729639 T2743 oass.SolrIndexSearcher.<init> Opening Searcher@6fc847bb main
[junit4:junit4] 2> 729640 T2743 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 729640 T2743 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 729640 T2743 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4] 2> 729640 T2743 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 729640 T2744 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6fc847bb main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 729641 T2666 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 729642 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 729642 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 729668 T2666 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=786819070
[junit4:junit4] 2> 729669 T2666 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3b060aa1
[junit4:junit4] 2> 729669 T2666 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> 729670 T2666 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 729670 T2666 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 729670 T2666 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 729671 T2666 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 729672 T2666 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 729672 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/index;done=false>>]
[junit4:junit4] 2> 729672 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/index
[junit4:junit4] 2> 729673 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data;done=false>>]
[junit4:junit4] 2> 729673 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data
[junit4:junit4] 2> 729673 T2666 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 729747 T2666 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=748734593
[junit4:junit4] 2> 729748 T2666 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@40b81cc8
[junit4:junit4] 2> 729748 T2666 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> 729749 T2666 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 729749 T2666 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 729749 T2666 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 729750 T2666 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 729750 T2666 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 729751 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data;done=false>>]
[junit4:junit4] 2> 729751 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data
[junit4:junit4] 2> 729751 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/index;done=false>>]
[junit4:junit4] 2> 729751 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/index
[junit4:junit4] 2> 729752 T2666 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 729805 T2666 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 729807 T2666 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46688
[junit4:junit4] 2> 729808 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 729808 T2666 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 729809 T2666 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master
[junit4:junit4] 2> 729809 T2666 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/solr.xml
[junit4:junit4] 2> 729809 T2666 oasc.CoreContainer.<init> New CoreContainer 1761702997
[junit4:junit4] 2> 729810 T2666 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 729810 T2666 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/'
[junit4:junit4] 2> 729810 T2666 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/'
[junit4:junit4] 2> 729847 T2666 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 729848 T2666 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 729848 T2666 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 729848 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 729848 T2666 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 729849 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 729849 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 729849 T2666 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 729850 T2666 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 729850 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 729853 T2666 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 729857 T2756 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1
[junit4:junit4] 2> 729857 T2756 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/'
[junit4:junit4] 2> 729880 T2756 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4:junit4] 2> 729912 T2756 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 729912 T2756 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 729914 T2756 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 729924 T2756 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 729925 T2756 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 729925 T2756 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/
[junit4:junit4] 2> 729926 T2756 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 729926 T2756 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 729926 T2756 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data
[junit4:junit4] 2> 729927 T2756 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/index/
[junit4:junit4] 2> 729927 T2756 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 729927 T2756 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582457/master/collection1/data/index
[junit4:junit4] 2> 729928 T2756 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5c1d3ee2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@343242ef),segFN=segments_1,generation=1}
[junit4:junit4] 2> 729928 T2756 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 729929 T2756 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 729929 T2756 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 729930 T2756 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 729930 T2756 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 729930 T2756 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 729930 T2756 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 729931 T2756 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 729933 T2756 oass.SolrIndexSearcher.<init> Opening Searcher@72161768 main
[junit4:junit4] 2> 729934 T2756 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 729934 T2756 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 729934 T2756 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4] 2> 729935 T2756 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5c1d3ee2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@343242ef),segFN=segments_1,generation=1}
[junit4:junit4] 2> 729935 T2756 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 729935 T2756 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 729935 T2756 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 729935 T2757 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@72161768 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 729936 T2666 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 729937 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 729937 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C1939 name=collection1 org.apache.solr.core.SolrCore@6a45642e
[junit4:junit4] 2> 729945 T2752 C1939 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4] 2> 729946 T2752 C1939 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4:junit4] 2> 729948 T2753 C1939 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 729949 T2753 C1939 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5c1d3ee2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@343242ef),segFN=segments_1,generation=1}
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5c1d3ee2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@343242ef),segFN=segments_2,generation=2}
[junit4:junit4] 2> 729949 T2753 C1939 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4] 2> 729950 T2753 C1939 oass.SolrIndexSearcher.<init> Opening Searcher@4b24c3b0 main
[junit4:junit4] 2> 729950 T2753 C1939 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 729950 T2757 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4b24c3b0 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4:junit4] 2> 729951 T2753 C1939 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 3
[junit4:junit4] 2> 729953 T2754 C1939 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 2> 729955 T2666 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4] 2> 729956 T2666 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54637
[junit4:junit4] 2> 729957 T2666 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 729957 T2666 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 729957 T2666 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave
[junit4:junit4] 2> 729958 T2666 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/solr.xml
[junit4:junit4] 2> 729958 T2666 oasc.CoreContainer.<init> New CoreContainer 2039939546
[junit4:junit4] 2> 729958 T2666 oasc.CoreContainer$Initializer.initialize no solr.xml found. using default old-style solr.xml
[junit4:junit4] 2> 729959 T2666 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/'
[junit4:junit4] 2> 729959 T2666 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/'
[junit4:junit4] 2> 729995 T2666 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4] 2> 729995 T2666 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 729996 T2666 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4] 2> 729996 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 729996 T2666 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 729996 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 729997 T2666 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 729997 T2666 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 729997 T2666 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 729998 T2666 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 2> 730000 T2666 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4] 2> 730003 T2768 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1
[junit4:junit4] 2> 730003 T2768 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/'
[junit4:junit4] 2> 730038 T2768 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4:junit4] 2> 730081 T2768 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 730081 T2768 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4] 2> 730084 T2768 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4] 2> 730096 T2768 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 730097 T2768 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 730097 T2768 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/
[junit4:junit4] 2> 730098 T2768 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4] 2> 730098 T2768 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 730098 T2768 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data
[junit4:junit4] 2> 730098 T2768 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/index/
[junit4:junit4] 2> 730099 T2768 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 730099 T2768 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860582593/slave/collection1/data/index
[junit4:junit4] 2> 730100 T2768 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@42b4ed35 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e071894),segFN=segments_1,generation=1}
[junit4:junit4] 2> 730100 T2768 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4] 2> 730101 T2768 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 730101 T2768 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 730101 T2768 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 730101 T2768 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 730102 T2768 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 730102 T2768 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 730103 T2768 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 730105 T2768 oass.SolrIndexSearcher.<init> Opening Searcher@5eedaba0 main
[junit4:junit4] 2> 730105 T2768 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 730105 T2768 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 730105 T2768 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4] 2> 730106 T2768 oasc.CoreContainer.registerCore registering core: collection1
[
[...truncated too long message...]
nit4] 2> 791194 T3262 C1996 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 791196 T3284 C1995 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 791199 T3287 C1995 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4:junit4] 2> 791200 T3259 C1996 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=3
[junit4:junit4] 2> 791204 T3261 C1996 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4:junit4] 2> 791204 T3285 C1995 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=2
[junit4:junit4] 2> 791205 T2666 oas.SolrTestCaseJ4.tearDown ###Ending doTestSnapPullWithMasterUrl
[junit4:junit4] 2> 791226 T2666 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1874870175
[junit4:junit4] 2> 791227 T2666 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4241724c
[junit4:junit4] 2> 791227 T2666 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=496,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4] 2> 791228 T2666 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 791228 T2666 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 791229 T2666 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 791230 T2666 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 791231 T2666 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 791232 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642161/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642161/master/collection1/data;done=false>>]
[junit4:junit4] 2> 791232 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642161/master/collection1/data
[junit4:junit4] 2> 791233 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642161/master/collection1/data/index.20130529165044152 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642161/master/collection1/data/index.20130529165044152;done=false>>]
[junit4:junit4] 2> 791233 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642161/master/collection1/data/index.20130529165044152
[junit4:junit4] 2> 791234 T2666 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 791305 T2666 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=420249101
[junit4:junit4] 2> 791306 T2666 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5aa1a3a3
[junit4:junit4] 2> 791306 T2666 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4] 2> 791307 T2666 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4] 2> 791308 T2666 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 2> 791308 T2666 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4] 2> 791309 T2666 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 791310 T2666 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 2> 791311 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642306/slave/collection1/data/index.20130529165043820 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642306/slave/collection1/data/index.20130529165043820;done=false>>]
[junit4:junit4] 2> 791311 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642306/slave/collection1/data/index.20130529165043820
[junit4:junit4] 2> 791312 T2666 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642306/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642306/slave/collection1/data;done=false>>]
[junit4:junit4] 2> 791312 T2666 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1369860642306/slave/collection1/data
[junit4:junit4] 2> 791313 T2666 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 791369 T2666 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Lucene42: {newname=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=Memory doPackFST= false), name=PostingsFormat(name=Memory doPackFST= false)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=es_PE, timezone=America/Martinique
[junit4:junit4] 2> NOTE: Linux 3.2.0-44-generic amd64/Sun Microsystems Inc. 1.6.0_45 (64-bit)/cpus=8,threads=1,free=216540560,total=394002432
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestPropInject, TestCodecSupport, RequestHandlersTest, MultiTermTest, CurrencyFieldXmlFileTest, CollectionsAPIDistributedZkTest, DateMathParserTest, TestDefaultSearchFieldResource, XmlUpdateRequestHandlerTest, TestMergePolicyConfig, OpenCloseCoreStressTest, DefaultValueUpdateProcessorTest, DocumentAnalysisRequestHandlerTest, TestFieldTypeResource, PingRequestHandlerTest, TestAnalyzedSuggestions, XsltUpdateRequestHandlerTest, SuggesterTSTTest, TestLMJelinekMercerSimilarityFactory, PathHierarchyTokenizerFactoryTest, TestNumberUtils, IndexSchemaRuntimeFieldTest, TestFoldingMultitermQuery, FileUtilsTest, TestAtomicUpdateErrorCases, SignatureUpdateProcessorFactoryTest, TestSchemaSimilarityResource, StatsComponentTest, SortByFunctionTest, TestSolrQueryParserDefaultOperatorResource, TestIndexSearcher, NotRequiredUniqueKeyTest, HardAutoCommitTest, ZkSolrClientTest, CopyFieldTest, SolrCmdDistributorTest, TestSolrXMLSerializer, ClusterStateTest, TestSolrQueryParserResource, OutputWriterTest, TestSolr4Spatial, TestUniqueKeyFieldResource, TestCollationField, DisMaxRequestHandlerTest, OpenExchangeRatesOrgProviderTest, DOMUtilTest, TestDFRSimilarityFactory, SolrTestCaseJ4Test, TestOmitPositions, HighlighterConfigTest, ConvertedLegacyTest, ShardRoutingCustomTest, TestFastOutputStream, JSONWriterTest, TestDynamicFieldCollectionResource, TestSystemIdResolver, DistanceFunctionTest, UniqFieldsUpdateProcessorFactoryTest, TestArbitraryIndexDir, TestMultiCoreConfBootstrap, SpellCheckComponentTest, CoreContainerCoreInitFailuresTest, ZkNodePropsTest, IndexBasedSpellCheckerTest, SpellCheckCollatorTest, IndexReaderFactoryTest, TestBinaryField, SolrRequestParserTest, TestDistributedGrouping, RequiredFieldsTest, TestIndexingPerformance, StandardRequestHandlerTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4:junit4] Completed on J1 in 65.80s, 13 tests, 1 failure <<< FAILURES!
[...truncated 434 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:386: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:366: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:437: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1243: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:887: There were test failures: 296 suites, 1235 tests, 1 failure, 13 ignored (7 assumptions)
Total time: 40 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.6.0_45 -XX:-UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure