lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-5.x - Build # 898 - Still Failing
Date Fri, 10 Jul 2015 19:49:24 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/898/

1 tests failed.
REGRESSION:  org.apache.solr.TestDistributedSearch.test

Error Message:


Stack Trace:
java.lang.NullPointerException
	at __randomizedtesting.SeedInfo.seed([D216D987AB6BA35D:5A42E65D0597CEA5]:0)
	at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:568)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10515 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/init-core-data-001
   [junit4]   2> 1350158 INFO  (SUITE-TestDistributedSearch-seed#[D216D987AB6BA35D]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1350158 INFO  (SUITE-TestDistributedSearch-seed#[D216D987AB6BA35D]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ocp/
   [junit4]   2> 1350475 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1350477 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1350508 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d10686{/ocp,null,AVAILABLE}
   [junit4]   2> 1350524 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@7460fecd{HTTP/1.1}{127.0.0.1:48638}
   [junit4]   2> 1350524 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.e.j.s.Server Started @1353859ms
   [junit4]   2> 1350524 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores, hostPort=48638, hostContext=/ocp}
   [junit4]   2> 1350525 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1fe91485
   [junit4]   2> 1350525 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/'
   [junit4]   2> 1350552 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/solr.xml
   [junit4]   2> 1350591 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores
   [junit4]   2> 1350592 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoreContainer New CoreContainer 1612960516
   [junit4]   2> 1350592 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/]
   [junit4]   2> 1350592 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/lib
   [junit4]   2> 1350592 WARN  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/lib).
   [junit4]   2> 1350604 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1350605 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1350606 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1350606 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1350606 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1350608 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores
   [junit4]   2> 1350608 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.SolrCore Created CoreDescriptor: {config=solrconfig.xml, schema=schema.xml, name=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1/, transient=false, configSet=collection1, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1, loadOnStartup=true, dataDir=data/}
   [junit4]   2> 1350609 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1/
   [junit4]   2> 1350609 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1350619 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1350643 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0
   [junit4]   2> 1350644 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1350645 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
   [junit4]   2> 1350645 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1350645 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1350684 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1350716 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
   [junit4]   2> 1350778 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1350779 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.IndexSchema Reading Solr Schema from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 1350786 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1351154 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1351165 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1351168 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1351174 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1351177 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1351180 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1351180 WARN  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1351180 WARN  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1351181 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1351181 WARN  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1351181 WARN  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1351181 INFO  (coreLoadExecutor-2207-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1351181 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1351181 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/], dataDir=[null]
   [junit4]   2> 1351182 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@470336d7
   [junit4]   2> 1351182 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1/data
   [junit4]   2> 1351182 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1/data/index/
   [junit4]   2> 1351182 WARN  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1351183 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1/data/index
   [junit4]   2> 1351183 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.0556640625, noCFSRatio=0.0]
   [junit4]   2> 1351183 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@423dfdd4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73e38b45),segFN=segments_1,generation=1}
   [junit4]   2> 1351184 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1351188 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1351189 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1351189 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1351189 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1351189 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1351189 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1351189 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1351189 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1351190 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1351190 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1351190 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1351190 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1351193 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1351194 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1351195 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1/data/
   [junit4]   2> 1351196 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/control/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1351208 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1351210 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1351214 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1351217 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: dismax,/config,/update/csv,spellCheckCompRH,/admin/segments,spellCheckCompRH_Direct,/search-facet-def,/admin/logging,spellCheckCompRH1,/admin/threads,lazy,/terms,mock,mltrh,/admin/ping,/admin/system,/mlt,/schema,/admin/mbeans,/admin/file,/update/json/docs,/get,spellCheckWithWordbreak_Direct,/admin/luke,tvrh,/debug/dump,/search-facet-invariants,/admin/properties,/update/json,/admin/plugins,standard,/update,spellCheckWithWordbreak,/replication,defaults
   [junit4]   2> 1351218 WARN  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 1351219 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1351228 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1351228 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1351229 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1351229 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1351229 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=592211097, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 1351230 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@423dfdd4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73e38b45),segFN=segments_1,generation=1}
   [junit4]   2> 1351230 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1351230 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@115ec040[collection1] main
   [junit4]   2> 1351230 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1351230 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1351231 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1351231 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1351254 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1351297 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1351298 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1351298 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1351298 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1351298 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1351299 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1351299 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1351299 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1351299 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1351299 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 1351300 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 1351300 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1351300 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1351301 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@115ec040[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1351300 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1506330010416316416
   [junit4]   2> 1351313 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.u.UpdateLog Took 14 ms to seed version buckets with highest version 1506330010416316416
   [junit4]   2> 1351314 INFO  (coreLoadExecutor-2207-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1352035 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1352036 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1352038 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@77d12a41{/ocp,null,AVAILABLE}
   [junit4]   2> 1352038 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@452d4edb{HTTP/1.1}{127.0.0.1:41298}
   [junit4]   2> 1352038 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.e.j.s.Server Started @1355373ms
   [junit4]   2> 1352039 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ocp, hostPort=41298, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores}
   [junit4]   2> 1352039 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1fe91485
   [junit4]   2> 1352039 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/'
   [junit4]   2> 1352055 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1352069 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores
   [junit4]   2> 1352069 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoreContainer New CoreContainer 1369714050
   [junit4]   2> 1352069 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/]
   [junit4]   2> 1352069 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/lib
   [junit4]   2> 1352070 WARN  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/lib).
   [junit4]   2> 1352078 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1352079 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1352079 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1352080 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1352080 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1352080 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores
   [junit4]   2> 1352081 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.SolrCore Created CoreDescriptor: {absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1/, transient=false, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1, name=collection1, dataDir=data/, configSet=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml}
   [junit4]   2> 1352081 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1/
   [junit4]   2> 1352081 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1352082 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1352082 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0
   [junit4]   2> 1352083 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1352083 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
   [junit4]   2> 1352083 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1352084 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1352105 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1352184 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
   [junit4]   2> 1352268 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1352270 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.IndexSchema Reading Solr Schema from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 1352283 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1352627 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1352635 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1352636 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1352642 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1352645 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1352647 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1352647 WARN  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1352648 WARN  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1352648 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1352648 WARN  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1352648 WARN  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1352648 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1352648 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1352649 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/], dataDir=[null]
   [junit4]   2> 1352649 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@470336d7
   [junit4]   2> 1352649 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1/data
   [junit4]   2> 1352649 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1/data/index/
   [junit4]   2> 1352650 WARN  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1352650 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1/data/index
   [junit4]   2> 1352650 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.0556640625, noCFSRatio=0.0]
   [junit4]   2> 1352651 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@48e55ca2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a24b511),segFN=segments_1,generation=1}
   [junit4]   2> 1352651 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1352656 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1352657 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1352657 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1352657 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1352657 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1352657 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1352657 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1352658 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1352658 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1352658 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1352658 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1352658 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1352661 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1352662 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1352663 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1/data/
   [junit4]   2> 1352664 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_D216D987AB6BA35D-001/tempDir-001/shard0/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1352665 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1352667 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1352672 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1352675 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: dismax,/config,/update/csv,spellCheckCompRH,/admin/segments,spellCheckCompRH_Direct,/search-facet-def,/admin/logging,spellCheckCompRH1,/admin/threads,lazy,/terms,mock,mltrh,/admin/ping,/admin/system,/mlt,/schema,/admin/mbeans,/admin/file,/update/json/docs,/get,spellCheckWithWordbreak_Direct,/admin/luke,tvrh,/debug/dump,/search-facet-invariants,/admin/properties,/update/json,/admin/plugins,standard,/update,spellCheckWithWordbreak,/replication,defaults
   [junit4]   2> 1352676 WARN  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 1352677 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1352678 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1352678 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1352679 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1352679 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1352687 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=592211097, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 1352687 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@48e55ca2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a24b511),segFN=segments_1,generation=1}
   [junit4]   2> 1352687 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1352688 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@50e98a60[collection1] main
   [junit4]   2> 1352688 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1352688 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1352688 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1352688 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1352709 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1352740 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1352741 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1352741 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1352741 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1352741 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1352742 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1352742 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1352742 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1352742 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 1352742 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 1352742 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1506330011928363008
   [junit4]   2> 1352742 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1352743 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1352743 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1352744 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@50e98a60[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1352744 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1506330011928363008
   [junit4]   2> 1352745 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1353084 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1353102 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1506330012304801792)} 0 1
   [junit4]   2> 1353121 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1506330012324724736)} 0 1
   [junit4]   2> 1353127 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[1 (1506330012328919040)]} 0 2
   [junit4]   2> 1353132 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[1 (1506330012334161920)]} 0 2
   [junit4]   2> 1353135 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[2 (1506330012339404800)]} 0 1
   [junit4]   2> 1353138 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[2 (1506330012342550528)]} 0 0
   [junit4]   2> 1353146 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[3 (1506330012350939136)]} 0 1
   [junit4]   2> 1353149 INFO  (qtp1736485992-3929) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[3 (1506330012354084864)]} 0 1
   [junit4]   2> 1353152 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[4 (1506330012357230592)]} 0 1
   [junit4]   2> 1353156 INFO  (qtp1736485992-3934) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[4 (1506330012361424896)]} 0 1
   [junit4]   2> 1353159 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[5 (1506330012364570624)]} 0 1
   [junit4]   2> 1353162 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[5 (1506330012367716352)]} 0 0
   [junit4]   2> 1353164 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[6 (1506330012370862080)]} 0 0
   [junit4]   2> 1353167 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[6 (1506330012372959232)]} 0 1
   [junit4]   2> 1353170 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[7 (1506330012376104960)]} 0 0
   [junit4]   2> 1353173 INFO  (qtp1736485992-3937) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[7 (1506330012379250688)]} 0 1
   [junit4]   2> 1353175 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[8 (1506330012382396416)]} 0 0
   [junit4]   2> 1353178 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[8 (1506330012384493568)]} 0 0
   [junit4]   2> 1353180 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[9 (1506330012387639296)]} 0 0
   [junit4]   2> 1353183 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[9 (1506330012389736448)]} 0 0
   [junit4]   2> 1353185 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1353217 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@423dfdd4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73e38b45),segFN=segments_1,generation=1}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@423dfdd4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73e38b45),segFN=segments_2,generation=2}
   [junit4]   2> 1353218 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1353221 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2225fce7[collection1] main
   [junit4]   2> 1353222 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1353224 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2225fce7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.3.0):C9)))}
   [junit4]   2> 1353224 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&softCommit=false&waitSearcher=true&commit=true&wt=javabin} {commit=} 0 39
   [junit4]   2> 1353226 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1353270 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@48e55ca2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a24b511),segFN=segments_1,generation=1}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@48e55ca2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a24b511),segFN=segments_2,generation=2}
   [junit4]   2> 1353270 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1353273 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@67d199cb[collection1] main
   [junit4]   2> 1353274 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@67d199cb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.3.0):C9)))}
   [junit4]   2> 1353275 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1353275 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&softCommit=false&waitSearcher=true&commit=true&wt=javabin} {commit=} 0 48
   [junit4]   2> 1353279 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[10 (1506330012489351168)]} 0 1
   [junit4]   2> 1353283 INFO  (qtp1736485992-3929) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[10 (1506330012493545472)]} 0 2
   [junit4]   2> 1353286 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[11 (1506330012497739776)]} 0 1
   [junit4]   2> 1353289 INFO  (qtp1736485992-3934) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[11 (1506330012500885504)]} 0 0
   [junit4]   2> 1353291 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[12 (1506330012504031232)]} 0 0
   [junit4]   2> 1353294 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[12 (1506330012506128384)]} 0 0
   [junit4]   2> 1353296 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[13 (1506330012509274112)]} 0 0
   [junit4]   2> 1353298 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[13 (1506330012511371264)]} 0 0
   [junit4]   2> 1353301 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[1001 (1506330012513468416)]} 0 0
   [junit4]   2> 1353304 INFO  (qtp1736485992-3937) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[1001 (1506330012516614144)]} 0 0
   [junit4]   2> 1353306 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[14 (1506330012518711296)]} 0 0
   [junit4]   2> 1353308 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[14 (1506330012521857024)]} 0 0
   [junit4]   2> 1353311 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[15 (1506330012523954176)]} 0 0
   [junit4]   2> 1353313 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[15 (1506330012527099904)]} 0 0
   [junit4]   2> 1353316 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[16 (1506330012530245632)]} 0 0
   [junit4]   2> 1353319 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[16 (1506330012532342784)]} 0 0
   [junit4]   2> 1353322 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[17 (1506330012535488512)]} 0 0
   [junit4]   2> 1353324 INFO  (qtp1736485992-3929) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[17 (1506330012538634240)]} 0 0
   [junit4]   2> 1353326 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[100 (1506330012540731392)]} 0 0
   [junit4]   2> 1353330 INFO  (qtp1736485992-3934) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[100 (1506330012544925696)]} 0 0
   [junit4]   2> 1353333 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[101 (1506330012547022848)]} 0 0
   [junit4]   2> 1353335 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[101 (1506330012550168576)]} 0 0
   [junit4]   2> 1353337 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[102 (1506330012552265728)]} 0 0
   [junit4]   2> 1353339 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[102 (1506330012554362880)]} 0 0
   [junit4]   2> 1353342 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[103 (1506330012556460032)]} 0 0
   [junit4]   2> 1353344 INFO  (qtp1736485992-3937) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[103 (1506330012558557184)]} 0 0
   [junit4]   2> 1353346 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[104 (1506330012561702912)]} 0 0
   [junit4]   2> 1353348 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[104 (1506330012563800064)]} 0 0
   [junit4]   2> 1353351 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[105 (1506330012565897216)]} 0 0
   [junit4]   2> 1353353 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[105 (1506330012567994368)]} 0 0
   [junit4]   2> 1353355 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[106 (1506330012571140096)]} 0 0
   [junit4]   2> 1353357 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[106 (1506330012573237248)]} 0 0
   [junit4]   2> 1353359 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[107 (1506330012575334400)]} 0 0
   [junit4]   2> 1353362 INFO  (qtp1736485992-3929) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[107 (1506330012577431552)]} 0 0
   [junit4]   2> 1353364 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[108 (1506330012580577280)]} 0 0
   [junit4]   2> 1353366 INFO  (qtp1736485992-3934) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[108 (1506330012582674432)]} 0 0
   [junit4]   2> 1353369 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[109 (1506330012584771584)]} 0 0
   [junit4]   2> 1353371 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[109 (1506330012586868736)]} 0 0
   [junit4]   2> 1353373 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[110 (1506330012590014464)]} 0 0
   [junit4]   2> 1353375 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[110 (1506330012592111616)]} 0 0
   [junit4]   2> 1353377 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[111 (1506330012594208768)]} 0 0
   [junit4]   2> 1353380 INFO  (qtp1736485992-3937) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[111 (1506330012596305920)]} 0 0
   [junit4]   2> 1353382 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[112 (1506330012598403072)]} 0 0
   [junit4]   2> 1353384 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[112 (1506330012601548800)]} 0 0
   [junit4]   2> 1353386 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[113 (1506330012603645952)]} 0 0
   [junit4]   2> 1353389 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[113 (1506330012605743104)]} 0 0
   [junit4]   2> 1353391 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[114 (1506330012607840256)]} 0 0
   [junit4]   2> 1353393 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[114 (1506330012609937408)]} 0 0
   [junit4]   2> 1353395 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[115 (1506330012613083136)]} 0 0
   [junit4]   2> 1353397 INFO  (qtp1736485992-3929) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[115 (1506330012615180288)]} 0 0
   [junit4]   2> 1353399 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[116 (1506330012617277440)]} 0 0
   [junit4]   2> 1353402 INFO  (qtp1736485992-3934) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[116 (1506330012619374592)]} 0 0
   [junit4]   2> 1353404 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[117 (1506330012621471744)]} 0 0
   [junit4]   2> 1353410 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[117 (1506330012627763200)]} 0 0
   [junit4]   2> 1353412 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[118 (1506330012630908928)]} 0 0
   [junit4]   2> 1353416 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[118 (1506330012635103232)]} 0 0
   [junit4]   2> 1353430 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[119 (1506330012648734720)]} 0 0
   [junit4]   2> 1353433 INFO  (qtp1736485992-3937) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[119 (1506330012651880448)]} 0 0
   [junit4]   2> 1353436 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[120 (1506330012656074752)]} 0 0
   [junit4]   2> 1353439 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[120 (1506330012659220480)]} 0 0
   [junit4]   2> 1353442 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[121 (1506330012661317632)]} 0 0
   [junit4]   2> 1353445 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[121 (1506330012664463360)]} 0 0
   [junit4]   2> 1353447 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[122 (1506330012667609088)]} 0 0
   [junit4]   2> 1353450 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[122 (1506330012669706240)]} 0 0
   [junit4]   2> 1353452 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[123 (1506330012672851968)]} 0 0
   [junit4]   2> 1353455 INFO  (qtp1736485992-3929) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[123 (1506330012675997696)]} 0 0
   [junit4]   2> 1353458 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[124 (1506330012678094848)]} 0 0
   [junit4]   2> 1353461 INFO  (qtp1736485992-3934) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[124 (1506330012681240576)]} 0 0
   [junit4]   2> 1353464 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[125 (1506330012684386304)]} 0 1
   [junit4]   2> 1353467 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[125 (1506330012687532032)]} 0 0
   [junit4]   2> 1353469 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[126 (1506330012690677760)]} 0 0
   [junit4]   2> 1353471 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[126 (1506330012692774912)]} 0 0
   [junit4]   2> 1353474 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[127 (1506330012695920640)]} 0 0
   [junit4]   2> 1353477 INFO  (qtp1736485992-3937) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[127 (1506330012699066368)]} 0 0
   [junit4]   2> 1353480 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[128 (1506330012702212096)]} 0 0
   [junit4]   2> 1353483 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[128 (1506330012704309248)]} 0 0
   [junit4]   2> 1353486 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[129 (1506330012707454976)]} 0 0
   [junit4]   2> 1353488 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[129 (1506330012710600704)]} 0 0
   [junit4]   2> 1353491 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[130 (1506330012712697856)]} 0 0
   [junit4]   2> 1353493 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[130 (1506330012715843584)]} 0 0
   [junit4]   2> 1353496 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[131 (1506330012717940736)]} 0 0
   [junit4]   2> 1353498 INFO  (qtp1736485992-3929) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[131 (1506330012720037888)]} 0 0
   [junit4]   2> 1353501 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[132 (1506330012723183616)]} 0 0
   [junit4]   2> 1353503 INFO  (qtp1736485992-3934) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[132 (1506330012726329344)]} 0 0
   [junit4]   2> 1353506 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[133 (1506330012728426496)]} 0 0
   [junit4]   2> 1353508 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[133 (1506330012731572224)]} 0 0
   [junit4]   2> 1353511 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[134 (1506330012733669376)]} 0 0
   [junit4]   2> 1353513 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[134 (1506330012736815104)]} 0 0
   [junit4]   2> 1353516 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[135 (1506330012739960832)]} 0 0
   [junit4]   2> 1353519 INFO  (qtp1736485992-3937) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[135 (1506330012742057984)]} 0 0
   [junit4]   2> 1353521 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[136 (1506330012745203712)]} 0 0
   [junit4]   2> 1353524 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[136 (1506330012747300864)]} 0 0
   [junit4]   2> 1353527 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[137 (1506330012750446592)]} 0 0
   [junit4]   2> 1353529 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[137 (1506330012753592320)]} 0 0
   [junit4]   2> 1353532 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[138 (1506330012755689472)]} 0 0
   [junit4]   2> 1353535 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[138 (1506330012758835200)]} 0 0
   [junit4]   2> 1353538 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[139 (1506330012761980928)]} 0 0
   [junit4]   2> 1353541 INFO  (qtp1736485992-3929) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[139 (1506330012765126656)]} 0 0
   [junit4]   2> 1353544 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[140 (1506330012768272384)]} 0 0
   [junit4]   2> 1353547 INFO  (qtp1736485992-3934) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[140 (1506330012771418112)]} 0 0
   [junit4]   2> 1353550 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[141 (1506330012775612416)]} 0 0
   [junit4]   2> 1353553 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[141 (1506330012777709568)]} 0 0
   [junit4]   2> 1353556 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[142 (1506330012780855296)]} 0 0
   [junit4]   2> 1353559 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[142 (1506330012784001024)]} 0 0
   [junit4]   2> 1353562 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[143 (1506330012787146752)]} 0 0
   [junit4]   2> 1353564 INFO  (qtp1736485992-3937) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[143 (1506330012790292480)]} 0 0
   [junit4]   2> 1353567 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[144 (1506330012793438208)]} 0 0
   [junit4]   2> 1353570 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[144 (1506330012795535360)]} 0 0
   [junit4]   2> 1353573 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[145 (1506330012798681088)]} 0 0
   [junit4]   2> 1353575 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[145 (1506330012801826816)]} 0 0
   [junit4]   2> 1353578 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[146 (1506330012804972544)]} 0 0
   [junit4]   2> 1353580 INFO  (qtp1736485992-3930) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[146 (1506330012807069696)]} 0 0
   [junit4]   2> 1353583 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[147 (1506330012809166848)]} 0 0
   [junit4]   2> 1353586 INFO  (qtp1736485992-3929) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[147 (1506330012812312576)]} 0 0
   [junit4]   2> 1353589 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[148 (1506330012815458304)]} 0 0
   [junit4]   2> 1353591 INFO  (qtp1736485992-3934) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[148 (1506330012818604032)]} 0 0
   [junit4]   2> 1353594 INFO  (qtp241294005-3913) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[149 (1506330012821749760)]} 0 0
   [junit4]   2> 1353597 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&wt=javabin} {add=[149 (1506330012823846912)]} 0 0
   [junit4]   2> 1353599 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1353707 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@423dfdd4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73e38b45),segFN=segments_2,generation=2}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@423dfdd4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73e38b45),segFN=segments_3,generation=3}
   [junit4]   2> 1353707 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 3
   [junit4]   2> 1353708 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@279a56eb[collection1] main
   [junit4]   2> 1353708 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1353710 INFO  (searcherExecutor-2208-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@279a56eb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.3.0):C9) Uninverting(_1(5.3.0):C59)))}
   [junit4]   2> 1353710 INFO  (qtp241294005-3909) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&softCommit=false&waitSearcher=true&commit=true&wt=javabin} {commit=} 0 111
   [junit4]   2> 1353712 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1353757 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@48e55ca2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a24b511),segFN=segments_2,generation=2}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@48e55ca2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a24b511),segFN=segments_3,generation=3}
   [junit4]   2> 1353757 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 3
   [junit4]   2> 1353758 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3b2b9e40[collection1] main
   [junit4]   2> 1353758 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1353759 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3b2b9e40[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.3.0):C9) Uninverting(_1(5.3.0):C59)))}
   [junit4]   2> 1353760 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/ocp path=/update params={version=2&softCommit=false&waitSearcher=true&commit=true&wt=javabin} {commit=} 0 47
   [junit4]   2> 1353763 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.c.SolrCore Ignoring exception matching parameter cannot be negative
   [junit4]   2> 1353763 INFO  (qtp241294005-3910) [    x:collection1] o.a.s.c.S.Request [collection1] webapp=/ocp path=/select params={start=-1&distrib=false&version=2&wt=javabin&q=*} status=400 QTime=1 
   [junit4]   2> 1353765 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.c.SolrCore Ignoring exception matching parameter cannot be negative
   [junit4]   2> 1353766 INFO  (qtp241294005-3911) [    x:collection1] o.a.s.c.S.Request [collection1] webapp=/ocp path=/select params={start=0&distrib=false&version=2&rows=-1&wt=javabin&q=*} status=400 QTime=0 
   [junit4]   2> 1353768 INFO  (qtp241294005-3912) [    x:collection1] o.a.s.c.S.Request [collection1] webapp=/ocp path=/select params={distrib=false&version=2&wt=javabin&q=*:*&sort=n_ti1+desc} hits=68 status=0 QTime=0 
   [junit4]   2> 1354060 INFO  (qtp1736485992-3928) [    x:collection1] o.a.s.c.S.Request [collection1] webapp=/ocp path=/select params={isShard=true&fl=id&NOW=1436548245438&start=0&distrib=false&fsv=true&version=2&rows=10&wt=javabin&q=*:*&shards.purpose=4&shard.url=[ff01::114]:33332/ocp|[ff01::213]:33332/ocp|http://127.0.0.1:41298/ocp/collection1&sort=n_ti1+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 1354065 INFO  (qtp1736485992-3926) [    x:collection1] o.a.s.c.S.Request [collection1] webapp=/ocp path=/select params={isShard=true&NOW=1436548245438&ids=7,148,103,140,138,136,111,119,2,139&distrib=false&version=2&wt=javabin&q=*:*&shards.purpose=64&shard.url=[ff01::114]:33332/ocp|[ff01::213]:33332/ocp|http://127.0.0.1:41298/ocp/collection1} status=0 QTime=0 
   [junit4]   2> 1354067 INFO  (qtp1736485992-3937) [    x:collection1] o.a.s.c.S.Request [collection1] webapp=/ocp path=/select params={shards=[ff01::114]:33332/ocp|[ff01::213]:33332/ocp|http://127.0.0.1:41298/ocp/collection1&version=2&wt=javabin&q=*:*&sort=n_ti1+desc} hits=68 status=0 QTime=295 
   [junit4]   2> 1354068 INFO  (TEST-TestDistributedSearch.test-seed#[D216D987AB6BA35D]) [    ] o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 1354140 INFO  (qtp1736485992-3935) [    x:collection1] o.a.s.c.S.Request [collection1] webapp=/ocp path=/select params={isShard=true&fl=id&NOW=1436548245804&start=0&distrib=false&fsv=true&version=2&rows=10&wt=javabin&q=*:*&shards.purpose=4&shard.url=[ff01::114]:33332/ocp|[ff01::213]:33332/ocp|http://127.0.0.1:41298/ocp/collection1&sort=n_ti1+desc} hits=68 status=0 QTime=0 
   [junit4]   2> 1354143 INFO  (qtp1736485992-3936) [    x:collection1] o.a.s.c.S.Request [collection1] webapp=/ocp path=/select params={isShard=true&NOW=1436548245804&ids=7,148,103,140,138,136,111,119,2,139&distrib=false&version=2&wt=javabin&q=*:*&shards.purpose=64

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

ightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/commons-collections-3.2.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/build/analysis/icu/lucene-analyzers-icu-5.3.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/contrib/analysis-extras/lib/icu4j-54.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.1.13.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/temp/junit4-J1-20150710_124808_200.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/temp/junit4-J1-20150710_124808_200.suites
   [junit4] ERROR: JVM J1 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1504)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:133)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:964)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:961)
   [junit4] 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4] 	at java.lang.Thread.run(Thread.java:745)

BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:543: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:484: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:61: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/extra-targets.xml:39: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:230: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:512: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1444: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:999: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.

Total time: 298 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-5.x #874
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 1285439655 bytes
Compression is 0.0%
Took 3 min 56 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message