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 2015/04/30 09:44:20 UTC
[JENKINS] Lucene-Solr-5.x-MacOSX (64bit/jdk1.7.0) - Build # 2197 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/2197/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.testRateLimitedReplication
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([AFE2376513F7757E:297642901C45F493]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.handler.TestReplicationHandler.testRateLimitedReplication(TestReplicationHandler.java:1320)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
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:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
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:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10032 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/init-core-data-001
[junit4] 2> 813935 T4977 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterStartupWithNoActivity
[junit4] 2> 813936 T4977 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/collection1
[junit4] 2> 813955 T4977 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 813960 T4977 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@7ce5915a{/solr,null,AVAILABLE}
[junit4] 2> 813961 T4977 oejs.AbstractConnector.doStart Started ServerConnector@43a9ebc5{HTTP/1.1}{127.0.0.1:61821}
[junit4] 2> 813961 T4977 oejs.Server.doStart Started @818175ms
[junit4] 2> 813961 T4977 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=61821, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/collection1/data, hostContext=/solr}
[junit4] 2> 813962 T4977 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@5374d4c9
[junit4] 2> 813963 T4977 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/'
[junit4] 2> 814011 T4977 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/solr.xml
[junit4] 2> 814069 T4977 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/.
[junit4] 2> 814070 T4977 oasc.CoreContainer.<init> New CoreContainer 1847666771
[junit4] 2> 814070 T4977 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/]
[junit4] 2> 814071 T4977 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/lib
[junit4] 2> 814072 T4977 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/lib).
[junit4] 2> 814091 T4977 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 814102 T4977 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 814104 T4977 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 814104 T4977 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 814105 T4977 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 814107 T4977 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/.
[junit4] 2> 814109 T4977 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, dataDir=data/, schema=schema.xml, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1, transient=false, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/, config=solrconfig.xml}
[junit4] 2> 814109 T4977 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/
[junit4] 2> 814110 T4977 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 814111 T4989 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/'
[junit4] 2> 814171 T4989 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 814210 T4989 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 814271 T4989 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 814273 T4989 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/conf/schema.xml
[junit4] 2> 814289 T4989 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 814326 T4989 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 814327 T4989 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/
[junit4] 2> 814327 T4989 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 814328 T4989 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/], dataDir=[null]
[junit4] 2> 814328 T4989 x:collection1 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 814329 T4989 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data
[junit4] 2> 814329 T4989 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 814329 T4989 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 814330 T4989 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data/index
[junit4] 2> 814330 T4989 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=7.927734375, floorSegmentMB=1.4921875, forceMergeDeletesPctAllowed=6.235006423488137, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 814331 T4989 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@46629c35 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73fd7fac),segFN=segments_1,generation=1}
[junit4] 2> 814332 T4989 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 814336 T4989 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 814355 T4989 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 814357 T4989 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 814360 T4989 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 814362 T4989 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 814368 T4989 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 814369 T4989 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/logging,/admin/ping,/admin/plugins,lazy,/admin/luke,/update/csv,defaults,/update/json,/update/json/docs,/schema,/get,/update,/config,/replication,/admin/system,/admin/mbeans,/admin/segments,standard,/admin/file,/admin/threads,/admin/properties
[junit4] 2> 814370 T4989 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 814371 T4989 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 814371 T4989 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 814372 T4989 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=87.2275390625, floorSegmentMB=1.9990234375, forceMergeDeletesPctAllowed=18.912462623341362, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 814373 T4989 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@46629c35 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73fd7fac),segFN=segments_1,generation=1}
[junit4] 2> 814373 T4989 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 814373 T4989 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@900c5df[collection1] main
[junit4] 2> 814374 T4989 x:collection1 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/conf
[junit4] 2> 814374 T4989 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/conf}
[junit4] 2> 814374 T4989 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/conf
[junit4] 2> 814375 T4989 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 814375 T4989 x:collection1 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 814376 T4989 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 814376 T4990 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@900c5df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 814376 T4989 x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 814378 T4977 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 814379 T4977 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 814382 T4977 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/collection1
[junit4] 2> 814403 T4977 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 814409 T4977 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@2dcf4de7{/solr,null,AVAILABLE}
[junit4] 2> 814410 T4977 oejs.AbstractConnector.doStart Started ServerConnector@2cef41e2{HTTP/1.1}{127.0.0.1:61822}
[junit4] 2> 814410 T4977 oejs.Server.doStart Started @818624ms
[junit4] 2> 814410 T4977 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/collection1/data, hostPort=61822, hostContext=/solr}
[junit4] 2> 814411 T4977 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@5374d4c9
[junit4] 2> 814412 T4977 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/'
[junit4] 2> 814459 T4977 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/solr.xml
[junit4] 2> 814514 T4977 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/.
[junit4] 2> 814516 T4977 oasc.CoreContainer.<init> New CoreContainer 251327202
[junit4] 2> 814516 T4977 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/]
[junit4] 2> 814517 T4977 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/lib
[junit4] 2> 814518 T4977 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/lib).
[junit4] 2> 814540 T4977 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 814546 T4977 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 814547 T4977 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 814548 T4977 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 814548 T4977 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 814550 T4977 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/.
[junit4] 2> 814553 T4977 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/, schema=schema.xml, config=solrconfig.xml, name=collection1, loadOnStartup=true, dataDir=data/, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1, transient=false}
[junit4] 2> 814554 T4977 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/
[junit4] 2> 814556 T4977 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 814557 T5003 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/'
[junit4] 2> 814604 T5003 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 814633 T5003 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 814698 T5003 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 814699 T5003 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/conf/schema.xml
[junit4] 2> 814706 T5003 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 814726 T5003 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 814727 T5003 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/
[junit4] 2> 814727 T5003 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 814728 T5003 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/], dataDir=[null]
[junit4] 2> 814728 T5003 x:collection1 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 814729 T5003 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data
[junit4] 2> 814730 T5003 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 814730 T5003 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 814731 T5003 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data/index
[junit4] 2> 814731 T5003 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=7.927734375, floorSegmentMB=1.4921875, forceMergeDeletesPctAllowed=6.235006423488137, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 814732 T5003 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12e5fba2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38eb525f),segFN=segments_1,generation=1}
[junit4] 2> 814732 T5003 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 814735 T5003 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 814750 T5003 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 814752 T5003 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 814754 T5003 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 814757 T5003 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 814761 T5003 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 814761 T5003 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/logging,/admin/ping,/admin/plugins,lazy,/admin/luke,/update/csv,defaults,/update/json,/update/json/docs,/schema,/get,/update,/config,/replication,/admin/system,/admin/mbeans,/admin/segments,standard,/admin/file,/admin/threads,/admin/properties
[junit4] 2> 814763 T5003 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 814765 T5003 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 814765 T5003 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 814766 T5003 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=87.2275390625, floorSegmentMB=1.9990234375, forceMergeDeletesPctAllowed=18.912462623341362, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 814767 T5003 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12e5fba2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38eb525f),segFN=segments_1,generation=1}
[junit4] 2> 814767 T5003 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 814768 T5003 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@6d5724d8[collection1] main
[junit4] 2> 814768 T5003 x:collection1 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/conf
[junit4] 2> 814769 T5003 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/conf}
[junit4] 2> 814769 T5003 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/conf
[junit4] 2> 814770 T5003 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 814777 T5003 x:collection1 oash.ReplicationHandler.setupPolling Poll Scheduled at an interval of 1000ms
[junit4] 2> 814777 T5003 x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 814777 T5004 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6d5724d8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 814779 T4977 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 814779 T4977 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 814781 T4977 oejs.AbstractConnector.doStop Stopped ServerConnector@2cef41e2{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 814781 T4977 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=251327202
[junit4] 2> 814782 T4977 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7bfceaac
[junit4] 2> 814783 T4977 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] 2> 814783 T4977 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 814784 T4977 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 814784 T4977 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 814787 T4977 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 814788 T4977 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 814789 T4977 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data/index;done=false>>]
[junit4] 2> 814789 T4977 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data/index
[junit4] 2> 814789 T4977 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data;done=false>>]
[junit4] 2> 814790 T4977 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-002/./collection1/data
[junit4] 2> 814791 T4977 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@2dcf4de7{/solr,null,UNAVAILABLE}
[junit4] 2> ASYNC NEW_CORE C1400 name=collection1 org.apache.solr.core.SolrCore@6e1a991
[junit4] 2> 814798 T4981 x:collection1 C1400 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 814799 T4981 x:collection1 C1400 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:*} 0 2
[junit4] 2> 814803 T4982 x:collection1 C1400 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 814805 T4982 x:collection1 C1400 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@46629c35 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73fd7fac),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@46629c35 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73fd7fac),segFN=segments_2,generation=2}
[junit4] 2> 814805 T4982 x:collection1 C1400 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 814807 T4982 x:collection1 C1400 oass.SolrIndexSearcher.<init> Opening Searcher@68c45ffe[collection1] main
[junit4] 2> 814807 T4982 x:collection1 C1400 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 814808 T4990 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@68c45ffe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 814809 T4982 x:collection1 C1400 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&commit=true&softCommit=false&waitSearcher=true&wt=javabin} {commit=} 0 6
[junit4] 2> 814815 T4977 oejs.AbstractConnector.doStop Stopped ServerConnector@43a9ebc5{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 814815 T4977 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1847666771
[junit4] 2> 814816 T4977 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6e1a991
[junit4] 2> 814816 T4977 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 814817 T4977 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 814818 T4977 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 814818 T4977 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 814820 T4977 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 814821 T4977 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 814822 T4977 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data/index;done=false>>]
[junit4] 2> 814823 T4977 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data/index
[junit4] 2> 814823 T4977 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data;done=false>>]
[junit4] 2> 814824 T4977 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/data
[junit4] 2> 814825 T4977 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@7ce5915a{/solr,null,UNAVAILABLE}
[junit4] 2> 814831 T4977 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 814835 T4977 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@623397c7{/solr,null,AVAILABLE}
[junit4] 2> 814837 T4977 oejs.AbstractConnector.doStart Started ServerConnector@2931bc2f{HTTP/1.1}{127.0.0.1:61824}
[junit4] 2> 814837 T4977 oejs.Server.doStart Started @819051ms
[junit4] 2> 814838 T4977 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/collection1/data, hostPort=61824, hostContext=/solr}
[junit4] 2> 814838 T4977 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@5374d4c9
[junit4] 2> 814839 T4977 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/'
[junit4] 2> 814894 T4977 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/solr.xml
[junit4] 2> 814965 T4977 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/.
[junit4] 2> 814966 T4977 oasc.CoreContainer.<init> New CoreContainer 42372106
[junit4] 2> 814966 T4977 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/]
[junit4] 2> 814966 T4977 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/lib
[junit4] 2> 814967 T4977 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/lib).
[junit4] 2> 814986 T4977 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 814994 T4977 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 814995 T4977 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 814996 T4977 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 814997 T4977 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 814998 T4977 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/.
[junit4] 2> 815000 T4977 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1, transient=false, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/, name=collection1, schema=schema.xml, loadOnStartup=true, dataDir=data/, config=solrconfig.xml}
[junit4] 2> 815001 T4977 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/
[junit4] 2> 815002 T4977 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 815004 T5019 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/'
[junit4] 2> 815047 T5019 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 815074 T5019 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 815142 T5019 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 815142 T5019 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/conf/schema.xml
[junit4] 2> 815152 T5019 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 815179 T5019 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 815183 T5019 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/
[junit4] 2> 815184 T5019 x:collection1 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 815185 T5019 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/], dataDir=[null]
[junit4] 2> 815185 T5019 x:collection1 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 815187 T5019 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/collection1/data
[junit4] 2> 815189 T5019 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/collection1/data/index/
[junit4] 2> 815191 T5019 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 815192 T5019 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/collection1/data/index
[junit4] 2> 815194 T5019 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 815201 T5019 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34690b8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 815201 T5019 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 815210 T5019 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 815230 T5019 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 815235 T5019 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 815239 T5019 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 815243 T5019 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 815250 T5019 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 815251 T5019 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/logging,/admin/ping,/admin/plugins,lazy,/admin/luke,/update/csv,defaults,/update/json,/update/json/docs,/schema,/get,/update,/config,/replication,/admin/system,/admin/mbeans,/admin/segments,standard,/admin/file,/admin/threads,/admin/properties
[junit4] 2> 815257 T5019 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 815260 T5019 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 815260 T5019 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 815261 T5019 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=16, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.26626683795968364]
[junit4] 2> 815265 T5019 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34690b8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 815265 T5019 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 815266 T5019 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@1794c8cf[collection1] main
[junit4] 2> 815267 T5019 x:collection1 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/conf
[junit4] 2> 815268 T5019 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/conf}
[junit4] 2> 815269 T5019 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-001/./collection1/conf
[junit4] 2> 815272 T5019 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 815273 T5019 x:collection1 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 815273 T5019 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 815274 T5019 x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 815274 T5020 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1794c8cf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 815276 T4977 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 815277 T4977 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C1401 name=collection1 org.apache.solr.core.SolrCore@41674176
[junit4] 2> 815293 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0]} 0 4
[junit4] 2> 815298 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1]} 0 0
[junit4] 2> 815304 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2]} 0 1
[junit4] 2> 815309 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[3]} 0 0
[junit4] 2> 815325 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[4]} 0 11
[junit4] 2> 815331 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[5]} 0 3
[junit4] 2> 815335 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[6]} 0 1
[junit4] 2> 815339 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[7]} 0 0
[junit4] 2> 815343 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[8]} 0 1
[junit4] 2> 815360 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[9]} 0 13
[junit4] 2> 815371 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[10]} 0 6
[junit4] 2> 815377 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[11]} 0 1
[junit4] 2> 815382 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[12]} 0 1
[junit4] 2> 815387 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[13]} 0 1
[junit4] 2> 815404 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[14]} 0 13
[junit4] 2> 815412 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[15]} 0 3
[junit4] 2> 815418 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[16]} 0 1
[junit4] 2> 815423 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[17]} 0 0
[junit4] 2> 815429 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[18]} 0 0
[junit4] 2> 815440 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[19]} 0 8
[junit4] 2> 815448 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[20]} 0 3
[junit4] 2> 815453 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[21]} 0 1
[junit4] 2> 815457 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[22]} 0 0
[junit4] 2> 815462 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[23]} 0 1
[junit4] 2> 815475 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[24]} 0 9
[junit4] 2> 815483 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[25]} 0 3
[junit4] 2> 815488 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[26]} 0 1
[junit4] 2> 815492 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[27]} 0 1
[junit4] 2> 815497 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[28]} 0 0
[junit4] 2> 815511 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[29]} 0 10
[junit4] 2> 815519 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[30]} 0 2
[junit4] 2> 815523 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[31]} 0 0
[junit4] 2> 815528 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[32]} 0 1
[junit4] 2> 815532 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[33]} 0 1
[junit4] 2> 815541 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[34]} 0 6
[junit4] 2> 815548 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[35]} 0 3
[junit4] 2> 815552 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[36]} 0 0
[junit4] 2> 815557 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[37]} 0 1
[junit4] 2> 815561 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[38]} 0 1
[junit4] 2> 815571 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[39]} 0 7
[junit4] 2> 815577 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[40]} 0 3
[junit4] 2> 815582 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[41]} 0 1
[junit4] 2> 815586 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[42]} 0 1
[junit4] 2> 815589 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[43]} 0 0
[junit4] 2> 815598 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[44]} 0 5
[junit4] 2> 815603 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[45]} 0 1
[junit4] 2> 815607 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[46]} 0 0
[junit4] 2> 815611 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[47]} 0 1
[junit4] 2> 815614 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[48]} 0 1
[junit4] 2> 815625 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[49]} 0 7
[junit4] 2> 815632 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[50]} 0 3
[junit4] 2> 815635 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[51]} 0 0
[junit4] 2> 815640 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[52]} 0 1
[junit4] 2> 815644 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[53]} 0 0
[junit4] 2> 815654 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[54]} 0 6
[junit4] 2> 815660 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[55]} 0 2
[junit4] 2> 815664 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[56]} 0 1
[junit4] 2> 815668 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[57]} 0 1
[junit4] 2> 815671 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[58]} 0 0
[junit4] 2> 815681 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[59]} 0 7
[junit4] 2> 815688 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[60]} 0 4
[junit4] 2> 815694 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[61]} 0 2
[junit4] 2> 815698 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[62]} 0 0
[junit4] 2> 815702 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[63]} 0 0
[junit4] 2> 815712 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[64]} 0 7
[junit4] 2> 815719 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[65]} 0 3
[junit4] 2> 815723 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[66]} 0 0
[junit4] 2> 815727 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[67]} 0 0
[junit4] 2> 815732 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[68]} 0 0
[junit4] 2> 815744 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[69]} 0 8
[junit4] 2> 815751 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[70]} 0 3
[junit4] 2> 815756 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[71]} 0 1
[junit4] 2> 815761 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[72]} 0 1
[junit4] 2> 815764 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[73]} 0 0
[junit4] 2> 815771 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[74]} 0 4
[junit4] 2> 815776 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[75]} 0 1
[junit4] 2> 815780 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[76]} 0 0
[junit4] 2> 815784 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[77]} 0 1
[junit4] 2> 815788 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[78]} 0 0
[junit4] 2> 815799 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[79]} 0 7
[junit4] 2> 815816 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[80]} 0 3
[junit4] 2> 815821 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[81]} 0 0
[junit4] 2> 815825 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[82]} 0 1
[junit4] 2> 815837 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[83]} 0 1
[junit4] 2> 815851 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[84]} 0 10
[junit4] 2> 815858 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[85]} 0 2
[junit4] 2> 815862 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[86]} 0 1
[junit4] 2> 815867 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[87]} 0 0
[junit4] 2> 815872 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[88]} 0 1
[junit4] 2> 815882 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[89]} 0 6
[junit4] 2> 815887 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[90]} 0 1
[junit4] 2> 815892 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[91]} 0 0
[junit4] 2> 815896 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[92]} 0 1
[junit4] 2> 815900 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[93]} 0 1
[junit4] 2> 815908 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[94]} 0 5
[junit4] 2> 815914 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[95]} 0 2
[junit4] 2> 815919 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[96]} 0 1
[junit4] 2> 815923 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[97]} 0 0
[junit4] 2> 815927 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[98]} 0 0
[junit4] 2> 815943 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[99]} 0 13
[junit4] 2> 815949 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[100]} 0 2
[junit4] 2> 815953 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[101]} 0 1
[junit4] 2> 815956 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[102]} 0 0
[junit4] 2> 815960 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[103]} 0 0
[junit4] 2> 815970 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[104]} 0 6
[junit4] 2> 815976 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[105]} 0 3
[junit4] 2> 815981 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[106]} 0 1
[junit4] 2> 815985 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[107]} 0 1
[junit4] 2> 815988 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[108]} 0 0
[junit4] 2> 816007 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[109]} 0 15
[junit4] 2> 816013 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[110]} 0 2
[junit4] 2> 816017 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[111]} 0 1
[junit4] 2> 816021 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[112]} 0 1
[junit4] 2> 816037 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[113]} 0 3
[junit4] 2> 816059 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[114]} 0 17
[junit4] 2> 816071 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[115]} 0 4
[junit4] 2> 816077 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[116]} 0 1
[junit4] 2> 816086 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[117]} 0 0
[junit4] 2> 816089 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[118]} 0 0
[junit4] 2> 816097 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[119]} 0 5
[junit4] 2> 816101 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[120]} 0 1
[junit4] 2> 816104 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[121]} 0 0
[junit4] 2> 816108 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[122]} 0 1
[junit4] 2> 816111 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[123]} 0 0
[junit4] 2> 816154 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[124]} 0 8
[junit4] 2> 816162 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[125]} 0 3
[junit4] 2> 816166 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[126]} 0 0
[junit4] 2> 816170 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[127]} 0 0
[junit4] 2> 816173 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[128]} 0 0
[junit4] 2> 816184 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[129]} 0 7
[junit4] 2> 816190 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[130]} 0 2
[junit4] 2> 816193 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[131]} 0 0
[junit4] 2> 816196 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[132]} 0 0
[junit4] 2> 816199 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[133]} 0 0
[junit4] 2> 816208 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[134]} 0 6
[junit4] 2> 816212 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[135]} 0 1
[junit4] 2> 816215 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[136]} 0 0
[junit4] 2> 816218 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[137]} 0 0
[junit4] 2> 816221 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[138]} 0 0
[junit4] 2> 816228 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[139]} 0 4
[junit4] 2> 816232 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[140]} 0 1
[junit4] 2> 816235 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[141]} 0 1
[junit4] 2> 816238 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[142]} 0 1
[junit4] 2> 816241 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[143]} 0 1
[junit4] 2> 816248 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[144]} 0 4
[junit4] 2> 816253 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[145]} 0 2
[junit4] 2> 816257 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[146]} 0 0
[junit4] 2> 816260 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[147]} 0 0
[junit4] 2> 816263 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[148]} 0 0
[junit4] 2> 816270 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[149]} 0 4
[junit4] 2> 816274 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[150]} 0 1
[junit4] 2> 816277 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[151]} 0 0
[junit4] 2> 816281 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[152]} 0 1
[junit4] 2> 816284 T5014 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[153]} 0 0
[junit4] 2> 816292 T5015 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[154]} 0 5
[junit4] 2> 816297 T5016 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[155]} 0 2
[junit4] 2> 816300 T5011 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[156]} 0 0
[junit4] 2> 816303 T5012 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[157]} 0 0
[junit4] 2> 816306 T5013 x:collection1 C1401 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[158]} 0 0
[junit4] 2> ASYNC NEW_CORE C1402 name=collection1 org.apache.solr.core.SolrCore@41674176
[junit4] 2> 816326 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[159]} 0 17
[junit4] 2> 816334 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[160]} 0 1
[junit4] 2> 816338 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[161]} 0 1
[junit4] 2> 816342 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[162]} 0 1
[junit4] 2> 816345 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[163]} 0 0
[junit4] 2> 816353 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[164]} 0 5
[junit4] 2> 816357 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[165]} 0 1
[junit4] 2> 816361 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[166]} 0 0
[junit4] 2> 816364 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[167]} 0 0
[junit4] 2> 816368 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[168]} 0 1
[junit4] 2> 816375 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[169]} 0 5
[junit4] 2> 816380 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[170]} 0 2
[junit4] 2> 816395 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[171]} 0 1
[junit4] 2> 816400 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[172]} 0 1
[junit4] 2> 816403 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[173]} 0 0
[junit4] 2> 816410 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[174]} 0 4
[junit4] 2> 816416 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[175]} 0 2
[junit4] 2> 816419 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[176]} 0 0
[junit4] 2> 816423 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[177]} 0 1
[junit4] 2> 816426 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[178]} 0 1
[junit4] 2> 816433 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[179]} 0 5
[junit4] 2> 816437 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[180]} 0 1
[junit4] 2> 816441 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[181]} 0 0
[junit4] 2> 816447 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[182]} 0 1
[junit4] 2> 816450 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[183]} 0 1
[junit4] 2> 816458 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[184]} 0 6
[junit4] 2> 816462 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[185]} 0 1
[junit4] 2> 816465 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[186]} 0 0
[junit4] 2> 816469 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[187]} 0 0
[junit4] 2> 816472 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[188]} 0 0
[junit4] 2> 816480 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[189]} 0 5
[junit4] 2> 816485 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[190]} 0 2
[junit4] 2> 816488 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[191]} 0 0
[junit4] 2> 816491 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[192]} 0 0
[junit4] 2> 816494 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[193]} 0 0
[junit4] 2> 816502 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[194]} 0 5
[junit4] 2> 816507 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[195]} 0 2
[junit4] 2> 816510 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[196]} 0 0
[junit4] 2> 816514 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[197]} 0 1
[junit4] 2> 816520 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[198]} 0 3
[junit4] 2> 816530 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[199]} 0 4
[junit4] 2> 816534 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[200]} 0 1
[junit4] 2> 816537 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[201]} 0 0
[junit4] 2> 816541 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[202]} 0 1
[junit4] 2> 816544 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[203]} 0 0
[junit4] 2> 816551 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[204]} 0 4
[junit4] 2> 816555 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[205]} 0 2
[junit4] 2> 816558 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[206]} 0 0
[junit4] 2> 816562 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[207]} 0 1
[junit4] 2> 816566 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[208]} 0 1
[junit4] 2> 816573 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[209]} 0 5
[junit4] 2> 816579 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[210]} 0 3
[junit4] 2> 816583 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[211]} 0 1
[junit4] 2> 816586 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[212]} 0 0
[junit4] 2> 816590 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[213]} 0 0
[junit4] 2> 816597 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[214]} 0 4
[junit4] 2> 816601 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[215]} 0 1
[junit4] 2> 816605 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[216]} 0 1
[junit4] 2> 816608 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[217]} 0 0
[junit4] 2> 816612 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[218]} 0 1
[junit4] 2> 816619 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[219]} 0 5
[junit4] 2> 816623 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[220]} 0 1
[junit4] 2> 816626 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[221]} 0 0
[junit4] 2> 816629 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[222]} 0 1
[junit4] 2> 816632 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[223]} 0 1
[junit4] 2> 816638 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[224]} 0 4
[junit4] 2> 816642 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[225]} 0 2
[junit4] 2> 816644 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[226]} 0 0
[junit4] 2> 816648 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[227]} 0 1
[junit4] 2> 816651 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[228]} 0 0
[junit4] 2> 816657 T5012 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[229]} 0 3
[junit4] 2> 816662 T5013 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[230]} 0 2
[junit4] 2> 816664 T5014 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[231]} 0 0
[junit4] 2> 816667 T5015 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[232]} 0 0
[junit4] 2> 816670 T5016 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[233]} 0 0
[junit4] 2> 816687 T5011 x:collection1 C1402 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=java
[...truncated too long message...]
ler AFE2376513F7757E-001/solr-instance-028/./collection1/data/index.20150430070547597
[junit4] 2> 916663 T4977 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@60733a76{/solr,null,UNAVAILABLE}
[junit4] 2> 916666 T4977 oas.SolrTestCaseJ4.tearDown ###Ending doTestRepeater
[junit4] 2> 916669 T4977 oejs.AbstractConnector.doStop Stopped ServerConnector@46e945a0{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 916669 T4977 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=10150911
[junit4] 2> 916670 T4977 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@b2ffae
[junit4] 2> 916671 T4977 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=6,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 916671 T4977 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 916672 T4977 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 916672 T4977 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 916673 T4977 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 916673 T4977 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 916673 T4977 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-026/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-026/./collection1/data/index;done=false>>]
[junit4] 2> 916674 T4977 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-026/./collection1/data/index
[junit4] 2> 916674 T4977 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-026/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-026/./collection1/data;done=false>>]
[junit4] 2> 916674 T4977 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-026/./collection1/data
[junit4] 2> 916675 T4977 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@34785071{/solr,null,UNAVAILABLE}
[junit4] 2> 916678 T4977 oejs.AbstractConnector.doStop Stopped ServerConnector@1b313c3f{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 916678 T4977 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=402302509
[junit4] 2> 916679 T4977 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50599c49
[junit4] 2> 916680 T4977 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] 2> 916680 T4977 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 916680 T4977 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 916680 T4977 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 916682 T4977 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 916683 T4977 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 916684 T4977 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-027/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-027/./collection1/data/index;done=false>>]
[junit4] 2> 916684 T4977 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-027/./collection1/data/index
[junit4] 2> 916685 T4977 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-027/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-027/./collection1/data;done=false>>]
[junit4] 2> 916686 T4977 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001/solr-instance-027/./collection1/data
[junit4] 2> 916687 T4977 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@6e5917ea{/solr,null,UNAVAILABLE}
[junit4] 2> 916692 T4977 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler AFE2376513F7757E-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50), sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ms, timezone=Universal
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=3,threads=1,free=44579368,total=206663680
[junit4] 2> NOTE: All tests run in this JVM: [SampleTest, RankQueryTest, SolrCoreTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestLRUCache, DistributedQueueTest, TestComponentsName, PreAnalyzedFieldTest, DirectUpdateHandlerTest, TestFieldSortValues, IgnoreCommitOptimizeUpdateProcessorFactoryTest, DocValuesMultiTest, SolrXmlInZkTest, TestDefaultSearchFieldResource, TestFreeTextSuggestions, ParsingFieldUpdateProcessorsTest, ResponseLogComponentTest, TestPhraseSuggestions, FieldAnalysisRequestHandlerTest, JSONWriterTest, TestPostingsSolrHighlighter, CloudExitableDirectoryReaderTest, TestLeaderElectionZkExpiry, CacheHeaderTest, ClusterStateUpdateTest, ConcurrentDeleteAndCreateCollectionTest, TestManagedResourceStorage, UnloadDistributedZkTest, TestOverriddenPrefixQueryForCustomFieldType, SolrIndexSplitterTest, InfoHandlerTest, LukeRequestHandlerTest, DeleteReplicaTest, HdfsDirectoryTest, AnalysisErrorHandlingTest, HdfsLockFactoryTest, TestXIncludeConfig, TestComplexPhraseQParserPlugin, TestFieldTypeResource, TestIntervalFaceting, TestCustomSort, SimpleFacetsTest, TestCSVLoader, DistributedSpellCheckComponentTest, TestAtomicUpdateErrorCases, TestFastLRUCache, TestHashQParserPlugin, SuggesterWFSTTest, TestDistribDocBasedVersion, TestCryptoKeys, URLClassifyProcessorTest, TestRestManager, DocExpirationUpdateProcessorFactoryTest, TestSolr4Spatial2, TestStressVersions, TestDocBasedVersionConstraints, TestSweetSpotSimilarityFactory, BadComponentTest, OverriddenZkACLAndCredentialsProvidersTest, TestUniqueKeyFieldResource, TestWriterPerf, ConnectionManagerTest, PingRequestHandlerTest, TestNamedUpdateProcessors, DeleteInactiveReplicaTest, TestSchemaResource, TestJsonRequest, TestReplicationHandlerBackup, ReplicationFactorTest, TestJmxMonitoredMap, TestSearcherReuse, SynonymTokenizerTest, TestCoreContainer, RecoveryZkTest, TestClusterStateMutator, TestMiniSolrCloudClusterSSL, DistribDocExpirationUpdateProcessorTest, DOMUtilTest, TestLazyCores, TestReplicationHandler]
[junit4] Completed [156/485] on J0 in 102.80s, 13 tests, 1 failure <<< FAILURES!
[...truncated 1050 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:536: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:484: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:229: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:511: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1433: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:991: There were test failures: 485 suites, 1949 tests, 1 failure, 62 ignored (24 assumptions)
Total time: 83 minutes 36 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any