You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/07/04 09:50:50 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_92) - Build #
17144 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17144/
Java: 64bit/jdk1.8.0_92 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.search.TestStressReorder
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper]
at __randomizedtesting.SeedInfo.seed([9E2E86F64EA5CFEB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:256)
at sun.reflect.GeneratedMethodAccessor16.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
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:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.TestStressReorder.testStressReorderVersions
Error Message:
Captured an uncaught exception in thread: Thread[id=1920, name=WRITER9, state=RUNNABLE, group=TGRP-TestStressReorder]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1920, name=WRITER9, state=RUNNABLE, group=TGRP-TestStressReorder]
Caused by: java.lang.RuntimeException: java.lang.AssertionError: seqNo=29 vs maxSeqNo=29
at __randomizedtesting.SeedInfo.seed([9E2E86F64EA5CFEB]:0)
at org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:254)
Caused by: java.lang.AssertionError: seqNo=29 vs maxSeqNo=29
at org.apache.lucene.index.DocumentsWriterDeleteQueue.getNextSequenceNumber(DocumentsWriterDeleteQueue.java:459)
at org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:161)
at org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:139)
at org.apache.lucene.index.DocumentsWriterPerThread.finishDocument(DocumentsWriterPerThread.java:334)
at org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:244)
at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
at org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:115)
at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:78)
at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:154)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2035)
at org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:124)
at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1119)
at org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1265)
at org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:227)
Build Log:
[...truncated 10792 lines...]
[junit4] Suite: org.apache.solr.search.TestStressReorder
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001
[junit4] 2> 183398 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 183398 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 183398 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 183398 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 183398 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr
[junit4] 2> 183399 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 183399 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 183422 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 183425 WARN (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 183426 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 183434 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 183441 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 183521 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 183522 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 183523 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 183525 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 183531 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 183531 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr
[junit4] 2> 183531 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr'
[junit4] 2> 183531 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 183531 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr
[junit4] 2> 183531 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.CoreContainer New CoreContainer 1351319905
[junit4] 2> 183531 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr]
[junit4] 2> 183532 WARN (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/lib
[junit4] 2> 183532 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 183536 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 183536 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 183536 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 183536 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 183536 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 183537 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig-tlog.xml, transient=false, schema=schema15.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, dataDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001, shard=shard1}
[junit4] 2> 183537 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 183537 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 183537 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr
[junit4] 2> 183538 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 183538 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 183553 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 183555 WARN (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 183555 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 183562 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 183570 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 183681 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 183683 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 183683 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 183686 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 183695 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 183695 INFO (coreLoadExecutor-967-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 183695 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 183695 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/]
[junit4] 2> 183695 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@350e4515
[junit4] 2> 183696 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001
[junit4] 2> 183696 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/index/
[junit4] 2> 183696 WARN (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 183697 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/index
[junit4] 2> 183697 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=58.3154296875, floorSegmentMB=2.1845703125, forceMergeDeletesPctAllowed=13.600094983507873, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6923138839120109
[junit4] 2> 183697 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_1,generation=1}
[junit4] 2> 183697 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 183700 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 183701 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 183701 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 183701 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 183701 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 183701 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 183702 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 183702 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 183702 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 183702 WARN (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 183703 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/
[junit4] 2> 183704 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 183706 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 183707 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 183708 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 183711 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
[junit4] 2> 183711 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 183711 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 183711 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 183712 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 183712 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 183713 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=77.1044921875, floorSegmentMB=0.4912109375, forceMergeDeletesPctAllowed=3.793701802923364, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4116257465620964
[junit4] 2> 183713 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_1,generation=1}
[junit4] 2> 183713 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 183714 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@73f8b263[collection1] main]
[junit4] 2> 183714 WARN (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 183714 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 183714 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 183714 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 183714 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 183715 INFO (searcherExecutor-968-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@73f8b263[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 183715 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 183715 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 183715 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 183715 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1538915258236665856
[junit4] 2> 183717 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1538915258236665856
[junit4] 2> 183717 INFO (coreLoadExecutor-967-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 183719 INFO (SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 183721 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStressReorderVersions
[junit4] 2> 183730 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 183732 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@56163577[collection1] realtime]
[junit4] 2> 183732 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 11
[junit4] 2> 183733 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 183733 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_2,generation=2}
[junit4] 2> 183733 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 183734 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@205b8948[collection1] main]
[junit4] 2> 183735 INFO (searcherExecutor-968-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@205b8948[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 183735 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 183736 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2
[junit4] 2> 183738 INFO (TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [ ] o.a.s.s.TestStressReorder VERBOSE: commitPercent 16 softCommitPercent 62 deletePercent 7 deleteByQueryPercent 3 ndocs 23 nWriteThreads 18 percentRealtimeQuery 75 operations 50000 nReadThreads 10
[junit4] 2> 183742 INFO (WRITER2) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 4 val= 2 version 1
[junit4] 2> 183745 INFO (WRITER4) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 15 val= 2 version 3
[junit4] 2> 183745 INFO (WRITER5) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 10 val= 2 version 4
[junit4] 2> 183745 INFO (WRITER7) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 7 val= 2 version 5
[junit4] 2> 183742 INFO (WRITER0) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 18 val= 2 version 2
[junit4] 2> 183745 INFO (WRITER12) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 18 val= 2 version 7
[junit4] 2> 183745 INFO (WRITER13) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 5 val= 2 version 6
[junit4] 2> 183755 INFO (WRITER11) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 2 val= 2 version 11
[junit4] 2> 183755 INFO (WRITER16) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 2 val= 2 version 10
[junit4] 2> 183760 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0 QTime=3
[junit4] 2> 183742 INFO (WRITER1) [ ] o.a.s.s.TestStressReorder VERBOSE: hardCommit start
[junit4] 2> 183763 INFO (WRITER1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 183759 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183759 INFO (WRITER8) [ ] o.a.s.s.TestStressReorder VERBOSE: deleting id 2 val= 2 version 16
[junit4] 2> 183765 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183759 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183759 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 183766 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183766 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183766 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183757 INFO (WRITER15) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 2 val= 2 version 15
[junit4] 2> 183757 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183766 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183756 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 183756 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 183756 INFO (WRITER9) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 22 val= 2 version 14
[junit4] 2> 183756 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183769 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183769 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183769 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183756 INFO (WRITER10) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 2 val= 2 version 13
[junit4] 2> 183756 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183756 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 183755 INFO (WRITER3) [ ] o.a.s.s.TestStressReorder VERBOSE: hardCommit start
[junit4] 2> 183755 INFO (WRITER14) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 2 val= 2 version 12
[junit4] 2> 183774 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183755 INFO (WRITER17) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 16 val= 2 version 9
[junit4] 2> 183755 INFO (WRITER6) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 11 val= 2 version 8
[junit4] 2> 183754 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[15 (3)]} 0 8
[junit4] 2> 183774 INFO (WRITER4) [ ] o.a.s.s.TestStressReorder VERBOSE: deleting id 19 val= 2 version 17
[junit4] 2> 183777 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-17&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[19 (-17)]} 0 2
[junit4] 2> 183777 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[10 (4)]} 0 31
[junit4] 2> 183777 INFO (WRITER4) [ ] o.a.s.s.TestStressReorder VERBOSE: deleting id 19 val= 2 version 17 DONE
[junit4] 2> 183777 INFO (WRITER4) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 17 val= 2 version 18
[junit4] 2> 183781 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[11 (8)]} 0 6
[junit4] 2> 183781 INFO (WRITER17) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[16 (9)]} 0 7
[junit4] 2> 183781 INFO (WRITER17) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 17 val= 2 version 21
[junit4] 2> 183782 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=7
[junit4] 2> 183782 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 183782 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183782 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183783 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183783 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 183783 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183784 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 183784 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 183784 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 183784 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183785 INFO (WRITER5) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 1 val= 2 version 19
[junit4] 2> 183785 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=15
[junit4] 2> 183785 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183785 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183785 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 183785 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183786 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183786 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183786 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 183786 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 183786 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[1 (19)]} 0 0
[junit4] 2> 183785 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=11
[junit4] 2> 183786 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=19
[junit4] 2> 183786 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183786 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 183787 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 183787 INFO (WRITER5) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 7 val= 2 version 22
[junit4] 2> 183787 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183787 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183787 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183788 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183788 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 183788 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183788 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 183788 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183788 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183788 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 183786 INFO (WRITER6) [ ] o.a.s.s.TestStressReorder VERBOSE: deleting id 18 val= 2 version 20
[junit4] 2> 183786 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183789 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183789 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183789 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183789 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183789 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183790 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183790 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 183790 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 183790 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 183790 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 183790 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 183790 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 183786 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183790 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 183791 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183791 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183791 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183791 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183791 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183791 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183787 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=20
[junit4] 2> 183791 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183791 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 183792 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183792 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 183792 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183792 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183792 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183792 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 183787 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 183792 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183793 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183792 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 183791 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183791 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=27
[junit4] 2> 183793 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183793 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183793 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183794 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183794 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183794 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183795 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183795 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183795 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 183796 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183796 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183796 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=29
[junit4] 2> 183796 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183796 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 183796 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=30
[junit4] 2> 183793 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183801 INFO (WRITER1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_3,generation=3}
[junit4] 2> 183796 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 183803 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[7 (5)]} 0 57
[junit4] 2> 183803 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183803 INFO (WRITER7) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 10 val= 3 version 23
[junit4] 2> 183793 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=27
[junit4] 2> 183804 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=42
[junit4] 2> 183804 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 183804 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183804 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 183804 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 183804 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183804 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183805 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 183805 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183805 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183805 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 183793 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183793 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183805 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183805 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:22&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183806 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183806 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 183806 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183806 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183806 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 183806 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 183806 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183804 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[4 (1)]} 0 59
[junit4] 2> 183806 INFO (WRITER2) [ ] o.a.s.s.TestStressReorder VERBOSE: adding id 4 val= 3 version 24
[junit4] 2> 183804 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183807 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=4
[junit4] 2> 183807 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183807 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:16&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183808 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183807 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 183808 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=11
[junit4] 2> 183808 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183808 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183808 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 183808 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 183808 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 183808 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183808 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183808 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 183808 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=12
[junit4] 2> 183808 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=12
[junit4] 2> 183808 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183808 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183808 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 183808 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 183808 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183809 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183809 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183809 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183809 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 183810 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183810 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 183810 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 183810 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 183810 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183810 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183810 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 183810 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 183810 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183811 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 183811 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 183811 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 183811 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 183811 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 QTime=3
[junit4] 2> 183811 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183811 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183808 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183811 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 183812 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183812 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183812 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 183812 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183812 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183812 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183812 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183812 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183811 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183810 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183810 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 183812 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 183812 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 183809 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183813 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183813 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183813 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:22&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183813 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183813 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183814 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183814 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 183814 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183814 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 183809 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=5
[junit4] 2> 183814 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 183814 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183814 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183814 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 183814 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183814 INFO (WRITER1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 183814 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183814 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183814 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183814 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183808 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183815 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183808 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=12
[junit4] 2> 183815 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183815 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183816 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 183816 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183814 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183814 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 183817 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183817 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183817 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183817 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183817 INFO (WRITER9) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{} 0 48
[junit4] 2> 183818 ERROR (WRITER9) [ ] o.a.s.s.TestStressReorder
[junit4] 2> java.lang.AssertionError: seqNo=29 vs maxSeqNo=29
[junit4] 2> at org.apache.lucene.index.DocumentsWriterDeleteQueue.getNextSequenceNumber(DocumentsWriterDeleteQueue.java:459)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:161)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:139)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterPerThread.finishDocument(DocumentsWriterPerThread.java:334)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:244)
[junit4] 2> at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
[junit4] 2> at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
[junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
[junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
[junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:115)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:78)
[junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:154)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2035)
[junit4] 2> at org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:124)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1119)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1265)
[junit4] 2> at org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:227)
[junit4] 2> 183813 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=1
[junit4] 2> 183813 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 183813 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=7
[junit4] 2> 183812 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 183819 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{} 0 32
[junit4] 2> 183817 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 183820 ERROR (WRITER5) [ ] o.a.s.s.TestStressReorder
[junit4] 2> java.lang.AssertionError: seqNo=30 vs maxSeqNo=29
[junit4] 2> at org.apache.lucene.index.DocumentsWriterDeleteQueue.getNextSequenceNumber(DocumentsWriterDeleteQueue.java:459)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:161)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:139)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterPerThread.finishDocument(DocumentsWriterPerThread.java:334)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:244)
[junit4] 2> at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
[junit4] 2> at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
[junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
[junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
[junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:115)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:78)
[junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:154)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2035)
[junit4] 2> at org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:124)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1119)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1265)
[junit4] 2> at org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:227)
[junit4] 2> 183816 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 183815 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 183821 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{} 0 62
[junit4] 2> 183821 ERROR (WRITER0) [ ] o.a.s.s.TestStressReorder
[junit4] 2> java.lang.AssertionError: seqNo=31 vs maxSeqNo=29
[junit4] 2> at org.apache.lucene.index.DocumentsWriterDeleteQueue.getNextSequenceNumber(DocumentsWriterDeleteQueue.java:459)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:161)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:139)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterPerThread.finishDocument(DocumentsWriterPerThread.java:334)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:244)
[junit4] 2> at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
[junit4] 2> at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
[junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
[junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
[junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:1
[...truncated too long message...]
ava.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.lang.RuntimeException: unclosed IndexOutput: _8.fdx
[junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:718)
[junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:653)
[junit4] 2> at org.apache.lucene.store.LockValidatingDirectoryWrapper.createOutput(LockValidatingDirectoryWrapper.java:44)
[junit4] 2> at org.apache.lucene.store.TrackingDirectoryWrapper.createOutput(TrackingDirectoryWrapper.java:43)
[junit4] 2> at org.apache.lucene.codecs.compressing.CompressingStoredFieldsWriter.<init>(CompressingStoredFieldsWriter.java:108)
[junit4] 2> at org.apache.lucene.codecs.compressing.CompressingStoredFieldsFormat.fieldsWriter(CompressingStoredFieldsFormat.java:128)
[junit4] 2> at org.apache.lucene.codecs.lucene50.Lucene50StoredFieldsFormat.fieldsWriter(Lucene50StoredFieldsFormat.java:183)
[junit4] 2> at org.apache.lucene.index.DefaultIndexingChain.initStoredFieldsWriter(DefaultIndexingChain.java:83)
[junit4] 2> at org.apache.lucene.index.DefaultIndexingChain.startStoredFields(DefaultIndexingChain.java:331)
[junit4] 2> at org.apache.lucene.index.DefaultIndexingChain.processDocument(DefaultIndexingChain.java:368)
[junit4] 2> at org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:231)
[junit4] 2> at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
[junit4] 2> at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
[junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
[junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
[junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:115)
[junit4] 2> at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:78)
[junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:154)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2035)
[junit4] 2> at org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:124)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1119)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1265)
[junit4] 2> at org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:227)
[junit4] 2>
[junit4] 2> 183908 INFO (coreCloseExecutor-973-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001;done=false>>]
[junit4] 2> 183908 INFO (coreCloseExecutor-973-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 183908 INFO (coreCloseExecutor-973-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001
[junit4] 2> ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper]
[junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] 2> at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:63)
[junit4] 2> at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:544)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:719)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:646)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:844)
[junit4] 2> at org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Lucene62, sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=en-ZA, timezone=America/Tijuana
[junit4] 2> NOTE: Linux 4.4.0-24-generic amd64/Oracle Corporation 1.8.0_92 (64-bit)/cpus=12,threads=1,free=465469008,total=508952576
[junit4] 2> NOTE: All tests run in this JVM: [TestCharFilters, SharedFSAutoReplicaFailoverUtilsTest, TestFieldCache, AnalyticsMergeStrategyTest, RestartWhileUpdatingTest, TestCSVResponseWriter, ChangedSchemaMergeTest, TestDefaultStatsCache, TestJmxMonitoredMap, CurrencyFieldXmlFileTest, MergeStrategyTest, TestLeaderElectionZkExpiry, TestFilteredDocIdSet, TestDistributedMissingSort, TestClusterStateMutator, HardAutoCommitTest, HdfsDirectoryTest, ConnectionManagerTest, TestCollationField, TestInitParams, TestSchemaManager, TestExactStatsCache, DistributedFacetPivotSmallAdvancedTest, TestFieldCacheSort, PrimitiveFieldTypeTest, ClusterStateTest, TestPostingsSolrHighlighter, ConjunctionSolrSpellCheckerTest, SuggesterWFSTTest, TestNamedUpdateProcessors, HdfsChaosMonkeySafeLeaderTest, TestFileDictionaryLookup, FullHLLTest, DeleteInactiveReplicaTest, TestWriterPerf, TestIndexSearcher, TestStressReorder]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestStressReorder -Dtests.seed=9E2E86F64EA5CFEB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-ZA -Dtests.timezone=America/Tijuana -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | TestStressReorder (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper]
[junit4] > at __randomizedtesting.SeedInfo.seed([9E2E86F64EA5CFEB]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:256)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [81/618 (1!)] on J2 in 0.53s, 1 test, 1 failure, 1 error <<< FAILURES!
[...truncated 1749 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:740: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:684: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/common-build.xml:530: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1427: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:984: There were test failures: 618 suites (10 ignored), 2590 tests, 1 suite-level error, 1 error, 117 ignored (72 assumptions) [seed: 9E2E86F64EA5CFEB]
Total time: 59 minutes 30 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_92) - Build #
17145 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17145/
Java: 64bit/jdk1.8.0_92 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
at __randomizedtesting.SeedInfo.seed([7561FB2D5FE19259:8212157599093DBF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1327)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11598 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/init-core-data-001
[junit4] 2> 1318275 INFO (SUITE-TestReplicationHandler-seed#[7561FB2D5FE19259]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1318277 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexFetchWithMasterUrl
[junit4] 2> 1318277 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/collection1
[junit4] 2> 1318279 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1318280 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3e441e66{/solr,null,AVAILABLE}
[junit4] 2> 1318280 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.ServerConnector Started ServerConnector@6ce7512b{HTTP/1.1,[http/1.1]}{127.0.0.1:41505}
[junit4] 2> 1318281 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.Server Started @1320239ms
[junit4] 2> 1318281 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=41505}
[junit4] 2> 1318281 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1318281 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001'
[junit4] 2> 1318281 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1318281 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1318281 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/solr.xml
[junit4] 2> 1318284 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/.
[junit4] 2> 1318284 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer New CoreContainer 25772814
[junit4] 2> 1318284 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001]
[junit4] 2> 1318285 WARN (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/lib
[junit4] 2> 1318285 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] 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,
[junit4] 2> 1318287 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1318287 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1318287 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1318287 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1318287 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1318287 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/.
[junit4] 2> 1318288 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 1318288 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/./collection1
[junit4] 2> 1318288 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1318288 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/collection1'
[junit4] 2> 1318289 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1
[junit4] 2> 1318289 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1318289 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1318289 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1318293 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1318295 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1318298 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1318300 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1318301 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1318302 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/collection1'
[junit4] 2> 1318302 INFO (coreLoadExecutor-3381-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/./collection1
[junit4] 2> 1318302 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1318302 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1318302 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1318303 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/./collection1/data
[junit4] 2> 1318303 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 1318303 WARN (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1318303 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/./collection1/data/index
[junit4] 2> 1318303 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=51.1552734375, floorSegmentMB=0.75, forceMergeDeletesPctAllowed=10.795290762322162, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8602760871874163
[junit4] 2> 1318303 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@630425ac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24b8b655),segFN=segments_1,generation=1}
[junit4] 2> 1318303 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1318303 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/./collection1/data/
[junit4] 2> 1318304 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1318305 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1318306 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1318306 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1318307 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@69b62bc4
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@630425ac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24b8b655),segFN=segments_1,generation=1}
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a71cfa5[collection1] main]
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/collection1/conf
[junit4] 2> 1318308 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/collection1/conf}
[junit4] 2> 1318309 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-001/collection1/conf
[junit4] 2> 1318309 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1318309 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1318309 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1318309 INFO (searcherExecutor-3382-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a71cfa5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1318309 INFO (coreLoadExecutor-3381-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1318790 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1
[junit4] 2> 1318792 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1318793 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@51947ee8{/solr,null,AVAILABLE}
[junit4] 2> 1318793 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.ServerConnector Started ServerConnector@675f29dc{HTTP/1.1,[http/1.1]}{127.0.0.1:44159}
[junit4] 2> 1318794 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.Server Started @1320752ms
[junit4] 2> 1318794 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=44159}
[junit4] 2> 1318794 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1318794 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002'
[junit4] 2> 1318794 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1318794 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1318794 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/solr.xml
[junit4] 2> 1318796 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/.
[junit4] 2> 1318796 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer New CoreContainer 1167654854
[junit4] 2> 1318796 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002]
[junit4] 2> 1318796 WARN (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/lib
[junit4] 2> 1318797 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] 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,
[junit4] 2> 1318798 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1318798 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1318798 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1318798 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1318798 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1318799 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/.
[junit4] 2> 1318799 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 1318799 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1
[junit4] 2> 1318799 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1318799 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1'
[junit4] 2> 1318800 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1318800 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1
[junit4] 2> 1318800 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1318800 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1318803 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1318804 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1318807 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1318809 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1318810 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1318811 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1'
[junit4] 2> 1318811 INFO (coreLoadExecutor-3391-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1
[junit4] 2> 1318811 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1318811 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1318811 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1318811 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data
[junit4] 2> 1318811 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 1318811 WARN (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1318811 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/index
[junit4] 2> 1318812 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=51.1552734375, floorSegmentMB=0.75, forceMergeDeletesPctAllowed=10.795290762322162, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8602760871874163
[junit4] 2> 1318812 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@54414937 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f9c31ea),segFN=segments_1,generation=1}
[junit4] 2> 1318812 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1318812 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/
[junit4] 2> 1318813 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1318813 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1318814 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1318815 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1318815 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1318815 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1318815 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@589c7580
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@54414937 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f9c31ea),segFN=segments_1,generation=1}
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@38877ffa[collection1] main]
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1/conf
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1/conf}
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1/conf
[junit4] 2> 1318816 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1318817 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1318817 INFO (searcherExecutor-3392-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@38877ffa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1318818 INFO (coreLoadExecutor-3391-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1318819 INFO (qtp59666310-8556) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1319313 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@675f29dc{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1319313 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1167654854
[junit4] 2> 1319313 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5b3df8b5
[junit4] 2> 1319314 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1319314 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1319314 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1319314 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1319315 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1319315 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1319315 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/index;done=false>>]
[junit4] 2> 1319315 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1319315 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/index
[junit4] 2> 1319315 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data;done=false>>]
[junit4] 2> 1319315 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1319315 INFO (coreCloseExecutor-3397-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data
[junit4] 2> 1319316 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@51947ee8{/solr,null,UNAVAILABLE}
[junit4] 2> 1319316 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1319317 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cdf8303{/solr,null,AVAILABLE}
[junit4] 2> 1319317 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3f2174f7{HTTP/1.1,[http/1.1]}{127.0.0.1:39677}
[junit4] 2> 1319318 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.Server Started @1321276ms
[junit4] 2> 1319318 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=39677}
[junit4] 2> 1319318 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1319318 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002'
[junit4] 2> 1319318 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1319318 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1319318 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/solr.xml
[junit4] 2> 1319320 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/.
[junit4] 2> 1319320 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer New CoreContainer 790362356
[junit4] 2> 1319320 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002]
[junit4] 2> 1319321 WARN (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/lib
[junit4] 2> 1319321 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] 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,
[junit4] 2> 1319322 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1319322 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1319322 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1319322 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1319322 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1319323 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/.
[junit4] 2> 1319323 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 1319323 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1
[junit4] 2> 1319323 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1319323 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1'
[junit4] 2> 1319324 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1319324 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1319327 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1
[junit4] 2> 1319327 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[7561FB2D5FE19259]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1319328 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1319329 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1319332 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1319333 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1319334 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1319335 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1'
[junit4] 2> 1319335 INFO (coreLoadExecutor-3404-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1
[junit4] 2> 1319335 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1319335 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1319335 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1319335 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data
[junit4] 2> 1319335 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 1319335 WARN (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1319336 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/index
[junit4] 2> 1319336 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=51.1552734375, floorSegmentMB=0.75, forceMergeDeletesPctAllowed=10.795290762322162, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8602760871874163
[junit4] 2> 1319336 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e28950c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c33d286),segFN=segments_1,generation=1}
[junit4] 2> 1319336 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1319336 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/./collection1/data/
[junit4] 2> 1319337 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1319338 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1319339 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1319339 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1319340 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1319340 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1319340 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
[junit4] 2> 1319340 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1319340 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1319340 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1319340 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@6e5a0b96
[junit4] 2> 1319340 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e28950c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c33d286),segFN=segments_1,generation=1}
[junit4] 2> 1319341 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1319341 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@524f21d2[collection1] main]
[junit4] 2> 1319341 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1/conf
[junit4] 2> 1319341 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1/conf}
[junit4] 2> 1319341 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-002/collection1/conf
[junit4] 2> 1319341 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1319341 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1319341 INFO (searcherExecutor-3405-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@524f21d2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1319341 INFO (coreLoadExecutor-3404-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1319829 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1319829 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1319830 INFO (qtp1487372995-8597) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1319831 INFO (qtp1487372995-8597) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1319831 INFO (qtp1487372995-8598) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1319831 INFO (qtp1487372995-8598) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e28950c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c33d286),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e28950c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c33d286),segFN=segments_2,generation=2}
[junit4] 2> 1319832 INFO (qtp1487372995-8598) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1319832 INFO (qtp1487372995-8598) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@cba8a77[collection1] main]
[junit4] 2> 1319832 INFO (qtp1487372995-8598) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1319832 INFO (searcherExecutor-3405-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cba8a77[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1319832 INFO (qtp1487372995-8598) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 1319833 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
[junit4] 2> 1319833 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1319834 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1319834 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1319834 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1319835 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1319835 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1319835 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1319835 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1319836 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1319836 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 1319836 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1319836 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1319837 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 1319837 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 1319837 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 1319837 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 1319838 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 1319838 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 1319838 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 1319838 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1319839 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 1319839 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 1319839 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 1319839 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 1319840 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 1319840 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 1319840 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 1319840 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1319840 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 1319841 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1319841 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 1319841 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 1319841 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1319841 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1319843 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1319843 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1319843 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1319843 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1319843 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 1319844 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1319844 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1319844 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1319844 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 1319845 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 1319845 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1319845 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1319845 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 1319846 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1319846 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1319846 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1319846 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1319847 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1319847 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1319847 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 1319848 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 1319848 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1319848 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1319848 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1319848 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1319849 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 1319849 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1319849 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1319849 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 1319850 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 1319850 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 1319850 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1319850 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1319851 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1319851 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1319851 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1319851 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 1319851 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1319852 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1319864 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1319864 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1319865 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 1319865 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 1319865 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 1319865 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 1319866 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 1319867 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 1319867 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 1319867 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 1319867 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 1319867 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 1319868 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 1319868 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 1319868 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 1319868 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 1319868 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 1319869 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 1319869 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 1319869 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 1319869 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 1319869 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 1319870 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 1319870 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 1319870 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
[junit4] 2> 1319870 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
[junit4] 2> 1319870 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 1319871 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 1319871 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 1319871 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 1319871 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 1319871 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 1319872 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 1319872 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 1319872 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 1319872 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 1319872 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 1319873 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 1319873 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 1319873 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 1319873 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 1319873 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 1319873 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 1319874 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 1319874 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 1319874 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 1319874 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 1319874 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 1319875 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 1319875 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[123]} 0 0
[junit4] 2> 1319875 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 1319875 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 1319875 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 1319876 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[127]} 0 0
[junit4] 2> 1319876 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 1319876 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 1319876 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 1319876 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 1319876 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 1319877 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 1319877 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 1319877 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 1319877 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 1319877 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 1319878 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[138]} 0 0
[junit4] 2> 1319879 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[139]} 0 0
[junit4] 2> 1319879 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[140]} 0 0
[junit4] 2> 1319879 INFO (qtp59666310-8558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[141]} 0 0
[junit4] 2> 1319879 INFO (qtp59666310-8552) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[142]} 0 0
[junit4] 2> 1319879 INFO (qtp59666310-8556) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[143]} 0 0
[junit4] 2> 1319880 INFO (qtp59666310-8557) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory
[...truncated too long message...]
hutdown=true
[junit4] 2> 1412300 INFO (coreCloseExecutor-4036-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-029/collection1/data
[junit4] 2> 1412300 INFO (coreCloseExecutor-4036-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-029/collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-029/collection1/data/index;done=false>>]
[junit4] 2> 1412300 INFO (coreCloseExecutor-4036-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1412300 INFO (coreCloseExecutor-4036-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-029/collection1/data/index
[junit4] 2> 1412301 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@724da2a{/solr,null,UNAVAILABLE}
[junit4] 2> 1412302 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@7a293558{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1412302 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[7561FB2D5FE19259]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=322387722
[junit4] 2> 1412303 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50555683
[junit4] 2> 1412303 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1412303 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1412303 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1412303 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1412304 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1412304 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1412304 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-030/collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-030/collection1/data/index;done=false>>]
[junit4] 2> 1412304 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1412304 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-030/collection1/data/index
[junit4] 2> 1412304 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-030/collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-030/collection1/data;done=false>>]
[junit4] 2> 1412304 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1412304 INFO (coreCloseExecutor-4038-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001/solr-instance-030/collection1/data
[junit4] 2> 1412305 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[7561FB2D5FE19259]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4b2f51d{/solr,null,UNAVAILABLE}
[junit4] 2> 1412306 INFO (SUITE-TestReplicationHandler-seed#[7561FB2D5FE19259]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_7561FB2D5FE19259-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62), sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=el-GR, timezone=Africa/Malabo
[junit4] 2> NOTE: Linux 4.4.0-24-generic amd64/Oracle Corporation 1.8.0_92 (64-bit)/cpus=12,threads=1,free=231369040,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrQueryParserDefaultOperatorResource, DistributedTermsComponentTest, TestHashPartitioner, TestSolrCoreProperties, DocValuesMissingTest, TestQuerySenderListener, HttpPartitionTest, TestCollapseQParserPlugin, TestLFUCache, TestInfoStreamLogging, CollectionsAPIDistributedZkTest, TestPKIAuthenticationPlugin, TestStressVersions, ZkCLITest, TestDefaultSearchFieldResource, OpenExchangeRatesOrgProviderTest, TestMissingGroups, TestScoreJoinQPNoScore, TestTolerantUpdateProcessorRandomCloud, DeleteReplicaTest, TestLMJelinekMercerSimilarityFactory, TestJsonFacets, TestShortCircuitedRequests, SpellCheckComponentTest, HighlighterTest, TestQueryWrapperFilter, DistributedFacetPivotWhiteBoxTest, NotRequiredUniqueKeyTest, BigEndianAscendingWordDeserializerTest, HdfsDirectoryFactoryTest, TestFiltering, MBeansHandlerTest, SpatialFilterTest, SuggesterFSTTest, TestHashQParserPlugin, TestCryptoKeys, DistribCursorPagingTest, ShardRoutingCustomTest, ClusterStateUpdateTest, BasicAuthIntegrationTest, TestDFISimilarityFactory, SpellPossibilityIteratorTest, TestHdfsUpdateLog, JavabinLoaderTest, BlockJoinFacetRandomTest, TestRTimerTree, TestStressCloudBlindAtomicUpdates, RulesTest, CachingDirectoryFactoryTest, AddBlockUpdateTest, TestSimpleTrackingShardHandler, FastVectorHighlighterTest, TestExpandComponent, TestSchemaSimilarityResource, DocValuesTest, TestPartialUpdateDeduplication, SolrCmdDistributorTest, TestDFRSimilarityFactory, TestSolrQueryResponse, TestEmbeddedSolrServerConstructors, TestConfigOverlay, TestQueryUtils, TestGraphMLResponseWriter, TestCloudInspectUtil, ZkSolrClientTest, ResponseHeaderTest, TestDocumentBuilder, ZkControllerTest, TestFieldCacheVsDocValues, SmileWriterTest, UUIDUpdateProcessorFallbackTest, BooleanFieldTest, InfoHandlerTest, SharedFSAutoReplicaFailoverTest, MinimalSchemaTest, TestHighFrequencyDictionaryFactory, TestBM25SimilarityFactory, DistributedMLTComponentTest, TestCursorMarkWithoutUniqueKey, ExplicitHLLTest, FileUtilsTest, TestQueryTypes, TermsComponentTest, DistributedQueueTest, SpellCheckCollatorTest, SuggestComponentContextFilterQueryTest, TestSolrDeletionPolicy2, TestQuerySenderNoQuery, OverseerTaskQueueTest, DistributedIntervalFacetingTest, TestExactSharedStatsCache, OutOfBoxZkACLAndCredentialsProvidersTest, TestWordDelimiterFilterFactory, TestReloadAndDeleteDocs, SortByFunctionTest, SolrPluginUtilsTest, TestLockTree, TestReplicationHandler]
[junit4] Completed [338/618 (1!)] on J1 in 94.04s, 15 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 936 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:740: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:684: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/common-build.xml:530: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1427: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:984: There were test failures: 618 suites (10 ignored), 2590 tests, 1 failure, 117 ignored (72 assumptions) [seed: 7561FB2D5FE19259]
Total time: 54 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any