lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.7.0_80) - Build # 88 - Failure!
Date Tue, 23 Feb 2016 11:29:20 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/88/
Java: 64bit/jdk1.7.0_80 -XX:+UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes

Error Message:
maxDoc !> numDocs ... expected some deletions

Stack Trace:
java.lang.AssertionError: maxDoc !> numDocs ... expected some deletions
	at __randomizedtesting.SeedInfo.seed([EC8641F3D74297BD:C0FF0576A2FB5F18]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes(DirectUpdateHandlerTest.java:291)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 10721 lines...]
   [junit4] Suite: org.apache.solr.update.DirectUpdateHandlerTest
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001
   [junit4]   2> 93254 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 93254 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 93254 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 93254 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 93254 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 93254 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 93254 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 93272 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 93284 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 93314 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 93319 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 93435 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 93441 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 93442 WARN  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 93447 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 93448 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 93450 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 93450 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 93450 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr'
   [junit4]   2> 93450 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 93450 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 93451 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.CoreContainer New CoreContainer 299767155
   [junit4]   2> 93451 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr]
   [junit4]   2> 93451 WARN  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/lib
   [junit4]   2> 93451 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-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,useRetries : false,
   [junit4]   2> 93452 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 93452 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 93452 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 93452 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 93452 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 93453 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {schema=schema12.xml, name=collection1, shard=shard1, configSetProperties=configsetprops.json, loadOnStartup=true, transient=false, config=solrconfig.xml, dataDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001, collection=collection1}
   [junit4]   2> 93454 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 93454 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 93454 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 93454 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 93454 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 93472 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 93483 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 93509 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 93513 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 93631 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 93631 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 93632 WARN  (coreLoadExecutor-542-thread-1) [    ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 93637 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 93639 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 93641 INFO  (coreLoadExecutor-542-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-5.5-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 93641 INFO  (coreLoadExecutor-542-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 93641 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockFSDirectoryFactory
   [junit4]   2> 93641 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/]
   [junit4]   2> 93642 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f748c4
   [junit4]   2> 93644 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001
   [junit4]   2> 93644 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index/
   [junit4]   2> 93644 WARN  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index' doesn't exist. Creating new index...
   [junit4]   2> 93644 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index
   [junit4]   2> 93644 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 93696 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_1,generation=1}
   [junit4]   2> 93696 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 93697 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-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/
   [junit4]   2> 93700 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 93700 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 93700 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 93700 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 93700 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 93700 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 93700 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 93700 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 93700 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 93701 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 93701 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 93701 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 93703 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 93704 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 93705 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 93705 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 93708 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 93710 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /debug/dump,/terms,/update,/get,/admin/plugins,spellCheckWithWordbreak_Direct,spellCheckCompRH,/search-facet-def,mltrh,/replication,spellCheckCompRH1,dismax,tvrh,/config,defaults,/mlt,/admin/logging,/admin/segments,/admin/system,/search-facet-invariants,/admin/file,spellCheckWithWordbreak,/update/json/docs,/update/json,/admin/mbeans,lazy,spellCheckCompRH_Direct,/update/csv,/schema,/admin/properties,/admin/ping,/admin/luke,/admin/threads,standard,mock
   [junit4]   2> 93711 WARN  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 93711 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 93711 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 93711 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 93712 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=16.0859375, floorSegmentMB=0.8515625, forceMergeDeletesPctAllowed=21.882753325861582, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6361451373160227
   [junit4]   2> 93715 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_1,generation=1}
   [junit4]   2> 93715 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 93715 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3456d1f5[collection1] main
   [junit4]   2> 93715 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 93715 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 93715 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 93715 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 93765 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 94001 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 94001 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 94001 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 94001 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 94001 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 94002 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 94002 INFO  (coreLoadExecutor-542-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 94002 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 94002 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 94003 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 94003 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 94003 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 94004 INFO  (searcherExecutor-543-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3456d1f5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 94006 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testPostSoftCommitEvents
   [junit4]   2> 94006 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 94007 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 94009 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94048 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_1,generation=1}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_2,generation=2}
   [junit4]   2> 94049 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 94049 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@1aecf762[collection1] main
   [junit4]   2> 94049 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94050 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1aecf762[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 94050 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 41
   [junit4]   2> 94052 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[999]} 0 0
   [junit4]   2> 94053 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 94057 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@26ef56eb[collection1] main
   [junit4]   2> 94057 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94058 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@26ef56eb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C1)))}
   [junit4]   2> 94058 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 5
   [junit4]   2> 94059 INFO  (TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testPostSoftCommitEvents
   [junit4]   2> 94062 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testBasics
   [junit4]   2> 94062 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 94062 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 94063 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94129 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_2,generation=2}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_3,generation=3}
   [junit4]   2> 94129 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 94130 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@1087660[collection1] main
   [junit4]   2> 94130 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94131 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1087660[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 94131 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 68
   [junit4]   2> 94133 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 94134 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 94135 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:5} hits=0 status=0 QTime=0
   [junit4]   2> 94135 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:6} hits=0 status=0 QTime=0
   [junit4]   2> 94136 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94366 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_3,generation=3}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_4,generation=4}
   [junit4]   2> 94367 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 4
   [junit4]   2> 94369 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@5803f6fa[collection1] main
   [junit4]   2> 94369 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94372 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5803f6fa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):C2)))}
   [junit4]   2> 94372 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 236
   [junit4]   2> 94373 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:5} hits=1 status=0 QTime=0
   [junit4]   2> 94374 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:6} hits=1 status=0 QTime=0
   [junit4]   2> 94375 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{delete=[5]} 0 0
   [junit4]   2> 94375 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:5} hits=1 status=0 QTime=0
   [junit4]   2> 94376 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94440 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_4,generation=4}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_5,generation=5}
   [junit4]   2> 94440 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 5
   [junit4]   2> 94441 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@533a0b63[collection1] main
   [junit4]   2> 94441 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94443 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@533a0b63[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):C2/1:delGen=1)))}
   [junit4]   2> 94443 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 67
   [junit4]   2> 94445 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:5} hits=0 status=0 QTime=0
   [junit4]   2> 94446 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:6} hits=1 status=0 QTime=0
   [junit4]   2> 94447 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 94447 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 94448 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:6} hits=1 status=0 QTime=0
   [junit4]   2> 94449 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94488 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_5,generation=5}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_6,generation=6}
   [junit4]   2> 94488 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 6
   [junit4]   2> 94489 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@e8220d5[collection1] main
   [junit4]   2> 94489 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94490 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e8220d5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 94491 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 42
   [junit4]   2> 94492 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:6} hits=0 status=0 QTime=0
   [junit4]   2> 94492 INFO  (TEST-DirectUpdateHandlerTest.testBasics-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testBasics
   [junit4]   2> 94496 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testRequireUniqueKey
   [junit4]   2> 94496 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 94496 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 94497 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94524 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_6,generation=6}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_7,generation=7}
   [junit4]   2> 94524 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 7
   [junit4]   2> 94525 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@39ca467c[collection1] main
   [junit4]   2> 94525 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94526 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39ca467c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 94526 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 29
   [junit4]   2> 94528 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 94529 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{} 0 0
   [junit4]   2> 94529 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.h.RequestHandlerBase Ignoring exception matching ignore_exception
   [junit4]   2> 94529 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{} 0 0
   [junit4]   2> 94530 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.h.RequestHandlerBase Ignoring exception matching id
   [junit4]   2> 94530 INFO  (TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testRequireUniqueKey
   [junit4]   2> 94533 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testPrepareCommit
   [junit4]   2> 94533 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 94533 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 94534 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94551 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_7,generation=7}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_8,generation=8}
   [junit4]   2> 94552 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 8
   [junit4]   2> 94552 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@c653def[collection1] main
   [junit4]   2> 94552 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94554 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c653def[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 94555 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 20
   [junit4]   2> 94556 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[999]} 0 0
   [junit4]   2> 94557 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94557 WARN  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 Starting optimize... Reading and rewriting the entire index! Use with care.
   [junit4]   2> 94698 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_8,generation=8}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_9,generation=9}
   [junit4]   2> 94698 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 9
   [junit4]   2> 94700 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@5ba41c3[collection1] main
   [junit4]   2> 94700 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94701 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5ba41c3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.5.0):C1)))}
   [junit4]   2> 94701 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{optimize=} 0 144
   [junit4]   2> 94703 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94703 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 94703 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 94703 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94703 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 0
   [junit4]   2> 94704 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandlerTest FILES before addDoc=[_3_LuceneVarGapFixedInterval_0.tib, _3_LuceneVarGapFixedInterval_0.doc, _3.nvm, _2.fdt, _3.fnm, _2.fdx, _3.nvd, _3.si, _3.fdx, segments_9, _3.fdt, _3_LuceneVarGapFixedInterval_0.tiv]
   [junit4]   2> 94704 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 94705 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandlerTest FILES before prepareCommit=[_3_LuceneVarGapFixedInterval_0.tib, _3_LuceneVarGapFixedInterval_0.doc, _3.nvm, _2.fdt, _3.fnm, _2.fdx, _3.nvd, _3.si, _3.fdx, segments_9, _3.fdt, _3_LuceneVarGapFixedInterval_0.tiv, _4.fdx, _4.fdt]
   [junit4]   2> 94705 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=true}
   [junit4]   2> 94915 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_prepareCommit
   [junit4]   2> 94915 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={prepareCommit=true&wt=json&indent=true}{commit=} 0 210
   [junit4]   2> 94915 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandlerTest FILES after prepareCommit=[_3_LuceneVarGapFixedInterval_0.tib, _3_LuceneVarGapFixedInterval_0.doc, _3.nvm, _2.fdt, _3.fnm, pending_segments_a, _2.fdx, _4.nvm, _4.nvd, _4_LuceneVarGapFixedInterval_0.tib, _3.nvd, _4_LuceneVarGapFixedInterval_0.tiv, _3.si, _3.fdx, _4_LuceneVarGapFixedInterval_0.doc, _4.fnm, segments_9, _3.fdt, _4.si, _3_LuceneVarGapFixedInterval_0.tiv, _4.fdx, _4.fdt]
   [junit4]   2> 94916 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:1} hits=0 status=0 QTime=0
   [junit4]   2> 94917 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start rollback{}
   [junit4]   2> 94917 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
   [junit4]   2> 94918 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6631394320986931]
   [junit4]   2> 94919 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_9,generation=9}
   [junit4]   2> 94919 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 9
   [junit4]   2> 94920 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 94920 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_rollback
   [junit4]   2> 94920 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={rollback=true&wt=json&indent=true}{rollback=} 0 3
   [junit4]   2> 94920 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 94920 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 94922 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@320f85c1[collection1] main
   [junit4]   2> 94922 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 94923 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@320f85c1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.5.0):C1)))}
   [junit4]   2> 94923 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 3
   [junit4]   2> 94924 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:1} hits=0 status=0 QTime=0
   [junit4]   2> 94925 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 94925 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=true}
   [junit4]   2> 95112 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_prepareCommit
   [junit4]   2> 95112 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={prepareCommit=true&wt=json&indent=true}{commit=} 0 186
   [junit4]   2> 95112 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:1} hits=0 status=0 QTime=0
   [junit4]   2> 95113 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95125 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_9,generation=9}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_a,generation=10}
   [junit4]   2> 95126 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 10
   [junit4]   2> 95126 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@774867a6[collection1] main
   [junit4]   2> 95127 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 95128 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@774867a6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.5.0):C1) Uninverting(_4(5.5.0):C1)))}
   [junit4]   2> 95129 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 16
   [junit4]   2> 95130 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={wt=xml&q=id:1} hits=1 status=0 QTime=0
   [junit4]   2> 95131 INFO  (TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testPrepareCommit
   [junit4]   2> 95135 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAddRollback
   [junit4]   2> 95135 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 95136 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 95137 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95196 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_a,generation=10}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@700801f0),segFN=segments_b,generation=11}
   [junit4]   2> 95196 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 11
   [junit4]   2> 95196 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@8d68fa3[collection1] main
   [junit4]   2> 95197 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 95198 INFO  (searcherExecutor-543-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8d68fa3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 95199 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 62
   [junit4]   2> 95200 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 95200 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=299767155
   [junit4]   2> 95200 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@c42e9f3
   [junit4]   2> 95200 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=12,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=1,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=6,cumulative_deletesById=1,cumulative_deletesByQuery=6,cumulative_errors=0}
   [junit4]   2> 95200 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 95200 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 95200 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 95201 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 95209 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockFSDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 95209 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001;done=false>>]
   [junit4]   2> 95209 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001
   [junit4]   2> 95209 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 95209 INFO  (coreCloseExecutor-547-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index
   [junit4]   2> 95211 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 95211 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 95211 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 95211 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 95211 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 95211 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 95229 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 95241 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 95265 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 95270 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 95396 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 95397 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 95397 WARN  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 95403 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 95404 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 95406 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 95406 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 95406 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr'
   [junit4]   2> 95406 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 95406 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 95406 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.CoreContainer New CoreContainer 1445014746
   [junit4]   2> 95406 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr]
   [junit4]   2> 95406 WARN  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/lib
   [junit4]   2> 95406 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] 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,useRetries : false,
   [junit4]   2> 95407 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 95407 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 95407 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 95407 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 95407 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 95408 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, schema=schema12.xml, transient=false, config=solrconfig.xml, loadOnStartup=true, configSetProperties=configsetprops.json, dataDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001, shard=shard1, collection=collection1}
   [junit4]   2> 95408 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 95409 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 95409 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 95409 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 95409 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 95427 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 95441 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 95473 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 95477 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 95624 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 95625 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 95626 WARN  (coreLoadExecutor-553-thread-1) [    ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 95631 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 95633 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 95636 INFO  (coreLoadExecutor-553-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-5.5-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 95636 INFO  (coreLoadExecutor-553-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 95636 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockFSDirectoryFactory
   [junit4]   2> 95636 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/]
   [junit4]   2> 95636 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f748c4
   [junit4]   2> 95637 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001
   [junit4]   2> 95637 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index/
   [junit4]   2> 95637 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-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/
   [junit4]   2> 95641 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 95641 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 95641 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 95641 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 95641 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 95641 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 95641 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 95642 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 95642 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 95642 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 95642 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 95642 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 95644 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 95645 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 95645 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 95646 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 95655 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 95657 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /debug/dump,/terms,/update,/get,/admin/plugins,spellCheckWithWordbreak_Direct,spellCheckCompRH,/search-facet-def,mltrh,/replication,spellCheckCompRH1,dismax,tvrh,/config,defaults,/mlt,/admin/logging,/admin/segments,/admin/system,/search-facet-invariants,/admin/file,spellCheckWithWordbreak,/update/json/docs,/update/json,/admin/mbeans,lazy,spellCheckCompRH_Direct,/update/csv,/schema,/admin/properties,/admin/ping,/admin/luke,/admin/threads,standard,mock
   [junit4]   2> 95657 WARN  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 95658 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 95658 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 95658 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 95659 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index
   [junit4]   2> 95659 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 95660 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@130f424b),segFN=segments_b,generation=11}
   [junit4]   2> 95660 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 11
   [junit4]   2> 95660 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@396352e[collection1] main
   [junit4]   2> 95660 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 95660 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 95660 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 95660 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 95661 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 95664 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 95664 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 95664 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 95664 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 95664 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 95664 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 95665 INFO  (coreLoadExecutor-553-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 95665 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 95665 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 95665 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 95666 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 95666 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 95666 INFO  (searcherExecutor-554-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@396352e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 95667 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[A]} 0 0
   [junit4]   2> 95667 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95925 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@130f424b),segFN=segments_b,generation=11}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@130f424b),segFN=segments_c,generation=12}
   [junit4]   2> 95925 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 12
   [junit4]   2> 95927 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@5b184a5e[collection1] main
   [junit4]   2> 95928 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 95928 INFO  (searcherExecutor-554-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5b184a5e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(5.5.0):C1)))}
   [junit4]   2> 95929 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[B]} 0 0
   [junit4]   2> 95930 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DirectUpdateHandler2 start rollback{}
   [junit4]   2> 95930 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
   [junit4]   2> 95931 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 95932 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@130f424b),segFN=segments_c,generation=12}
   [junit4]   2> 95932 INFO  (TEST-DirectUpdateHandlerTest.testAddRollback-seed#[EC8641F3D74297BD]) [    ] o.a.s.c.SolrDeletionPol

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

297BD -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nl-BE -Dtests.timezone=Pacific/Norfolk -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 0.35s J2 | DirectUpdateHandlerTest.testExpungeDeletes <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: maxDoc !> numDocs ... expected some deletions
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([EC8641F3D74297BD:C0FF0576A2FB5F18]:0)
   [junit4]    > 	at org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes(DirectUpdateHandlerTest.java:291)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 97269 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 97269 INFO  (SUITE-DirectUpdateHandlerTest-seed#[EC8641F3D74297BD]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=69465447
   [junit4]   2> 97269 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3dfabff7
   [junit4]   2> 97269 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=1,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=8,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
   [junit4]   2> 97269 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 97269 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 97269 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 97327 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d5b2603),segFN=segments_j,generation=19}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d5b2603),segFN=segments_k,generation=20}
   [junit4]   2> 97327 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 20
   [junit4]   2> 97328 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 97336 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockFSDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 109446 ERROR (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Timeout waiting for all directory ref counts to be released - gave up waiting on CachedDir<<refCount=1;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index;done=false>>
   [junit4]   2> 109447 ERROR (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Error closing directory:org.apache.solr.common.SolrException: Timeout waiting for all directory ref counts to be released - gave up waiting on CachedDir<<refCount=1;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001/index;done=false>>
   [junit4]   2> 	at org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:187)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.close(SolrCore.java:1275)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:134)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:129)
   [junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 109447 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001;done=false>>]
   [junit4]   2> 109447 INFO  (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001/init-core-data-001
   [junit4]   2> 109447 ERROR (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.SolrCore java.lang.AssertionError: 1
   [junit4]   2> 	at org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:201)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.close(SolrCore.java:1275)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:134)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:129)
   [junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 109447 ERROR (coreCloseExecutor-569-thread-1) [    x:collection1] o.a.s.c.SolrCores Error shutting down core:java.lang.AssertionError: 1
   [junit4]   2> 	at org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:201)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.close(SolrCore.java:1275)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:134)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:129)
   [junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_EC8641F3D74297BD-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54): {id=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{}, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=nl-BE, timezone=Pacific/Norfolk
   [junit4]   2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=12,threads=1,free=213331456,total=524812288
   [junit4]   2> NOTE: All tests run in this JVM: [LukeRequestHandlerTest, BadIndexSchemaTest, TestMiniSolrCloudClusterSSL, TestReload, HdfsLockFactoryTest, ReplicationFactorTest, DirectSolrConnectionTest, AtomicUpdatesTest, TestDistributedMissingSort, DocValuesMultiTest, SecurityConfHandlerTest, RecoveryZkTest, TestGroupingSearch, OverseerTaskQueueTest, HdfsNNFailoverTest, TestMaxScoreQueryParser, UpdateParamsTest, TestSearcherReuse, CoreAdminRequestStatusTest, TestTolerantSearch, OverseerCollectionConfigSetProcessorTest, DirectUpdateHandlerTest]
   [junit4] Completed [47/566 (1!)] on J2 in 16.21s, 7 tests, 1 failure <<< FAILURES!

[...truncated 1645 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:750: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:694: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/common-build.xml:534: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1477: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1033: There were test failures: 566 suites (7 ignored), 2275 tests, 1 failure, 84 ignored (39 assumptions) [seed: EC8641F3D74297BD]

Total time: 53 minutes 13 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



Mime
View raw message