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/03/23 16:31:43 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 2083
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2083/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.testRateLimitedReplication
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([7063D5195C8F2F83:F6F7A0EC533DAE6E]: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:1319)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
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:53)
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:53)
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 9939 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/init-core-data-001
[junit4] 2> 1471877 T7212 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterWrite2Slave
[junit4] 2> 1471879 T7212 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/collection1
[junit4] 2> 1471901 T7212 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 1471905 T7212 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@3cda4555{/solr,null,AVAILABLE}
[junit4] 2> 1471909 T7212 oejs.AbstractConnector.doStart Started ServerConnector@34e491b9{HTTP/1.1}{127.0.0.1:60865}
[junit4] 2> 1471910 T7212 oejs.Server.doStart Started @1473943ms
[junit4] 2> 1471910 T7212 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=60865}
[junit4] 2> 1471910 T7212 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1471910 T7212 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/'
[junit4] 2> 1471945 T7212 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/solr.xml
[junit4] 2> 1471955 T7212 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/.
[junit4] 2> 1471955 T7212 oasc.CoreContainer.<init> New CoreContainer 167999177
[junit4] 2> 1471955 T7212 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/]
[junit4] 2> 1471955 T7212 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/lib
[junit4] 2> 1471956 T7212 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/lib).
[junit4] 2> 1471966 T7212 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1471966 T7212 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1471967 T7212 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1471967 T7212 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1471967 T7212 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1471968 T7212 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1471968 T7212 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1471968 T7212 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1471968 T7212 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1471969 T7212 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1471969 T7212 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1471969 T7212 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1471969 T7212 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1471970 T7212 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1471970 T7212 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1471973 T7212 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/.
[junit4] 2> 1471974 T7212 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/, schema=schema.xml, transient=false, dataDir=data/}
[junit4] 2> 1471975 T7212 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/
[junit4] 2> 1471975 T7212 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1471978 T7224 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/'
[junit4] 2> 1472006 T7224 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1472009 T7224 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1472015 T7224 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1472015 T7224 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/conf/schema.xml
[junit4] 2> 1472017 T7224 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1472021 T7224 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1472022 T7224 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/
[junit4] 2> 1472023 T7224 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1472023 T7224 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/data/
[junit4] 2> 1472023 T7224 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1472024 T7224 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/data
[junit4] 2> 1472024 T7224 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 1472024 T7224 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1472025 T7224 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/data/index
[junit4] 2> 1472025 T7224 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=56.8251953125, floorSegmentMB=0.7138671875, forceMergeDeletesPctAllowed=20.455578269994067, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5628068816654727
[junit4] 2> 1472033 T7224 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/index-MMapDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@45fc8788),segFN=segments_1,generation=1}
[junit4] 2> 1472033 T7224 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1472037 T7224 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1472042 T7224 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1472044 T7224 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1472045 T7224 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1472046 T7224 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1472061 T7224 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1472061 T7224 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1472061 T7224 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1472062 T7224 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1472062 T7224 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1472062 T7224 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=24.8115234375, floorSegmentMB=0.9033203125, forceMergeDeletesPctAllowed=10.463155417839575, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1472065 T7224 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/index-MMapDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@45fc8788),segFN=segments_1,generation=1}
[junit4] 2> 1472065 T7224 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1472066 T7224 oass.SolrIndexSearcher.<init> Opening Searcher@44e842e4[collection1] main
[junit4] 2> 1472066 T7224 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/conf
[junit4] 2> 1472066 T7224 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/conf}
[junit4] 2> 1472066 T7224 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-001/./collection1/conf
[junit4] 2> 1472067 T7224 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1472067 T7224 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1472068 T7224 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1472068 T7224 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1472068 T7225 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44e842e4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1472069 T7212 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1472069 T7212 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1472070 T7212 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/collection1
[junit4] 2> 1472084 T7212 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 1472087 T7212 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@37997da5{/solr,null,AVAILABLE}
[junit4] 2> 1472088 T7212 oejs.AbstractConnector.doStart Started ServerConnector@40fefb69{HTTP/1.1}{127.0.0.1:60866}
[junit4] 2> 1472088 T7212 oejs.Server.doStart Started @1474122ms
[junit4] 2> 1472089 T7212 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=60866}
[junit4] 2> 1472089 T7212 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1472089 T7212 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/'
[junit4] 2> 1472122 T7212 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/solr.xml
[junit4] 2> 1472158 T7212 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/.
[junit4] 2> 1472159 T7212 oasc.CoreContainer.<init> New CoreContainer 1398788023
[junit4] 2> 1472160 T7212 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/]
[junit4] 2> 1472160 T7212 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/lib
[junit4] 2> 1472160 T7212 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/lib).
[junit4] 2> 1472169 T7212 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1472170 T7212 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1472170 T7212 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1472170 T7212 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1472170 T7212 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1472171 T7212 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1472171 T7212 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1472171 T7212 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1472171 T7212 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1472172 T7212 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1472172 T7212 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1472172 T7212 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1472172 T7212 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1472173 T7212 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1472173 T7212 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1472174 T7212 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/.
[junit4] 2> 1472175 T7212 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/, schema=schema.xml, transient=false, dataDir=data/}
[junit4] 2> 1472176 T7212 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/
[junit4] 2> 1472176 T7212 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1472177 T7238 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/'
[junit4] 2> 1472204 T7238 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1472206 T7238 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1472212 T7238 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1472212 T7238 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/conf/schema.xml
[junit4] 2> 1472214 T7238 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1472218 T7238 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1472219 T7238 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/
[junit4] 2> 1472219 T7238 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1472220 T7238 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/data/
[junit4] 2> 1472220 T7238 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1472220 T7238 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/data
[junit4] 2> 1472221 T7238 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 1472221 T7238 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1472222 T7238 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/data/index
[junit4] 2> 1472222 T7238 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=56.8251953125, floorSegmentMB=0.7138671875, forceMergeDeletesPctAllowed=20.455578269994067, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5628068816654727
[junit4] 2> 1472226 T7238 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/index-MMapDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@45fc8788),segFN=segments_1,generation=1}
[junit4] 2> 1472226 T7238 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1472228 T7238 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1472236 T7238 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1472236 T7238 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1472237 T7238 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1472238 T7238 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1472241 T7238 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1472241 T7238 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1472242 T7238 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1472242 T7238 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1472243 T7238 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1472243 T7238 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=24.8115234375, floorSegmentMB=0.9033203125, forceMergeDeletesPctAllowed=10.463155417839575, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1472245 T7238 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/index-MMapDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@45fc8788),segFN=segments_1,generation=1}
[junit4] 2> 1472245 T7238 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1472245 T7238 oass.SolrIndexSearcher.<init> Opening Searcher@61eb8b6b[collection1] main
[junit4] 2> 1472246 T7238 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/conf
[junit4] 2> 1472246 T7238 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/conf}
[junit4] 2> 1472246 T7238 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-002/./collection1/conf
[junit4] 2> 1472247 T7238 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1472248 T7238 oash.ReplicationHandler.setupPolling Poll Scheduled at an interval of 1000ms
[junit4] 2> 1472248 T7238 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1472248 T7239 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@61eb8b6b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1472250 T7212 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1472250 T7212 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C718 name=collection1 org.apache.solr.core.SolrCore@199d7d58
[junit4] 2> 1472262 T7216 C718 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1472267 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 2
[junit4] 2> 1472271 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 1472274 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4] 2> 1472276 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 1472278 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 1472281 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 1472283 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
[junit4] 2> 1472286 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 1472289 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 1
[junit4] 2> 1472291 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 0
[junit4] 2> 1472293 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 1472297 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 1472302 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 1472304 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 1472307 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 1472311 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 0
[junit4] 2> 1472313 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 1472316 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4] 2> 1472319 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 1
[junit4] 2> 1472322 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 1472324 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 1472327 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 1
[junit4] 2> 1472329 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 1472332 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 0
[junit4] 2> 1472334 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 1472337 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 0
[junit4] 2> 1472339 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 0
[junit4] 2> 1472342 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
[junit4] 2> 1472344 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 0
[junit4] 2> 1472347 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4] 2> 1472350 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 1
[junit4] 2> 1472353 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 1
[junit4] 2> 1472355 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 0
[junit4] 2> 1472359 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 1472362 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
[junit4] 2> 1472364 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
[junit4] 2> 1472367 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4] 2> 1472370 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4] 2> 1472372 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 0
[junit4] 2> 1472378 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 1
[junit4] 2> 1472383 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 1
[junit4] 2> 1472385 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4] 2> 1472390 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4] 2> 1472394 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 1
[junit4] 2> 1472397 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 1
[junit4] 2> 1472400 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 1
[junit4] 2> 1472402 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4] 2> 1472405 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4] 2> 1472407 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 0
[junit4] 2> 1472410 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 0
[junit4] 2> 1472413 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 0
[junit4] 2> 1472416 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 1
[junit4] 2> 1472418 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 0
[junit4] 2> 1472420 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4] 2> 1472423 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 0
[junit4] 2> 1472426 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 1
[junit4] 2> 1472428 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 0
[junit4] 2> 1472431 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 0
[junit4] 2> 1472435 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4] 2> 1472438 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 1
[junit4] 2> 1472440 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 0
[junit4] 2> 1472442 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 0
[junit4] 2> 1472445 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 0
[junit4] 2> 1472448 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 1
[junit4] 2> 1472450 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 0
[junit4] 2> 1472456 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 1
[junit4] 2> 1472461 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 0
[junit4] 2> 1472465 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 1
[junit4] 2> 1472467 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 0
[junit4] 2> 1472470 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4] 2> 1472472 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 0
[junit4] 2> 1472475 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 0
[junit4] 2> 1472478 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 1
[junit4] 2> 1472481 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 0
[junit4] 2> 1472483 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 0
[junit4] 2> 1472486 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 0
[junit4] 2> 1472488 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 0
[junit4] 2> 1472491 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 0
[junit4] 2> 1472494 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 1
[junit4] 2> 1472498 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 1
[junit4] 2> 1472500 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 0
[junit4] 2> 1472502 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 0
[junit4] 2> 1472505 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 1
[junit4] 2> 1472508 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 1
[junit4] 2> 1472510 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4] 2> 1472513 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 0
[junit4] 2> 1472515 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 0
[junit4] 2> 1472518 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 1
[junit4] 2> 1472520 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 0
[junit4] 2> 1472523 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 1
[junit4] 2> 1472525 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 0
[junit4] 2> 1472528 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 0
[junit4] 2> 1472531 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4] 2> 1472533 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 0
[junit4] 2> 1472535 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 0
[junit4] 2> 1472538 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 0
[junit4] 2> 1472540 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4] 2> 1472543 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 1
[junit4] 2> 1472545 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 0
[junit4] 2> 1472548 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 0
[junit4] 2> 1472551 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 0
[junit4] 2> 1472553 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 0
[junit4] 2> 1472556 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 0
[junit4] 2> 1472562 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4] 2> 1472565 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4] 2> 1472568 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 0
[junit4] 2> 1472572 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 0
[junit4] 2> 1472574 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 0
[junit4] 2> 1472577 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 0
[junit4] 2> 1472580 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 0
[junit4] 2> 1472583 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 0
[junit4] 2> 1472586 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 0
[junit4] 2> 1472588 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 0
[junit4] 2> 1472591 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 0
[junit4] 2> 1472593 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 0
[junit4] 2> 1472596 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 1
[junit4] 2> 1472598 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 0
[junit4] 2> 1472601 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 0
[junit4] 2> 1472604 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 1
[junit4] 2> 1472606 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4] 2> 1472609 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 0
[junit4] 2> 1472612 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 1
[junit4] 2> 1472614 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 0
[junit4] 2> 1472616 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 0
[junit4] 2> 1472619 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 0
[junit4] 2> 1472622 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 1
[junit4] 2> 1472624 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 0
[junit4] 2> 1472627 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 0
[junit4] 2> 1472629 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128]} 0 0
[junit4] 2> 1472632 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 0
[junit4] 2> 1472634 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4] 2> 1472636 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 0
[junit4] 2> 1472639 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132]} 0 0
[junit4] 2> 1472642 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133]} 0 1
[junit4] 2> 1472644 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 0
[junit4] 2> 1472646 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135]} 0 0
[junit4] 2> 1472649 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 0
[junit4] 2> 1472652 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137]} 0 1
[junit4] 2> 1472654 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138]} 0 0
[junit4] 2> 1472657 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 0
[junit4] 2> 1472659 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 0
[junit4] 2> 1472663 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 0
[junit4] 2> 1472665 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 0
[junit4] 2> 1472668 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 1
[junit4] 2> 1472670 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 0
[junit4] 2> 1472673 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 0
[junit4] 2> 1472676 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 1
[junit4] 2> 1472679 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 1
[junit4] 2> 1472682 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 0
[junit4] 2> 1472685 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149]} 0 1
[junit4] 2> 1472689 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[150]} 0 0
[junit4] 2> 1472691 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[151]} 0 0
[junit4] 2> 1472694 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[152]} 0 0
[junit4] 2> 1472697 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[153]} 0 1
[junit4] 2> 1472699 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[154]} 0 0
[junit4] 2> 1472702 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[155]} 0 0
[junit4] 2> 1472705 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[156]} 0 0
[junit4] 2> 1472707 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[157]} 0 0
[junit4] 2> 1472710 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[158]} 0 0
[junit4] 2> 1472712 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[159]} 0 0
[junit4] 2> 1472716 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[160]} 0 1
[junit4] 2> 1472719 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[161]} 0 1
[junit4] 2> 1472721 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[162]} 0 0
[junit4] 2> 1472725 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[163]} 0 0
[junit4] 2> 1472727 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[164]} 0 0
[junit4] 2> 1472727 T7219 C718 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1472730 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[165]} 0 1
[junit4] 2> 1472732 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[166]} 0 0
[junit4] 2> 1472735 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[167]} 0 1
[junit4] 2> 1472737 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[168]} 0 0
[junit4] 2> 1472740 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[169]} 0 0
[junit4] 2> 1472742 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[170]} 0 0
[junit4] 2> 1472744 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[171]} 0 0
[junit4] 2> 1472747 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[172]} 0 0
[junit4] 2> 1472750 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[173]} 0 0
[junit4] 2> 1472753 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[174]} 0 0
[junit4] 2> 1472755 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[175]} 0 0
[junit4] 2> 1472758 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[176]} 0 1
[junit4] 2> 1472760 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[177]} 0 0
[junit4] 2> 1472763 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[178]} 0 0
[junit4] 2> 1472766 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[179]} 0 0
[junit4] 2> 1472769 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[180]} 0 0
[junit4] 2> 1472772 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[181]} 0 0
[junit4] 2> 1472775 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[182]} 0 1
[junit4] 2> 1472778 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[183]} 0 0
[junit4] 2> 1472780 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[184]} 0 0
[junit4] 2> 1472783 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[185]} 0 1
[junit4] 2> 1472785 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[186]} 0 0
[junit4] 2> 1472789 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[187]} 0 0
[junit4] 2> 1472792 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[188]} 0 0
[junit4] 2> 1472795 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[189]} 0 0
[junit4] 2> 1472798 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[190]} 0 1
[junit4] 2> 1472801 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[191]} 0 0
[junit4] 2> 1472804 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[192]} 0 0
[junit4] 2> 1472806 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[193]} 0 0
[junit4] 2> 1472809 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[194]} 0 0
[junit4] 2> 1472813 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[195]} 0 1
[junit4] 2> 1472816 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[196]} 0 1
[junit4] 2> 1472818 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[197]} 0 0
[junit4] 2> 1472821 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[198]} 0 1
[junit4] 2> 1472823 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[199]} 0 0
[junit4] 2> 1472826 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[200]} 0 0
[junit4] 2> 1472829 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[201]} 0 1
[junit4] 2> 1472831 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[202]} 0 0
[junit4] 2> 1472833 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[203]} 0 0
[junit4] 2> 1472836 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[204]} 0 0
[junit4] 2> 1472839 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[205]} 0 0
[junit4] 2> 1472841 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[206]} 0 0
[junit4] 2> 1472844 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[207]} 0 1
[junit4] 2> 1472846 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[208]} 0 0
[junit4] 2> 1472849 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[209]} 0 0
[junit4] 2> 1472851 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[210]} 0 0
[junit4] 2> 1472855 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[211]} 0 1
[junit4] 2> 1472861 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[212]} 0 0
[junit4] 2> 1472864 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[213]} 0 1
[junit4] 2> 1472866 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[214]} 0 0
[junit4] 2> 1472869 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[215]} 0 0
[junit4] 2> 1472871 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[216]} 0 0
[junit4] 2> 1472875 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[217]} 0 0
[junit4] 2> 1472877 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[218]} 0 0
[junit4] 2> 1472881 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[219]} 0 0
[junit4] 2> 1472884 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[220]} 0 0
[junit4] 2> 1472888 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[221]} 0 0
[junit4] 2> 1472891 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222]} 0 1
[junit4] 2> 1472893 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[223]} 0 0
[junit4] 2> 1472895 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[224]} 0 0
[junit4] 2> 1472898 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[225]} 0 0
[junit4] 2> 1472900 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[226]} 0 0
[junit4] 2> 1472903 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[227]} 0 0
[junit4] 2> 1472905 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[228]} 0 0
[junit4] 2> 1472908 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[229]} 0 0
[junit4] 2> 1472911 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[230]} 0 0
[junit4] 2> 1472914 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[231]} 0 1
[junit4] 2> 1472916 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[232]} 0 0
[junit4] 2> 1472919 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[233]} 0 1
[junit4] 2> 1472921 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[234]} 0 0
[junit4] 2> 1472924 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[235]} 0 1
[junit4] 2> 1472926 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[236]} 0 0
[junit4] 2> 1472930 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[237]} 0 0
[junit4] 2> 1472933 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[238]} 0 1
[junit4] 2> 1472935 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[239]} 0 0
[junit4] 2> 1472937 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[240]} 0 0
[junit4] 2> 1472940 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[241]} 0 0
[junit4] 2> 1472942 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[242]} 0 0
[junit4] 2> 1472945 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[243]} 0 0
[junit4] 2> 1472948 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[244]} 0 0
[junit4] 2> 1472950 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[245]} 0 0
[junit4] 2> 1472953 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[246]} 0 0
[junit4] 2> 1472955 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[247]} 0 0
[junit4] 2> 1472958 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[248]} 0 0
[junit4] 2> 1472960 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[249]} 0 0
[junit4] 2> 1472963 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[250]} 0 0
[junit4] 2> 1472966 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[251]} 0 1
[junit4] 2> 1472968 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[252]} 0 0
[junit4] 2> 1472970 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[253]} 0 0
[junit4] 2> 1472973 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[254]} 0 1
[junit4] 2> 1472976 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[255]} 0 0
[junit4] 2> 1472979 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[256]} 0 0
[junit4] 2> 1472981 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[257]} 0 0
[junit4] 2> 1472984 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[258]} 0 1
[junit4] 2> 1472986 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[259]} 0 0
[junit4] 2> 1472990 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[260]} 0 1
[junit4] 2> 1472992 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[261]} 0 0
[junit4] 2> 1472995 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[262]} 0 0
[junit4] 2> 1472998 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[263]} 0 0
[junit4] 2> 1473001 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[264]} 0 0
[junit4] 2> 1473004 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[265]} 0 0
[junit4] 2> 1473007 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[266]} 0 1
[junit4] 2> 1473010 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[267]} 0 1
[junit4] 2> 1473012 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[268]} 0 0
[junit4] 2> 1473015 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[269]} 0 0
[junit4] 2> 1473018 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[270]} 0 1
[junit4] 2> 1473021 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[271]} 0 0
[junit4] 2> 1473024 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[272]} 0 1
[junit4] 2> 1473026 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[273]} 0 0
[junit4] 2> 1473029 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[274]} 0 0
[junit4] 2> 1473031 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[275]} 0 0
[junit4] 2> 1473034 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[276]} 0 0
[junit4] 2> 1473037 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[277]} 0 0
[junit4] 2> 1473040 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[278]} 0 0
[junit4] 2> 1473043 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[279]} 0 0
[junit4] 2> 1473046 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[280]} 0 0
[junit4] 2> 1473048 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[281]} 0 0
[junit4] 2> 1473051 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[282]} 0 0
[junit4] 2> 1473054 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[283]} 0 0
[junit4] 2> 1473057 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[284]} 0 0
[junit4] 2> 1473060 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[285]} 0 0
[junit4] 2> 1473062 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[286]} 0 0
[junit4] 2> 1473066 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[287]} 0 1
[junit4] 2> 1473069 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[288]} 0 1
[junit4] 2> 1473072 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[289]} 0 1
[junit4] 2> 1473074 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[290]} 0 0
[junit4] 2> 1473077 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[291]} 0 0
[junit4] 2> 1473080 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[292]} 0 1
[junit4] 2> 1473082 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[293]} 0 0
[junit4] 2> 1473085 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[294]} 0 0
[junit4] 2> 1473088 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[295]} 0 0
[junit4] 2> 1473091 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[296]} 0 1
[junit4] 2> 1473094 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[297]} 0 1
[junit4] 2> 1473096 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[298]} 0 0
[junit4] 2> 1473100 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[299]} 0 1
[junit4] 2> 1473102 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[300]} 0 0
[junit4] 2> 1473105 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[301]} 0 0
[junit4] 2> 1473109 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[302]} 0 1
[junit4] 2> 1473111 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[303]} 0 0
[junit4] 2> 1473114 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[304]} 0 0
[junit4] 2> 1473116 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[305]} 0 0
[junit4] 2> 1473119 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[306]} 0 0
[junit4] 2> 1473122 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[307]} 0 1
[junit4] 2> 1473125 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[308]} 0 1
[junit4] 2> 1473127 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[309]} 0 0
[junit4] 2> 1473130 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[310]} 0 1
[junit4] 2> 1473132 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[311]} 0 0
[junit4] 2> 1473135 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[312]} 0 0
[junit4] 2> 1473138 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[313]} 0 0
[junit4] 2> 1473140 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[314]} 0 0
[junit4] 2> 1473143 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[315]} 0 0
[junit4] 2> 1473146 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[316]} 0 0
[junit4] 2> 1473153 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[317]} 0 0
[junit4] 2> 1473156 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[318]} 0 0
[junit4] 2> 1473159 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[319]} 0 1
[junit4] 2> 1473162 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[320]} 0 1
[junit4] 2> 1473164 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[321]} 0 0
[junit4] 2> 1473167 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[322]} 0 0
[junit4] 2> 1473170 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[323]} 0 1
[junit4] 2> 1473172 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[324]} 0 0
[junit4] 2> 1473175 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[325]} 0 0
[junit4] 2> 1473178 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[326]} 0 0
[junit4] 2> 1473182 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[327]} 0 1
[junit4] 2> 1473184 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[328]} 0 0
[junit4] 2> 1473188 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[329]} 0 1
[junit4] 2> 1473190 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[330]} 0 0
[junit4] 2> 1473194 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[331]} 0 1
[junit4] 2> 1473198 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[332]} 0 0
[junit4] 2> 1473201 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[333]} 0 0
[junit4] 2> 1473204 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[334]} 0 0
[junit4] 2> 1473207 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[335]} 0 0
[junit4] 2> 1473210 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[336]} 0 0
[junit4] 2> 1473212 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[337]} 0 0
[junit4] 2> 1473215 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[338]} 0 0
[junit4] 2> 1473218 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[339]} 0 0
[junit4] 2> 1473222 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[340]} 0 1
[junit4] 2> 1473225 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[341]} 0 1
[junit4] 2> 1473227 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[342]} 0 0
[junit4] 2> 1473231 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[343]} 0 1
[junit4] 2> 1473233 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[344]} 0 0
[junit4] 2> 1473237 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[345]} 0 1
[junit4] 2> 1473239 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[346]} 0 0
[junit4] 2> 1473242 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[347]} 0 0
[junit4] 2> 1473245 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[348]} 0 0
[junit4] 2> 1473248 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[349]} 0 0
[junit4] 2> 1473252 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[350]} 0 1
[junit4] 2> 1473254 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[351]} 0 0
[junit4] 2> 1473261 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[352]} 0 0
[junit4] 2> 1473264 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[353]} 0 0
[junit4] 2> 1473267 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[354]} 0 0
[junit4] 2> 1473270 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[355]} 0 0
[junit4] 2> 1473275 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[356]} 0 0
[junit4] 2> 1473277 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[357]} 0 0
[junit4] 2> 1473281 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[358]} 0 0
[junit4] 2> 1473283 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[359]} 0 0
[junit4] 2> 1473287 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[360]} 0 0
[junit4] 2> 1473289 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[361]} 0 0
[junit4] 2> 1473292 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[362]} 0 1
[junit4] 2> 1473294 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[363]} 0 0
[junit4] 2> 1473296 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[364]} 0 0
[junit4] 2> 1473299 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[365]} 0 1
[junit4] 2> 1473301 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[366]} 0 0
[junit4] 2> 1473304 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[367]} 0 1
[junit4] 2> 1473306 T7219 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[368]} 0 0
[junit4] 2> 1473308 T7221 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[369]} 0 0
[junit4] 2> 1473311 T7216 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[370]} 0 0
[junit4] 2> 1473313 T7217 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[371]} 0 0
[junit4] 2> 1473316 T7218 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[372]} 0 0
[junit4] 2> 1473318 T7220 C718 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[373]} 0 0
[junit4] 2> 1473321 T7219 C718 oasup.LogUpdateProce
[...truncated too long message...]
12 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1563883 T7212 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1563884 T7212 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-026/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-026/./collection1/data;done=false>>]
[junit4] 2> 1563884 T7212 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-026/./collection1/data
[junit4] 2> 1563884 T7212 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-026/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-026/./collection1/data/index;done=false>>]
[junit4] 2> 1563884 T7212 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-026/./collection1/data/index
[junit4] 2> 1563885 T7212 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@2c122536{/solr,null,UNAVAILABLE}
[junit4] 2> 1563888 T7212 oejs.AbstractConnector.doStop Stopped ServerConnector@69c0f048{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 1563888 T7212 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=925661757
[junit4] 2> 1563889 T7212 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2db0c759
[junit4] 2> 1563889 T7212 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> 1563889 T7212 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1563890 T7212 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1563890 T7212 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1563893 T7212 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1563893 T7212 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1563894 T7212 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-027/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-027/./collection1/data;done=false>>]
[junit4] 2> 1563894 T7212 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-027/./collection1/data
[junit4] 2> 1563894 T7212 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-027/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-027/./collection1/data/index;done=false>>]
[junit4] 2> 1563894 T7212 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001/solr-instance-027/./collection1/data/index
[junit4] 2> 1563895 T7212 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@7d84b625{/solr,null,UNAVAILABLE}
[junit4] 2> 1563898 T7212 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler 7063D5195C8F2F83-001
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=1, maxDocsPerChunk=73, blockSize=9), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=1, blockSize=9)), sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=sr__#Latn, timezone=PRC
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_40 (64-bit)/cpus=3,threads=1,free=125600424,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestManagedResource, TestExactSharedStatsCache, SaslZkACLProviderTest, HardAutoCommitTest, TestSimpleQParserPlugin, TestJmxIntegration, TestWriterPerf, TestReplicaProperties, TestManagedSchemaDynamicFieldResource, TestDistribDocBasedVersion, ClusterStateTest, TestFunctionQuery, TestRebalanceLeaders, DocValuesMissingTest, TestElisionMultitermQuery, NumericFieldsTest, TestSurroundQueryParser, TestIntervalFaceting, DirectUpdateHandlerTest, TestGroupingSearch, SuggesterWFSTTest, TestAtomicUpdateErrorCases, SolrCoreTest, TestMaxScoreQueryParser, SortByFunctionTest, UUIDUpdateProcessorFallbackTest, TestRecoveryHdfs, TestOmitPositions, TestManagedSchemaFieldResource, TestShortCircuitedRequests, TestFieldSortValues, SOLR749Test, TestExactStatsCache, DistribJoinFromCollectionTest, SpatialRPTFieldTypeTest, SolrPluginUtilsTest, TestCoreContainer, FieldAnalysisRequestHandlerTest, SolrInfoMBeanTest, TestMiniSolrCloudCluster, TestComplexPhraseQParserPlugin, TestFaceting, TestLazyCores, TestNoOpRegenerator, JSONWriterTest, TestFieldResource, SuggesterTest, AnalysisErrorHandlingTest, TestDefaultSearchFieldResource, UnloadDistributedZkTest, UUIDFieldTest, TestCloudPivotFacet, BadComponentTest, UniqFieldsUpdateProcessorFactoryTest, TestIndexingPerformance, TestArbitraryIndexDir, TestQueryTypes, RemoteQueryErrorTest, TestDynamicFieldResource, DirectSolrConnectionTest, DistributedExpandComponentTest, TestCollationField, TestSolrConfigHandlerConcurrent, SoftAutoCommitTest, ShowFileRequestHandlerTest, SyncSliceTest, PreAnalyzedUpdateProcessorTest, BufferStoreTest, NoCacheHeaderTest, ActionThrottleTest, BlockCacheTest, SuggestComponentTest, SuggesterFSTTest, TestSolrXml, RankQueryTest, TestHighFrequencyDictionaryFactory, AddBlockUpdateTest, PingRequestHandlerTest, ShardRoutingTest, TestStressReorder, TestAnalyzedSuggestions, BlockDirectoryTest, SystemInfoHandlerTest, TestSchemaNameResource, DistributedQueueTest, TestCloudManagedSchema, TestNRTOpen, TestFastLRUCache, RecoveryZkTest, CachingDirectoryFactoryTest, TestUpdate, RequestLoggingTest, OverriddenZkACLAndCredentialsProvidersTest, DistributedFacetPivotSmallTest, TestManagedSynonymFilterFactory, CircularListTest, DistribDocExpirationUpdateProcessorTest, TestStressLucene, DistributedSpellCheckComponentTest, TestPerFieldSimilarity, StressHdfsTest, CoreAdminHandlerTest, DocValuesMultiTest, AnalysisAfterCoreReloadTest, TestLFUCache, SimpleCollectionCreateDeleteTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, DistributedFacetPivotWhiteBoxTest, TestMissingGroups, ChaosMonkeyNothingIsSafeTest, TestManagedStopFilterFactory, DefaultValueUpdateProcessorTest, TestSweetSpotSimilarityFactory, TestExceedMaxTermLength, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, TestReplicationHandler]
[junit4] Completed on J0 in 92.05s, 13 tests, 1 failure <<< FAILURES!
[...truncated 708 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:191: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1351: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:958: There were test failures: 476 suites, 1894 tests, 1 failure, 53 ignored (20 assumptions)
Total time: 63 minutes 59 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 2084
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2084/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.handler.TestSolrConfigHandlerCloud.test
Error Message:
Could not get expected value 'P val' for path 'response/params/y/p' full output: { "responseHeader":{ "status":0, "QTime":0}, "response":{ "znodeVersion":1, "params":{ "x":{ "a":"A val", "b":"B val", "":{"v":0}}, "y":{ "c":"CY val", "b":"BY val", "i":20, "d":[ "val 1", "val 2"], "":{"v":0}}}}}
Stack Trace:
java.lang.AssertionError: Could not get expected value 'P val' for path 'response/params/y/p' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"response":{
"znodeVersion":1,
"params":{
"x":{
"a":"A val",
"b":"B val",
"":{"v":0}},
"y":{
"c":"CY val",
"b":"BY val",
"i":20,
"d":[
"val 1",
"val 2"],
"":{"v":0}}}}}
at __randomizedtesting.SeedInfo.seed([32AB64B7EC33568D:BAFF5B6D42CF3B75]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:399)
at org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:245)
at org.apache.solr.handler.TestSolrConfigHandlerCloud.test(TestSolrConfigHandlerCloud.java:78)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.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:53)
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)
FAILED: org.apache.solr.schema.TestBulkSchemaConcurrent.test
Error Message:
[[field a0 still exists, new type mystr0 still exists, dynamic field *_lol0 still exists, CopyField source=a0,dest=hello_lol0 still exists], [], [], [], []]
Stack Trace:
java.lang.AssertionError: [[field a0 still exists, new type mystr0 still exists, dynamic field *_lol0 still exists, CopyField source=a0,dest=hello_lol0 still exists], [], [], [], []]
at __randomizedtesting.SeedInfo.seed([32AB64B7EC33568D:BAFF5B6D42CF3B75]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.schema.TestBulkSchemaConcurrent.test(TestBulkSchemaConcurrent.java:121)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.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:53)
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 9512 lines...]
[junit4] Suite: org.apache.solr.schema.TestBulkSchemaConcurrent
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/init-core-data-001
[junit4] 2> 868001 T6017 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 868001 T6017 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 868004 T6017 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 868006 T6018 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 868106 T6017 oasc.ZkTestServer.run start zk server on port:56351
[junit4] 2> 868106 T6017 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 868107 T6017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 868113 T6025 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2ded47 name:ZooKeeperConnection Watcher:127.0.0.1:56351 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 868113 T6017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 868114 T6017 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 868114 T6017 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 868119 T6017 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 868121 T6017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 868124 T6028 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@68dcc86 name:ZooKeeperConnection Watcher:127.0.0.1:56351/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 868124 T6017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 868124 T6017 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 868125 T6017 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 868128 T6017 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 868133 T6017 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 868137 T6017 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 868140 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-managed-schema.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 868141 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 868147 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 868148 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 868152 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 868152 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 868157 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 868157 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 868161 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 868162 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 868166 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 868166 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 868171 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 868171 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 868176 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 868176 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 868181 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 868181 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 868186 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 868186 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 868190 T6017 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 868191 T6017 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 868600 T6017 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1
[junit4] 2> 868605 T6017 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 868608 T6017 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@13d44a21{/,null,AVAILABLE}
[junit4] 2> 868614 T6017 oejs.AbstractConnector.doStart Started ServerConnector@5e9b1a72{HTTP/1.1}{127.0.0.1:56354}
[junit4] 2> 868615 T6017 oejs.Server.doStart Started @871036ms
[junit4] 2> 868615 T6017 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/tempDir-001/control/data, hostContext=/, hostPort=56354, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores}
[junit4] 2> 868615 T6017 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 868616 T6017 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/'
[junit4] 2> 868653 T6017 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/solr.xml
[junit4] 2> 868667 T6017 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores
[junit4] 2> 868668 T6017 oasc.CoreContainer.<init> New CoreContainer 444454909
[junit4] 2> 868668 T6017 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/]
[junit4] 2> 868669 T6017 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/lib
[junit4] 2> 868670 T6017 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/lib).
[junit4] 2> 868685 T6017 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 868686 T6017 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 868686 T6017 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 868687 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 868688 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 868688 T6017 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 868688 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 868689 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 868689 T6017 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 868690 T6017 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 868690 T6017 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 868692 T6017 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 868692 T6017 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 868693 T6017 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 868693 T6017 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 868693 T6017 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56351/solr
[junit4] 2> 868694 T6017 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 868694 T6017 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 868696 T6017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 868700 T6042 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@255606ea name:ZooKeeperConnection Watcher:127.0.0.1:56351 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 868701 T6017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 868701 T6017 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 868705 T6017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 868707 T6045 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@38445406 name:ZooKeeperConnection Watcher:127.0.0.1:56351/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 868708 T6017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 868711 T6017 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 868715 T6017 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 868719 T6017 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 868724 T6017 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 868728 T6017 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 868735 T6017 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 868739 T6017 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 868742 T6017 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 868745 T6017 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56354_
[junit4] 2> 868747 T6017 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56354_
[junit4] 2> 868750 T6017 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 868752 T6017 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 868755 T6017 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 868758 T6017 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:56354_
[junit4] 2> 868759 T6017 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 868761 T6017 oasc.Overseer.start Overseer (id=93528916322549763-127.0.0.1:56354_-n_0000000000) starting
[junit4] 2> 868765 T6017 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 868780 T6017 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 868781 T6047 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 868781 T6017 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 868783 T6046 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 868792 T6017 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores
[junit4] 2> 868794 T6017 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 868795 T6017 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/
[junit4] 2> 868795 T6017 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 868797 T6049 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 868797 T6049 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 868798 T6045 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 868798 T6049 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 868800 T6046 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56354",
[junit4] 2> "node_name":"127.0.0.1:56354_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 868801 T6046 oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56354",
[junit4] 2> "node_name":"127.0.0.1:56354_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 868802 T6046 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 868802 T6046 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 868804 T6045 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 869800 T6049 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 869800 T6049 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 869801 T6049 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 869801 T6049 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 869803 T6049 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 869803 T6049 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/'
[junit4] 2> 869819 T6049 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 869822 T6049 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 869829 T6049 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 869832 T6049 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 869836 T6049 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 869837 T6049 oass.ManagedIndexSchemaFactory.create The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 869839 T6049 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 869845 T6049 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 869947 T6049 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 869956 T6049 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 869957 T6049 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 869975 T6049 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 869979 T6049 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 869982 T6049 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 869983 T6049 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 869984 T6049 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 869984 T6049 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 869985 T6049 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 869985 T6049 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 869989 T6049 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 869991 T6049 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak
[junit4] 2> 869997 T6049 oass.ManagedIndexSchemaFactory.zkUgradeToManagedSchema After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak
[junit4] 2> 869998 T6049 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 869998 T6049 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 869999 T6049 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/data/
[junit4] 2> 869999 T6049 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 870001 T6049 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/data
[junit4] 2> 870002 T6049 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/data/index/
[junit4] 2> 870002 T6049 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 870003 T6049 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/data/index
[junit4] 2> 870004 T6049 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=10.806640625, floorSegmentMB=1.41015625, forceMergeDeletesPctAllowed=9.480615705872658, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 870006 T6049 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5643794; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 870007 T6049 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 870010 T6049 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 870011 T6049 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 870019 T6049 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 870019 T6049 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 870021 T6049 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 870022 T6049 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 870419 T6049 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 870420 T6049 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 870421 T6049 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 870421 T6049 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 870424 T6049 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 870424 T6049 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 870425 T6049 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=12.9326171875, floorSegmentMB=0.5869140625, forceMergeDeletesPctAllowed=15.754365339908952, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6825380302008239
[junit4] 2> 870428 T6049 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/control-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5643794; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 870429 T6049 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 870429 T6049 oass.SolrIndexSearcher.<init> Opening Searcher@317185a6[collection1] main
[junit4] 2> 870430 T6049 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 870433 T6049 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 870434 T6049 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 870435 T6049 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 870435 T6049 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 870436 T6049 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 870437 T6049 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 870437 T6049 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 870437 T6049 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 870437 T6049 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 870438 T6049 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 870439 T6050 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@317185a6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 870438 T6049 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 870441 T6053 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:56354 collection:control_collection shard:shard1
[junit4] 2> 870441 T6017 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 870441 T6017 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 870442 T6017 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 870442 T6053 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 870443 T6017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 870447 T6056 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5602f614 name:ZooKeeperConnection Watcher:127.0.0.1:56351/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 870447 T6017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 870447 T6017 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 870448 T6017 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 870453 T6053 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 870454 T6017 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 870457 T6045 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 870458 T6053 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 870458 T6053 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C3492 name=collection1 org.apache.solr.core.SolrCore@25aa30b url=http://127.0.0.1:56354/collection1 node=127.0.0.1:56354_ C3492_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:56354, node_name=127.0.0.1:56354_, state=down}
[junit4] 2> 870458 T6053 C3492 P56354 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:56354/collection1/
[junit4] 2> 870459 T6053 C3492 P56354 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 870459 T6046 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 870459 T6053 C3492 P56354 oasc.SyncStrategy.syncToMe http://127.0.0.1:56354/collection1/ has no replicas
[junit4] 2> 870459 T6053 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:56354/collection1/ shard1
[junit4] 2> 870459 T6053 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 870461 T6056 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 870463 T6045 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 870477 T6045 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 870479 T6046 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:56354",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 870585 T6045 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 870586 T6056 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 870633 T6053 oasc.ZkController.register We are http://127.0.0.1:56354/collection1/ and leader is http://127.0.0.1:56354/collection1/
[junit4] 2> 870633 T6053 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56354
[junit4] 2> 870634 T6053 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 870634 T6053 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 870635 T6053 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 870637 T6045 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 870639 T6046 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56354",
[junit4] 2> "node_name":"127.0.0.1:56354_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 870641 T6046 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56354",
[junit4] 2> "node_name":"127.0.0.1:56354_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 870749 T6056 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 870749 T6045 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 870881 T6017 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1
[junit4] 2> 870884 T6017 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001
[junit4] 2> 870885 T6017 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 870890 T6017 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@10805577{/,null,AVAILABLE}
[junit4] 2> 870891 T6017 oejs.AbstractConnector.doStart Started ServerConnector@36e087f9{HTTP/1.1}{127.0.0.1:56359}
[junit4] 2> 870892 T6017 oejs.Server.doStart Started @873312ms
[junit4] 2> 870892 T6017 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=56359, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores}
[junit4] 2> 870893 T6017 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 870894 T6017 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/'
[junit4] 2> 870924 T6017 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/solr.xml
[junit4] 2> 870936 T6017 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores
[junit4] 2> 870936 T6017 oasc.CoreContainer.<init> New CoreContainer 128911531
[junit4] 2> 870936 T6017 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/]
[junit4] 2> 870937 T6017 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/lib
[junit4] 2> 870937 T6017 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/lib).
[junit4] 2> 870949 T6017 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 870949 T6017 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 870949 T6017 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 870949 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 870950 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 870950 T6017 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 870950 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 870950 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 870950 T6017 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 870950 T6017 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 870951 T6017 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 870951 T6017 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 870951 T6017 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 870951 T6017 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 870952 T6017 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 870952 T6017 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56351/solr
[junit4] 2> 870952 T6017 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 870953 T6017 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 870954 T6017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 870957 T6070 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54306f6f name:ZooKeeperConnection Watcher:127.0.0.1:56351 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 870957 T6017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 870958 T6017 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 870963 T6017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 870965 T6073 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7943c242 name:ZooKeeperConnection Watcher:127.0.0.1:56351/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 870965 T6017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 870974 T6017 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 871986 T6017 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56359_
[junit4] 2> 871987 T6017 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56359_
[junit4] 2> 871993 T6017 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 871997 T6017 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores
[junit4] 2> 871999 T6017 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 871999 T6017 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/
[junit4] 2> 871999 T6017 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 872001 T6074 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 872001 T6074 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 872002 T6045 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 872002 T6074 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 872004 T6046 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56359",
[junit4] 2> "node_name":"127.0.0.1:56359_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 872005 T6046 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56359",
[junit4] 2> "node_name":"127.0.0.1:56359_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 872005 T6046 oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 872005 T6046 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 872112 T6045 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 872112 T6073 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 872112 T6056 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 873004 T6074 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 873004 T6074 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 873005 T6074 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 873005 T6074 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 873007 T6074 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 873007 T6074 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/'
[junit4] 2> 873022 T6074 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 873026 T6074 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 873032 T6074 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 873034 T6074 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 873038 T6074 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 873040 T6074 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/managed-schema
[junit4] 2> 873043 T6074 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 873105 T6074 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 873113 T6074 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 873113 T6074 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 873116 T6074 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 873119 T6074 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 873133 T6074 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 873134 T6074 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 873135 T6074 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 873135 T6074 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 873136 T6074 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 873136 T6074 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 873141 T6074 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 873141 T6074 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 873142 T6074 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/data/
[junit4] 2> 873142 T6074 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 873143 T6074 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/data
[junit4] 2> 873143 T6074 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 873144 T6074 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 873144 T6074 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/data/index
[junit4] 2> 873144 T6074 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=10.806640625, floorSegmentMB=1.41015625, forceMergeDeletesPctAllowed=9.480615705872658, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 873146 T6074 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c3b2a33; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 873147 T6074 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 873149 T6074 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 873149 T6074 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 873155 T6074 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 873156 T6074 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 873157 T6074 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 873157 T6074 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 873160 T6074 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 873161 T6074 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 873161 T6074 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 873161 T6074 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 873162 T6074 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 873162 T6074 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 873163 T6074 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=12.9326171875, floorSegmentMB=0.5869140625, forceMergeDeletesPctAllowed=15.754365339908952, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6825380302008239
[junit4] 2> 873164 T6074 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-1-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c3b2a33; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 873164 T6074 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 873164 T6074 oass.SolrIndexSearcher.<init> Opening Searcher@c8887ff[collection1] main
[junit4] 2> 873164 T6074 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 873165 T6074 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 873166 T6074 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 873167 T6074 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 873167 T6074 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 873167 T6074 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 873168 T6074 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 873168 T6074 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 873168 T6074 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 873168 T6074 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 873169 T6074 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 873170 T6074 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 873170 T6075 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c8887ff[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 873171 T6078 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:56359 collection:collection1 shard:shard2
[junit4] 2> 873172 T6017 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 873172 T6017 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 873175 T6078 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 873184 T6078 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 873187 T6045 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 873188 T6078 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 873188 T6078 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C3493 name=collection1 org.apache.solr.core.SolrCore@6e504047 url=http://127.0.0.1:56359/collection1 node=127.0.0.1:56359_ C3493_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:56359, node_name=127.0.0.1:56359_, state=down}
[junit4] 2> 873188 T6078 C3493 P56359 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:56359/collection1/
[junit4] 2> 873188 T6078 C3493 P56359 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 873189 T6078 C3493 P56359 oasc.SyncStrategy.syncToMe http://127.0.0.1:56359/collection1/ has no replicas
[junit4] 2> 873189 T6078 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:56359/collection1/ shard2
[junit4] 2> 873189 T6078 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 873189 T6046 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 873198 T6045 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 873199 T6046 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:56359",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 873306 T6045 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 873307 T6073 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 873307 T6056 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 873353 T6078 oasc.ZkController.register We are http://127.0.0.1:56359/collection1/ and leader is http://127.0.0.1:56359/collection1/
[junit4] 2> 873353 T6078 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56359
[junit4] 2> 873353 T6078 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 873353 T6078 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 873353 T6078 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 873355 T6045 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 873358 T6046 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56359",
[junit4] 2> "node_name":"127.0.0.1:56359_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 6
[junit4] 2> 873358 T6046 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56359",
[junit4] 2> "node_name":"127.0.0.1:56359_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 873464 T6056 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 873465 T6045 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 873465 T6073 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 873514 T6017 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1
[junit4] 2> 873517 T6017 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001
[junit4] 2> 873518 T6017 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 873521 T6017 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@4c5f837b{/,null,AVAILABLE}
[junit4] 2> 873522 T6017 oejs.AbstractConnector.doStart Started ServerConnector@5a222163{HTTP/1.1}{127.0.0.1:56362}
[junit4] 2> 873522 T6017 oejs.Server.doStart Started @875941ms
[junit4] 2> 873522 T6017 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/, hostPort=56362, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores}
[junit4] 2> 873522 T6017 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 873522 T6017 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/'
[junit4] 2> 873553 T6017 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/solr.xml
[junit4] 2> 873657 T6017 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores
[junit4] 2> 873658 T6017 oasc.CoreContainer.<init> New CoreContainer 1624453963
[junit4] 2> 873658 T6017 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/]
[junit4] 2> 873659 T6017 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/lib
[junit4] 2> 873659 T6017 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/lib).
[junit4] 2> 873669 T6017 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 873669 T6017 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 873669 T6017 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 873670 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 873670 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 873670 T6017 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 873670 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 873670 T6017 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 873670 T6017 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 873670 T6017 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 873671 T6017 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 873671 T6017 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 873671 T6017 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 873672 T6017 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 873672 T6017 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 873672 T6017 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56351/solr
[junit4] 2> 873672 T6017 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 873672 T6017 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 873674 T6017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 873677 T6092 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@502fc70d name:ZooKeeperConnection Watcher:127.0.0.1:56351 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 873677 T6017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 873677 T6017 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 873681 T6017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 873684 T6095 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@77ee9d84 name:ZooKeeperConnection Watcher:127.0.0.1:56351/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 873684 T6017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 873694 T6017 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 874709 T6017 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56362_
[junit4] 2> 874711 T6017 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56362_
[junit4] 2> 874725 T6017 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 874728 T6017 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores
[junit4] 2> 874731 T6017 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1, collection=collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 874732 T6017 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/
[junit4] 2> 874733 T6017 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 874734 T6096 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 874734 T6096 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 874735 T6045 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 874735 T6096 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 874737 T6046 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56362",
[junit4] 2> "node_name":"127.0.0.1:56362_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 7
[junit4] 2> 874738 T6046 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56362",
[junit4] 2> "node_name":"127.0.0.1:56362_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 874738 T6046 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 874738 T6046 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 874845 T6056 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 874845 T6095 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 874845 T6045 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 874845 T6073 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 875737 T6096 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 875738 T6096 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 875739 T6096 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 875739 T6096 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 875741 T6096 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 875741 T6096 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/'
[junit4] 2> 875760 T6096 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 875764 T6096 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 875775 T6096 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 875778 T6096 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 875830 T6096 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 875832 T6096 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/managed-schema
[junit4] 2> 875836 T6096 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 875904 T6096 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 875914 T6096 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 875915 T6096 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 875919 T6096 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 875923 T6096 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 875939 T6096 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 875941 T6096 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 875941 T6096 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 875942 T6096 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 875944 T6096 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 875944 T6096 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 875949 T6096 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 875950 T6096 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 875950 T6096 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/data/
[junit4] 2> 875950 T6096 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 875951 T6096 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/data
[junit4] 2> 875951 T6096 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/data/index/
[junit4] 2> 875952 T6096 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 875952 T6096 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/data/index
[junit4] 2> 875952 T6096 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=10.806640625, floorSegmentMB=1.41015625, forceMergeDeletesPctAllowed=9.480615705872658, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 875955 T6096 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent 32AB64B7EC33568D-001/shard-2-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7183fe5d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 875955 T6096 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 875959 T6096 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 875959 T6096 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 875966 T6096 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 875967 T6096 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 875968 T6096 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 875969 T6096 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 875972 T6096 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/rep
[...truncated too long message...]
yUtil.retryOnThrowable(RetryUtil.java:31)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:135)
[junit4] 2> ... 11 more
[junit4] 2>
[junit4] 2> 2218878 T11466 oejs.AbstractConnector.doStop Stopped ServerConnector@d32bf94{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 2218878 T11466 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@4dc081f1{/dj_uvk,null,UNAVAILABLE}
[junit4] 2> 2218887 T11466 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:59642 59642
[junit4] 2> 2218900 T11467 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:59642 59642
[junit4] 2> 2219433 T11467 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 110 /solr/configs/conf1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrConfigHandlerCloud -Dtests.method=test -Dtests.seed=32AB64B7EC33568D -Dtests.slow=true -Dtests.locale=is_IS -Dtests.timezone=Asia/Tel_Aviv -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 55.5s J1 | TestSolrConfigHandlerCloud.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not get expected value 'P val' for path 'response/params/y/p' full output: {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":0},
[junit4] > "response":{
[junit4] > "znodeVersion":1,
[junit4] > "params":{
[junit4] > "x":{
[junit4] > "a":"A val",
[junit4] > "b":"B val",
[junit4] > "":{"v":0}},
[junit4] > "y":{
[junit4] > "c":"CY val",
[junit4] > "b":"BY val",
[junit4] > "i":20,
[junit4] > "d":[
[junit4] > "val 1",
[junit4] > "val 2"],
[junit4] > "":{"v":0}}}}}
[junit4] > at __randomizedtesting.SeedInfo.seed([32AB64B7EC33568D:BAFF5B6D42CF3B75]:0)
[junit4] > at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:399)
[junit4] > at org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:245)
[junit4] > at org.apache.solr.handler.TestSolrConfigHandlerCloud.test(TestSolrConfigHandlerCloud.java:78)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2219447 T11466 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud 32AB64B7EC33568D-001
[junit4] 2> 55502 T11465 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50), sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=is_IS, timezone=Asia/Tel_Aviv
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_40 (64-bit)/cpus=3,threads=1,free=189958480,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestSchemaVersionResource, TestSerializedLuceneMatchVersion, TestMiniSolrCloudClusterSSL, ResponseHeaderTest, DistribCursorPagingTest, DistributedFacetPivotLargeTest, LoggingHandlerTest, SegmentsInfoRequestHandlerTest, IndexSchemaTest, TestSimpleTrackingShardHandler, StatelessScriptUpdateProcessorFactoryTest, TestCloudManagedSchemaConcurrent, TestDynamicLoading, StandardRequestHandlerTest, TestSortingResponseWriter, TestAnalyzeInfixSuggestions, ConvertedLegacyTest, SchemaVersionSpecificBehaviorTest, TestFiltering, EchoParamsTest, TestSearcherReuse, TestCoreDiscovery, TestSolrDynamicMBean, MergeStrategyTest, XsltUpdateRequestHandlerTest, QueryParsingTest, TestDefaultSimilarityFactory, TestRandomFaceting, TestTrie, TestHashQParserPlugin, PrimitiveFieldTypeTest, TestCursorMarkWithoutUniqueKey, ExternalFileFieldSortTest, TermsComponentTest, TestStandardQParsers, TestInfoStreamLogging, BadCopyFieldTest, TestLMDirichletSimilarityFactory, TestInitParams, DebugComponentTest, SolrCmdDistributorTest, TestSchemaResource, SpellCheckComponentTest, BinaryUpdateRequestHandlerTest, TestSolrCoreProperties, TestZkChroot, TermVectorComponentTest, TestCloudSchemaless, DistributedTermsComponentTest, TestDocSet, SolrRequestParserTest, TestRemoteStreaming, MBeansHandlerTest, RecoveryAfterSoftCommitTest, TermVectorComponentDistributedTest, WordBreakSolrSpellCheckerTest, CloudMLTQParserTest, TestNonDefinedSimilarityFactory, AnalyticsMergeStrategyTest, HdfsBasicDistributedZkTest, JsonLoaderTest, TestHdfsUpdateLog, TestDefaultStatsCache, CurrencyFieldXmlFileTest, OpenExchangeRatesOrgProviderTest, DirectUpdateHandlerOptimizeTest, TestStressUserVersions, TestSolrJ, TestSolrDeletionPolicy1, BasicZkTest, DateRangeFieldTest, PluginInfoTest, CollectionsAPIDistributedZkTest, ChaosMonkeySafeLeaderTest, FieldMutatingUpdateProcessorTest, TestDocBasedVersionConstraints, ShardSplitTest, DocumentBuilderTest, TestOrdValues, DeleteInactiveReplicaTest, TestClassNameShortening, TestBulkSchemaConcurrent, TestLuceneMatchVersion, TestRTGBase, HdfsLockFactoryTest, HdfsChaosMonkeySafeLeaderTest, ShardRoutingCustomTest, CollectionsAPIAsyncDistributedZkTest, TestSolrQueryParserDefaultOperatorResource, SimpleMLTQParserTest, ConnectionManagerTest, ExitableDirectoryReaderTest, TestCryptoKeys, TriLevelCompositeIdRoutingTest, TestSolrDeletionPolicy2, SuggesterTSTTest, TestBlobHandler, TestReversedWildcardFilterFactory, TestSolrQueryParser, DocExpirationUpdateProcessorFactoryTest, TestConfig, TestSearchPerf, CopyFieldTest, SignatureUpdateProcessorFactoryTest, MinimalSchemaTest, TestSolrConfigHandler, HdfsCollectionsAPIDistributedZkTest, SpellingQueryConverterTest, TestBulkSchemaAPI, ChangedSchemaMergeTest, TestBadConfig, DateMathParserTest, TestCollectionAPI, HttpPartitionTest, TestTolerantSearch, StatsComponentTest, LeaderElectionIntegrationTest, LeaderElectionTest, ZkSolrClientTest, TestDistributedSearch, SimpleFacetsTest, TestGroupingSearch, QueryElevationComponentTest, BadIndexSchemaTest, BasicFunctionalityTest, OverseerCollectionProcessorTest, HighlighterTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, SuggesterFSTTest, TestFoldingMultitermQuery, SpatialFilterTest, PolyFieldTest, TestPseudoReturnFields, QueryEqualityTest, DisMaxRequestHandlerTest, TestQueryTypes, FileBasedSpellCheckerTest, RequestHandlersTest, MoreLikeThisHandlerTest, FastVectorHighlighterTest, RegexBoostProcessorTest, TestJmxIntegration, JSONWriterTest, TestAnalyzedSuggestions, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestBinaryResponseWriter, HighlighterConfigTest, TestQuerySenderListener, AlternateDirectoryTest, TestQuerySenderNoQuery, SolrIndexConfigTest, SampleTest, OutputWriterTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, SpellPossibilityIteratorTest, TestCodecSupport, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestFastWriter, ResourceLoaderTest, ChaosMonkeyNothingIsSafeTest, AsyncMigrateRouteKeyTest, CustomCollectionTest, MigrateRouteKeyTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, ReplicationFactorTest, TestMiniSolrCloudCluster, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZk2Test, HdfsRecoveryZkTest, HdfsSyncSliceTest, HdfsWriteToMultipleCollectionsTest, StressHdfsTest, TestClusterStateMutator, ZkStateWriterTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, TestImplicitCoreProperties, TestNRTOpen, TestReloadAndDeleteDocs, TestShardHandlerFactory, TestConfigReload, TestReplicationHandlerBackup, TestReqParamsAPI, TestSolrConfigHandlerCloud]
[junit4] Completed on J1 in 55.78s, 1 test, 1 failure <<< FAILURES!
[...truncated 177 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:191: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1351: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:958: There were test failures: 476 suites, 1894 tests, 2 failures, 53 ignored (20 assumptions)
Total time: 59 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any