You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/02/12 00:39:09 UTC
[JENKINS] Lucene-Solr-NightlyTests-5.5 - Build # 9 - Still Failing
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/9/
1 tests failed.
FAILED: org.apache.solr.update.HardAutoCommitTest.testCommitWithin
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([D0BFBC47DC169D10:6A6DD33F5F387305]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:764)
at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:99)
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)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
</response>
request was:version=2.2&start=0&q=id:529&rows=20&qt=standard
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:757)
... 40 more
Build Log:
[...truncated 11280 lines...]
[junit4] Suite: org.apache.solr.update.HardAutoCommitTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001
[junit4] 2> 573444 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 573444 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 573444 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1'
[junit4] 2> 573445 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 573445 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr
[junit4] 2> 573445 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 573445 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 573473 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 573526 WARN (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 573529 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.4
[junit4] 2> 573807 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 573811 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 574129 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 574134 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 574135 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 574139 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 574148 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr'
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.CoreContainer New CoreContainer 2115779435
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr]
[junit4] 2> 574150 WARN (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/lib
[junit4] 2> 574150 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-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,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 574154 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 574156 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 574156 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 574156 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 574157 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 574157 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {config=solrconfig.xml, schema=schema.xml, transient=false, shard=shard1, dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001, loadOnStartup=true, collection=collection1, name=collection1, configSetProperties=configsetprops.json}
[junit4] 2> 574158 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1'
[junit4] 2> 574160 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 574160 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr
[junit4] 2> 574160 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 574160 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 574172 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 574184 WARN (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 574187 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.4
[junit4] 2> 574262 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 574266 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 574742 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 574747 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 574748 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 574757 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 574758 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 574760 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 574760 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 574760 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1'
[junit4] 2> 574760 INFO (coreLoadExecutor-1112-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1
[junit4] 2> 574760 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 574761 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/]
[junit4] 2> 574761 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3941794b
[junit4] 2> 574761 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001
[junit4] 2> 574761 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/index/
[junit4] 2> 574761 WARN (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 574762 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/index
[junit4] 2> 574762 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=12.455078125, floorSegmentMB=0.44140625, forceMergeDeletesPctAllowed=26.091869582582035, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19343389843737768
[junit4] 2> 574769 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@57a3026f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef4f25c; maxCacheMB=0.8955078125 maxMergeSizeMB=0.3154296875)),segFN=segments_1,generation=1}
[junit4] 2> 574769 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 574775 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 574775 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 574775 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 574775 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 574775 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 574775 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 574775 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 574775 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 574775 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 574776 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 574776 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 574776 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 574777 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 574778 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 574778 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 574779 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 574776 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/
[junit4] 2> 574791 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 574792 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/file,/update/csv,/replication,/mlt,/schema,/admin/luke,/update/json,/debug/dump,/admin/threads,lazy,/admin/properties,tvrh,/search-facet-invariants,spellCheckWithWordbreak_Direct,mock,/admin/ping,spellCheckWithWordbreak,standard,/admin/logging,/admin/system,/config,spellCheckCompRH_Direct,dismax,mltrh,spellCheckCompRH1,/admin/segments,/get,/update/json/docs,/admin/mbeans,/update,/admin/plugins,defaults,spellCheckCompRH,/search-facet-def,/terms
[junit4] 2> 574793 WARN (coreLoadExecutor-1112-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> 574793 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 574794 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 574794 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 574795 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 574795 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 574795 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=79.263671875, floorSegmentMB=1.56640625, forceMergeDeletesPctAllowed=13.928388603852804, segmentsPerTier=38.0, maxCFSSegmentSizeMB=1.453125, noCFSRatio=0.6423910684418743
[junit4] 2> 574795 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@57a3026f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef4f25c; maxCacheMB=0.8955078125 maxMergeSizeMB=0.3154296875)),segFN=segments_1,generation=1}
[junit4] 2> 574795 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 574795 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@5d038ef7[collection1] main
[junit4] 2> 574796 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 574796 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 574796 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 574796 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 574802 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 574819 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 574819 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 574819 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 574819 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 574819 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 574819 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 574820 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 574820 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 574820 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 574820 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 574821 INFO (searcherExecutor-1113-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d038ef7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 574821 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 574821 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 574821 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 574821 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1559081335106043904
[junit4] 2> 574823 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1559081335106043904
[junit4] 2> 574823 INFO (coreLoadExecutor-1112-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 574824 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 574827 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
[junit4] 2> 574828 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1559081335112335360)} 0 1
[junit4] 2> 574830 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1'
[junit4] 2> 574830 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 574830 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr
[junit4] 2> 574830 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 574830 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 574845 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 574856 WARN (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 574859 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.4
[junit4] 2> 574889 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 574893 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 575255 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 575261 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 575263 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 575268 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 575270 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 575272 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 575272 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 575273 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1'
[junit4] 2> 575273 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1
[junit4] 2> 575273 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/]
[junit4] 2> 575274 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3941794b
[junit4] 2> 575274 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/index/
[junit4] 2> 575275 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/
[junit4] 2> 575285 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 575285 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 575285 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 575285 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 575286 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 575286 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 575286 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 575286 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 575286 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 575286 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 575286 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 575286 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 575288 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 575289 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 575290 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 575291 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 575294 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 575298 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/file,/update/csv,/replication,/mlt,/schema,/admin/luke,/update/json,/debug/dump,/admin/threads,lazy,/admin/properties,tvrh,/search-facet-invariants,spellCheckWithWordbreak_Direct,mock,/admin/ping,spellCheckWithWordbreak,standard,/admin/logging,/admin/system,/config,spellCheckCompRH_Direct,dismax,mltrh,spellCheckCompRH1,/admin/segments,/get,/update/json/docs,/admin/mbeans,/update,/admin/plugins,defaults,spellCheckCompRH,/search-facet-def,/terms
[junit4] 2> 575299 WARN (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 575300 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 575300 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 575300 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 575300 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@16ff53df[collection1] main
[junit4] 2> 575301 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 575301 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 575301 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 575301 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 575301 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 575304 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 575305 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 575305 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 575305 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 575307 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1559081335112335360
[junit4] 2> 575307 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1
[junit4] 2> 575307 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 575307 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 575307 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 575307 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 575323 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 575336 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 575336 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=89.8388671875, floorSegmentMB=0.6396484375, forceMergeDeletesPctAllowed=5.920413092925711, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 575336 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 575343 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 575343 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@57a3026f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef4f25c; maxCacheMB=0.8955078125 maxMergeSizeMB=0.3154296875)),segFN=segments_1,generation=1}
[junit4] 2> 575343 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 575343 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 575343 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 575344 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@16ff53df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 575344 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@44ee4855[collection1] main
[junit4] 2> 575344 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.CoreContainer replacing core: collection1
[junit4] 2> 575345 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@10bd5ba6
[junit4] 2> 575345 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 575345 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 575356 INFO (searcherExecutor-1118-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@44ee4855[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 575368 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1559081335657594880)]} 0 21
[junit4] 2> 575384 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={version=2.2&start=0&q=id:529&rows=20&qt=standard} hits=0 status=0 QTime=15
[junit4] 2> 575386 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1559081335697440768)]} 0 38
[junit4] 2> 575386 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={version=2.2&start=0&q=id:530&rows=20&qt=standard} hits=0 status=0 QTime=0
[junit4] 2> 575387 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1559081335699537920)]} 0 40
[junit4] 2> 575387 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={version=2.2&start=0&q=id:529&rows=20&qt=standard} hits=0 status=0 QTime=0
[junit4] 2> 575388 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:version=2.2&start=0&q=id:529&rows=20&qt=standard
[junit4] 2> 575388 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: version=2.2&start=0&q=id:529&rows=20&qt=standard:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:version=2.2&start=0&q=id:529&rows=20&qt=standard
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:757)
[junit4] 2> at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:99)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:606)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 575388 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[D0BFBC47DC169D10]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HardAutoCommitTest -Dtests.method=testCommitWithin -Dtests.seed=D0BFBC47DC169D10 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/test-data/enwiki.random.lines.txt -Dtests.locale=es-PY -Dtests.timezone=Turkey -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.57s J2 | HardAutoCommitTest.testCommitWithin <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([D0BFBC47DC169D10:6A6DD33F5F387305]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:764)
[junit4] > at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:99)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] > </response>
[junit4] > request was:version=2.2&start=0&q=id:529&rows=20&qt=standard
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:757)
[junit4] > ... 40 more
[junit4] 2> 575390 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 575390 INFO (SUITE-HardAutoCommitTest-seed#[D0BFBC47DC169D10]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2115779435
[junit4] 2> 575400 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@78d33d63
[junit4] 2> 575400 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=2,adds=2,deletesById=1,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=1,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 575400 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 575400 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 575400 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 575400 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 575432 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@57a3026f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef4f25c; maxCacheMB=0.8955078125 maxMergeSizeMB=0.3154296875)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@57a3026f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef4f25c; maxCacheMB=0.8955078125 maxMergeSizeMB=0.3154296875)),segFN=segments_2,generation=2}
[junit4] 2> 575432 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 575435 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 575437 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 575437 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001;done=false>>]
[junit4] 2> 575437 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001
[junit4] 2> 575437 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/index;done=false>>]
[junit4] 2> 575437 INFO (coreCloseExecutor-1122-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001/init-core-data-001/index
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_D0BFBC47DC169D10-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {multiDefault=BlockTreeOrds(blocksize=128), timestamp=BlockTreeOrds(blocksize=128), range_facet_i_dv=BlockTreeOrds(blocksize=128), range_facet_l=BlockTreeOrds(blocksize=128), field_t=PostingsFormat(name=Memory doPackFST= true), intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), subject=BlockTreeOrds(blocksize=128), range_facet_l_dv=PostingsFormat(name=Memory doPackFST= true), text=PostingsFormat(name=Direct), id=PostingsFormat(name=Memory doPackFST= true), _version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{timestamp=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Memory), range_facet_l_dv=DocValuesFormat(name=Lucene54)}, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=es-PY, timezone=Turkey
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=131183576,total=378535936
[junit4] 2> NOTE: All tests run in this JVM: [CollectionReloadTest, OverseerTest, UnloadDistributedZkTest, XmlUpdateRequestHandlerTest, ReplicationFactorTest, TestMaxScoreQueryParser, TestTolerantSearch, TestObjectReleaseTracker, SearchHandlerTest, TestSolrXml, BadIndexSchemaTest, TestDownShardTolerantSearch, TestPerFieldSimilarityWithDefaultOverride, UpdateParamsTest, BlockDirectoryTest, TestManagedSchemaFieldTypeResource, HdfsNNFailoverTest, TestReRankQParserPlugin, AutoCommitTest, TestPostingsSolrHighlighter, OutOfBoxZkACLAndCredentialsProvidersTest, LeaderInitiatedRecoveryOnShardRestartTest, TermVectorComponentDistributedTest, TestTrieFacet, TestFieldCollectionResource, HttpPartitionTest, HardAutoCommitTest]
[junit4] Completed [90/575 (1!)] on J2 in 2.07s, 1 test, 1 error <<< FAILURES!
[...truncated 140 lines...]
[junit4] JVM J1: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/temp/junit4-J1-20170211_232049_435.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/heapdumps/java_pid16405.hprof ...
[junit4] Heap dump file created [613238470 bytes in 5.350 secs]
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J1: stderr was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/temp/junit4-J1-20170211_232049_435.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] WARN: Unhandled exception in event serialization. -> java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] at sun.nio.cs.StreamEncoder.write(StreamEncoder.java:133)
[junit4] at java.io.OutputStreamWriter.write(OutputStreamWriter.java:220)
[junit4] at java.io.Writer.write(Writer.java:157)
[junit4] at com.carrotsearch.ant.tasks.junit4.gson.stream.JsonWriter.string(JsonWriter.java:546)
[junit4] at com.carrotsearch.ant.tasks.junit4.gson.stream.JsonWriter.value(JsonWriter.java:414)
[junit4] at com.carrotsearch.ant.tasks.junit4.events.AbstractEvent.writeBinaryProperty(AbstractEvent.java:36)
[junit4] at com.carrotsearch.ant.tasks.junit4.events.AppendStdErrEvent.serialize(AppendStdErrEvent.java:30)
[junit4] at com.carrotsearch.ant.tasks.junit4.events.Serializer$2.run(Serializer.java:101)
[junit4] at com.carrotsearch.ant.tasks.junit4.events.Serializer$2.run(Serializer.java:96)
[junit4] at java.security.AccessController.doPrivileged(Native Method)
[junit4] at com.carrotsearch.ant.tasks.junit4.events.Serializer.flushQueue(Serializer.java:96)
[junit4] at com.carrotsearch.ant.tasks.junit4.events.Serializer.serialize(Serializer.java:81)
[junit4] at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain$3$2.write(SlaveMain.java:456)
[junit4] at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
[junit4] at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
[junit4] at java.io.PrintStream.flush(PrintStream.java:338)
[junit4] at java.io.FilterOutputStream.flush(FilterOutputStream.java:140)
[junit4] at java.io.PrintStream.write(PrintStream.java:482)
[junit4] at sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221)
[junit4] at sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291)
[junit4] at sun.nio.cs.StreamEncoder.implFlush(StreamEncoder.java:295)
[junit4] at sun.nio.cs.StreamEncoder.flush(StreamEncoder.java:141)
[junit4] at java.io.OutputStreamWriter.flush(OutputStreamWriter.java:229)
[junit4] at org.apache.log4j.helpers.QuietWriter.flush(QuietWriter.java:59)
[junit4] at org.apache.log4j.WriterAppender.subAppend(WriterAppender.java:324)
[junit4] at org.apache.log4j.WriterAppender.append(WriterAppender.java:162)
[junit4] at org.apache.log4j.AppenderSkeleton.doAppend(AppenderSkeleton.ja
[junit4] va:251)
[junit4] at org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:66)
[junit4] at org.apache.log4j.Category.callAppenders(Category.java:206)
[junit4] at org.apache.log4j.Category.forcedLog(Category.java:391)
[junit4] at org.apache.log4j.Category.log(Category.java:856)
[junit4] at org.slf4j.impl.Log4jLoggerAdapter.info(Log4jLoggerAdapter.java:304)
[junit4] <<< JVM J1: EOF ----
[...truncated 1196 lines...]
[junit4] ERROR: JVM J1 ended with an exception, command line: /usr/local/asfpackages/java/jdk1.7.0_80/jre/bin/java -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/heapdumps -XX:MaxPermSize=192m -ea -esa -Dtests.prefix=tests -Dtests.seed=D0BFBC47DC169D10 -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/test-data/enwiki.random.lines.txt -Dtests.luceneMatchVersion=5.5.4 -Dtests.cleanthreads=perClass -Djava.util.logging.config.file=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/tools/junit4/logging.properties -Dtests.nightly=true -Dtests.weekly=false -Dtests.monster=false -Dtests.slow=true -Dtests.asserts=true -Dtests.multiplier=2 -DtempDir=./temp -Djava.io.tmpdir=./temp -Djunit4.tempDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/temp -Dcommon.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene -Dclover.db.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/clover/db -Djava.security.policy=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/tools/junit4/solr-tests.policy -Dtests.LUCENE_VERSION=5.5.4 -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Djdk.map.althashing.threshold=0 -Djunit4.childvm.cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1 -Djunit4.childvm.id=1 -Djunit4.childvm.count=3 -Dtests.leaveTemporary=false -Dtests.filterstacks=true -Dfile.encoding=UTF-8 -classpath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-test-framework/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/test-framework/lib/junit4-ant-2.3.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/test-framework/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/codecs/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-solrj/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/analysis/common/lucene-analyzers-common-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/codecs/lucene-codecs-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/backward-codecs/lucene-backward-codecs-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/highlighter/lucene-highlighter-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/memory/lucene-memory-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/misc/lucene-misc-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/spatial/lucene-spatial-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/expressions/lucene-expressions-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/suggest/lucene-suggest-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/grouping/lucene-grouping-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/queries/lucene-queries-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/queryparser/lucene-queryparser-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/join/lucene-join-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/sandbox/lucene-sandbox-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/antlr4-runtime-4.5.1-1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/asm-5.0.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/asm-commons-5.0.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/commons-cli-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/commons-codec-1.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/commons-configuration-1.6.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/commons-exec-1.3.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/commons-fileupload-1.3.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/commons-lang-2.6.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/concurrentlinkedhashmap-lru-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/dom4j-1.6.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/guava-14.0.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/hadoop-annotations-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/hadoop-auth-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/hadoop-common-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/hadoop-hdfs-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/hppc-0.7.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/htrace-core-3.0.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/jackson-core-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/jackson-dataformat-smile-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/joda-time-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/log4j-1.2.17.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/org.restlet-2.3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/org.restlet.ext.servlet-2.3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/protobuf-java-2.5.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/slf4j-log4j12-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/spatial4j-0.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/lib/t-digest-3.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/commons-io-2.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/httpclient-4.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/httpcore-4.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/httpmime-4.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/noggit-0.6.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/slf4j-api-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/stax2-api-3.1.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/woodstox-core-asl-4.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/solrj/lib/zookeeper-3.4.6.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/javax.servlet-api-3.1.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-continuation-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-deploy-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-http-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-io-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-jmx-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-rewrite-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-security-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-server-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-servlet-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-servlets-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-util-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-webapp-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/server/lib/jetty-xml-9.2.13.v20150730.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/core/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/test-framework/lib/junit-4.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/test-framework/lib/randomizedtesting-runner-2.3.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/antlr-2.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/antlr4-runtime-4.5.1-1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-core-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-core-api-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-core-avl-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-core-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-i18n-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptor-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-admin-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/analysis/icu/lucene-analyzers-icu-5.5.4-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/contrib/analysis-extras/lib/icu4j-54.1.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-log4j.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit4.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jai.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-javamail.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bsf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-net.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-antlr.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jsch.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-oro.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-logging.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-netrexx.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-testutil.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jdepend.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bcel.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-xalan2.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-resolver.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jmf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-regexp.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-swing.jar:/usr/local/asfpackages/java/jdk1.7.0_80/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.2.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/temp/junit4-J1-20170211_232049_435.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/temp/junit4-J1-20170211_232049_435.suites -stdin
[junit4] ERROR: JVM J1 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1501)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] at java.lang.Thread.run(Thread.java:745)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/build.xml:757: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/build.xml:694: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/common-build.xml:534: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/common-build.xml:1477: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/common-build.xml:1033: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.
Total time: 208 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-NightlyTests-5.5 - Build # 10 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/10/
5 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Timeout occured while waiting response from server at: https://127.0.0.1:58370
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:58370
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:586)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:400)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:516)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:179)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
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)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:152)
at java.net.SocketInputStream.read(SocketInputStream.java:122)
at sun.security.ssl.InputRecord.readFully(InputRecord.java:442)
at sun.security.ssl.InputRecord.read(InputRecord.java:480)
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:934)
at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:891)
at sun.security.ssl.AppInputStream.read(AppInputStream.java:102)
at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:482)
... 47 more
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDistributedZkTest
Error Message:
3 threads leaked from SUITE scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest: 1) Thread[id=28823, name=searcherExecutor-5870-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) 2) Thread[id=28781, name=searcherExecutor-5861-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) 3) Thread[id=28771, name=searcherExecutor-5860-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 3 threads leaked from SUITE scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest:
1) Thread[id=28823, name=searcherExecutor-5870-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
2) Thread[id=28781, name=searcherExecutor-5861-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
3) Thread[id=28771, name=searcherExecutor-5860-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([374B7A504FDF3891]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDistributedZkTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=28781, name=searcherExecutor-5861-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) 2) Thread[id=28823, name=searcherExecutor-5870-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) 3) Thread[id=28771, name=searcherExecutor-5860-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=28781, name=searcherExecutor-5861-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
2) Thread[id=28823, name=searcherExecutor-5870-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
3) Thread[id=28771, name=searcherExecutor-5860-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([374B7A504FDF3891]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsChaosMonkeyNothingIsSafeTest
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
at __randomizedtesting.SeedInfo.seed([374B7A504FDF3891]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:238)
at sun.reflect.GeneratedMethodAccessor25.invoke(Unknown Source)
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$7.evaluate(RandomizedRunner.java:834)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.handler.TestReplicationHandlerBackup.doTestBackup
Error Message:
Failed to create backup
Stack Trace:
java.lang.AssertionError: Failed to create backup
at __randomizedtesting.SeedInfo.seed([374B7A504FDF3891:76C05A356861CBDE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.CheckBackupStatus.fetchStatus(CheckBackupStatus.java:50)
at org.apache.solr.handler.TestReplicationHandlerBackup.doTestBackup(TestReplicationHandlerBackup.java:196)
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 11240 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandlerBackup
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/init-core-data-001
[junit4] 2> 475909 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackupOnCommit
[junit4] 2> 475909 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/collection1
[junit4] 2> 475915 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 475922 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2095a36b{/solr,null,AVAILABLE}
[junit4] 2> 475923 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.e.j.s.ServerConnector Started ServerConnector@63a05356{HTTP/1.1}{127.0.0.1:60432}
[junit4] 2> 475923 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.e.j.s.Server Started @478168ms
[junit4] 2> 475923 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=60432}
[junit4] 2> 475923 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@617df472
[junit4] 2> 475923 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001'
[junit4] 2> 475923 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 475923 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 475923 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/solr.xml
[junit4] 2> 475931 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/.
[junit4] 2> 475931 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer New CoreContainer 848840020
[junit4] 2> 475931 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001]
[junit4] 2> 475932 WARN (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/lib
[junit4] 2> 475932 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 475932 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 475933 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 475933 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 475933 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 475933 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 475933 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/.
[junit4] 2> 475933 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {dataDir=data/, configSetProperties=configsetprops.json, name=collection1, loadOnStartup=true, transient=false, config=solrconfig.xml, schema=schema.xml}
[junit4] 2> 475933 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1
[junit4] 2> 475933 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 475934 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/collection1'
[junit4] 2> 475934 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0
[junit4] 2> 475934 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 475934 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 475934 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 475938 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 475949 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.4
[junit4] 2> 475968 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 475970 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 475976 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 475976 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/collection1'
[junit4] 2> 475976 INFO (coreLoadExecutor-1767-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1
[junit4] 2> 475977 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 475977 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/]
[junit4] 2> 475977 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 475977 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data
[junit4] 2> 475977 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 475977 WARN (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 475977 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/index
[junit4] 2> 475977 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] 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.3695212854481752]
[junit4] 2> 475978 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d6abdfe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5adf7593),segFN=segments_1,generation=1}
[junit4] 2> 475978 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 475978 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/
[junit4] 2> 475979 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 475980 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 475980 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 475981 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 475981 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 475983 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 475983 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/system,lazy,/replication,/admin/luke,/admin/file,/update/json/docs,/admin/plugins,/schema,/admin/mbeans,/config,/update/csv,/admin/ping,/admin/properties,standard,/admin/segments,defaults,/update/json,/admin/threads,/get,/admin/logging,/update
[junit4] 2> 475984 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 475984 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 475984 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 475984 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=3.951171875, floorSegmentMB=0.5107421875, forceMergeDeletesPctAllowed=13.522468014356475, segmentsPerTier=20.0, maxCFSSegmentSizeMB=1.8994140625, noCFSRatio=1.0
[junit4] 2> 475984 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d6abdfe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5adf7593),segFN=segments_1,generation=1}
[junit4] 2> 475984 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 475985 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4a8948c6[collection1] main
[junit4] 2> 475985 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/collection1/conf
[junit4] 2> 475985 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/collection1/conf}
[junit4] 2> 475985 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/collection1/conf
[junit4] 2> 475985 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 475985 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema-replication2.xml:schema.xml
[junit4] 2> 475985 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 475985 INFO (searcherExecutor-1768-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a8948c6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 475985 INFO (coreLoadExecutor-1767-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 476437 INFO (qtp472662535-10294) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 476437 INFO (qtp472662535-10294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{deleteByQuery=*:*} 0 0
[junit4] 2> 476440 INFO (qtp472662535-10296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[0]} 0 0
[junit4] 2> 476442 INFO (qtp472662535-10297) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[1]} 0 0
[junit4] 2> 476444 INFO (qtp472662535-10298) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[2]} 0 0
[junit4] 2> 476446 INFO (qtp472662535-10299) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[3]} 0 0
[junit4] 2> 476449 INFO (qtp472662535-10294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[4]} 0 0
[junit4] 2> 476451 INFO (qtp472662535-10296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[5]} 0 0
[junit4] 2> 476453 INFO (qtp472662535-10297) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 476455 INFO (qtp472662535-10297) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d6abdfe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5adf7593),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d6abdfe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5adf7593),segFN=segments_2,generation=2}
[junit4] 2> 476455 INFO (qtp472662535-10297) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 476456 INFO (Thread-7177) [ x:collection1] o.a.s.h.SnapShooter Creating backup snapshot <not named> at /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/
[junit4] 2> 476456 INFO (qtp472662535-10297) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2aea2c0d[collection1] main
[junit4] 2> 476457 INFO (qtp472662535-10297) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 476457 INFO (searcherExecutor-1768-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2aea2c0d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.4):C6)))}
[junit4] 2> 476457 INFO (qtp472662535-10297) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&softCommit=false&waitSearcher=true&version=2&wt=javabin}{commit=} 0 3
[junit4] 2> 476457 INFO (Thread-7177) [ x:collection1] o.a.s.h.SnapShooter Done creating backup snapshot: <not named> at /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/
[junit4] 2> 476459 INFO (qtp472662535-10298) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=0
[junit4] 2> 477463 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBackupOnCommit
[junit4] 2> 477470 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@63a05356{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 477470 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=848840020
[junit4] 2> 477471 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50c4757
[junit4] 2> 477471 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=6,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 477472 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 477472 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 477472 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 477472 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 477472 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 477472 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data;done=false>>]
[junit4] 2> 477472 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data
[junit4] 2> 477472 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/index;done=false>>]
[junit4] 2> 477472 INFO (coreCloseExecutor-1772-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-001/./collection1/data/index
[junit4] 2> 477473 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[374B7A504FDF3891]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2095a36b{/solr,null,UNAVAILABLE}
[junit4] 2> 477476 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestBackup
[junit4] 2> 477476 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/collection1
[junit4] 2> 477478 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 477479 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f61cad5{/solr,null,AVAILABLE}
[junit4] 2> 477479 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1532f3d2{HTTP/1.1}{127.0.0.1:33683}
[junit4] 2> 477479 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.e.j.s.Server Started @479724ms
[junit4] 2> 477479 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=33683}
[junit4] 2> 477480 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@617df472
[junit4] 2> 477480 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002'
[junit4] 2> 477480 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 477480 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 477480 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/solr.xml
[junit4] 2> 477489 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/.
[junit4] 2> 477489 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer New CoreContainer 1172944601
[junit4] 2> 477489 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002]
[junit4] 2> 477489 WARN (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/lib
[junit4] 2> 477489 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 477490 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 477490 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 477490 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 477490 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 477490 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 477491 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/.
[junit4] 2> 477491 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {config=solrconfig.xml, configSetProperties=configsetprops.json, name=collection1, dataDir=data/, schema=schema.xml, loadOnStartup=true, transient=false}
[junit4] 2> 477491 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1
[junit4] 2> 477491 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 477491 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/collection1'
[junit4] 2> 477491 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0
[junit4] 2> 477491 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 477491 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 477491 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 477495 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 477505 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.4
[junit4] 2> 477524 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 477526 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 477532 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 477532 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/collection1'
[junit4] 2> 477532 INFO (coreLoadExecutor-1779-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1
[junit4] 2> 477533 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 477533 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/]
[junit4] 2> 477533 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 477533 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data
[junit4] 2> 477533 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 477533 WARN (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 477533 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/index
[junit4] 2> 477533 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] 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.3695212854481752]
[junit4] 2> 477534 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@63ff45c5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c3dbbcd),segFN=segments_1,generation=1}
[junit4] 2> 477534 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 477534 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/
[junit4] 2> 477535 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 477536 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 477536 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 477537 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 477537 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 477538 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/system,/replication,/admin/luke,/admin/file,/update/json/docs,/admin/plugins,/schema,/admin/mbeans,/config,/update/csv,/admin/ping,/admin/properties,standard,/admin/segments,/update/json,/admin/threads,/get,/admin/logging,/update
[junit4] 2> 477539 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 477539 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 477539 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 477539 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=3.951171875, floorSegmentMB=0.5107421875, forceMergeDeletesPctAllowed=13.522468014356475, segmentsPerTier=20.0, maxCFSSegmentSizeMB=1.8994140625, noCFSRatio=1.0
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@63ff45c5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c3dbbcd),segFN=segments_1,generation=1}
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@33568b80[collection1] main
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/collection1/conf
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/collection1/conf}
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/collection1/conf
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema-replication2.xml:schema.xml
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 477540 INFO (searcherExecutor-1780-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@33568b80[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 477540 INFO (coreLoadExecutor-1779-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 477994 INFO (qtp2056086332-10319) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 477994 INFO (qtp2056086332-10319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{deleteByQuery=*:*} 0 0
[junit4] 2> 477997 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[0]} 0 0
[junit4] 2> 477999 INFO (qtp2056086332-10321) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[1]} 0 0
[junit4] 2> 478001 INFO (qtp2056086332-10322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[2]} 0 0
[junit4] 2> 478003 INFO (qtp2056086332-10323) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[3]} 0 0
[junit4] 2> 478005 INFO (qtp2056086332-10319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[4]} 0 0
[junit4] 2> 478007 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[5]} 0 0
[junit4] 2> 478009 INFO (qtp2056086332-10321) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[6]} 0 0
[junit4] 2> 478011 INFO (qtp2056086332-10322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[7]} 0 0
[junit4] 2> 478013 INFO (qtp2056086332-10323) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[8]} 0 0
[junit4] 2> 478015 INFO (qtp2056086332-10319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[9]} 0 0
[junit4] 2> 478017 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[10]} 0 0
[junit4] 2> 478019 INFO (qtp2056086332-10321) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[11]} 0 0
[junit4] 2> 478021 INFO (qtp2056086332-10322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[12]} 0 0
[junit4] 2> 478023 INFO (qtp2056086332-10323) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[13]} 0 0
[junit4] 2> 478025 INFO (qtp2056086332-10319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[14]} 0 0
[junit4] 2> 478027 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[15]} 0 0
[junit4] 2> 478029 INFO (qtp2056086332-10321) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[16]} 0 0
[junit4] 2> 478030 INFO (qtp2056086332-10322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[17]} 0 0
[junit4] 2> 478032 INFO (qtp2056086332-10323) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[18]} 0 0
[junit4] 2> 478034 INFO (qtp2056086332-10319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[19]} 0 0
[junit4] 2> 478036 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[20]} 0 0
[junit4] 2> 478038 INFO (qtp2056086332-10321) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[21]} 0 0
[junit4] 2> 478040 INFO (qtp2056086332-10322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[22]} 0 0
[junit4] 2> 478042 INFO (qtp2056086332-10323) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[23]} 0 0
[junit4] 2> 478044 INFO (qtp2056086332-10319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[24]} 0 0
[junit4] 2> 478046 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[25]} 0 0
[junit4] 2> 478048 INFO (qtp2056086332-10321) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[26]} 0 0
[junit4] 2> 478050 INFO (qtp2056086332-10322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[27]} 0 0
[junit4] 2> 478052 INFO (qtp2056086332-10323) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[28]} 0 0
[junit4] 2> 478054 INFO (qtp2056086332-10319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[29]} 0 0
[junit4] 2> 478056 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[30]} 0 0
[junit4] 2> 478058 INFO (qtp2056086332-10321) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[31]} 0 0
[junit4] 2> 478060 INFO (qtp2056086332-10322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[32]} 0 0
[junit4] 2> 478062 INFO (qtp2056086332-10323) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[33]} 0 0
[junit4] 2> 478064 INFO (qtp2056086332-10319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin}{add=[34]} 0 0
[junit4] 2> 478066 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 478068 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@63ff45c5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c3dbbcd),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@63ff45c5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c3dbbcd),segFN=segments_2,generation=2}
[junit4] 2> 478068 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 478071 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7a08d4a8[collection1] main
[junit4] 2> 478071 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 478071 INFO (searcherExecutor-1780-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7a08d4a8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.4):C35)))}
[junit4] 2> 478071 INFO (qtp2056086332-10320) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&softCommit=false&waitSearcher=true&version=2&wt=javabin}{commit=} 0 5
[junit4] 2> 478073 INFO (qtp2056086332-10321) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=backup} status=0 QTime=0
[junit4] 2> 478074 INFO (Thread-7193) [ x:collection1] o.a.s.h.SnapShooter Creating backup snapshot <not named> at /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/
[junit4] 2> 478078 INFO (Thread-7192) [ x:collection1] o.a.s.h.SnapShooter Creating backup snapshot <not named> at /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/
[junit4] 2> 478081 INFO (qtp2056086332-10322) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=2
[junit4] 2> 478081 INFO (Thread-7193) [ x:collection1] o.a.s.h.SnapShooter Done creating backup snapshot: <not named> at /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/
[junit4] 2> 478086 ERROR (Thread-7192) [ x:collection1] o.a.s.h.SnapShooter Exception while creating snapshot
[junit4] 2> java.nio.file.NoSuchFileException: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/collection1/data/snapshot.20170213061356301/_0_Direct_0.doc
[junit4] 2> at sun.nio.fs.UnixException.translateToIOException(UnixException.java:86)
[junit4] 2> at sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:102)
[junit4] 2> at sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:107)
[junit4] 2> at sun.nio.fs.UnixFileSystemProvider.newByteChannel(UnixFileSystemProvider.java:214)
[junit4] 2> at java.nio.file.spi.FileSystemProvider.newOutputStream(FileSystemProvider.java:430)
[junit4] 2> at java.nio.file.Files.newOutputStream(Files.java:172)
[junit4] 2> at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:271)
[junit4] 2> at org.apache.lucene.store.FSDirectory.createOutput(FSDirectory.java:224)
[junit4] 2> at org.apache.lucene.store.Directory.copyFrom(Directory.java:151)
[junit4] 2> at org.apache.solr.handler.SnapShooter.copyFiles(SnapShooter.java:203)
[junit4] 2> at org.apache.solr.handler.SnapShooter.createSnapshot(SnapShooter.java:136)
[junit4] 2> at org.apache.solr.handler.SnapShooter$1.run(SnapShooter.java:85)
[junit4] 2> 479085 INFO (qtp2056086332-10323) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=0
[junit4] 2> 479086 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestBackup
[junit4] 2> 479087 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1532f3d2{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 479087 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1172944601
[junit4] 2> 479087 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@13d51145
[junit4] 2> 479088 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=35,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 479088 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 479088 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 479088 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 479089 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 479089 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 479089 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/index;done=false>>]
[junit4] 2> 479089 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data/index
[junit4] 2> 479089 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data;done=false>>]
[junit4] 2> 479090 INFO (coreCloseExecutor-1784-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001/solr-instance-002/./collection1/data
[junit4] 2> 479100 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[374B7A504FDF3891]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1f61cad5{/solr,null,UNAVAILABLE}
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandlerBackup -Dtests.method=doTestBackup -Dtests.seed=374B7A504FDF3891 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/test-data/enwiki.random.lines.txt -Dtests.locale=sl -Dtests.timezone=CTT -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 1.63s J0 | TestReplicationHandlerBackup.doTestBackup <<<
[junit4] > Throwable #1: java.lang.AssertionError: Failed to create backup
[junit4] > at __randomizedtesting.SeedInfo.seed([374B7A504FDF3891:76C05A356861CBDE]:0)
[junit4] > at org.apache.solr.handler.CheckBackupStatus.fetchStatus(CheckBackupStatus.java:50)
[junit4] > at org.apache.solr.handler.TestReplicationHandlerBackup.doTestBackup(TestReplicationHandlerBackup.java:196)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 479103 INFO (SUITE-TestReplicationHandlerBackup-seed#[374B7A504FDF3891]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandlerBackup_374B7A504FDF3891-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {name=PostingsFormat(name=Direct), id=PostingsFormat(name=Direct)}, docValues:{}, sim=DefaultSimilarity, locale=sl, timezone=CTT
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=258482736,total=414187520
[junit4] 2> NOTE: All tests run in this JVM: [TestCryptoKeys, TestFoldingMultitermQuery, ConnectionManagerTest, SolrCoreTest, TestSchemaManager, TestMergePolicyConfig, SolrXmlInZkTest, EnumFieldTest, TestSchemaSimilarityResource, SignatureUpdateProcessorFactoryTest, ResourceLoaderTest, TestConfigSetsAPI, TestSimpleQParserPlugin, TestMiniSolrCloudCluster, TestCustomDocTransformer, MigrateRouteKeyTest, PingRequestHandlerTest, HdfsBasicDistributedZk2Test, TestMiniSolrCloudClusterSSL, TestRecoveryHdfs, ConnectionReuseTest, BlockJoinFacetDistribTest, CloneFieldUpdateProcessorFactoryTest, TestMissingGroups, TestExclusionRuleCollectionAccess, TestPivotHelperCode, PeerSyncTest, RequiredFieldsTest, PathHierarchyTokenizerFactoryTest, SuggestComponentTest, TestPseudoReturnFields, TestCrossCoreJoin, TestReplicationHandlerBackup]
[junit4] Completed [78/575 (1!)] on J0 in 3.21s, 2 tests, 1 failure <<< FAILURES!
[...truncated 272 lines...]
[junit4] Suite: org.apache.solr.cloud.hdfs.HdfsChaosMonkeyNothingIsSafeTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsChaosMonkeyNothingIsSafeTest_374B7A504FDF3891-001/init-core-data-001
[junit4] 2> 813951 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 813976 WARN (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 813979 WARN (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 813981 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 813985 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_40482_hdfs____.i1gu90/webapp
[junit4] 2> 814075 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 814384 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:40482
[junit4] 2> 814451 WARN (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 814452 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 814455 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_39270_datanode____.mv2s6s/webapp
[junit4] 2> 814541 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 814720 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:39270
[junit4] 2> 814747 WARN (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 814748 INFO (SUITE-HdfsChaosMonkeyNothingIsSafeTest-seed#[374B7A504FDF3891]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 814752 INFO (SUITE-HdfsChaosMonkeyNothi
[...truncated too long message...]
egration, DistributedDebugComponentTest, TestInitParams, LukeRequestHandlerTest, TestSuggestSpellingConverter, OutOfBoxZkACLAndCredentialsProvidersTest, UpdateParamsTest, DocValuesMultiTest, SolrCoreCheckLockOnStartupTest, CoreAdminHandlerTest, TestXIncludeConfig, HardAutoCommitTest, CollectionReloadTest, TestWriterPerf, TestCollationField, HttpPartitionTest, CircularListTest, DeleteReplicaTest, UnloadDistributedZkTest, TestFieldSortValues, SuggesterTest, CollectionsAPIAsyncDistributedZkTest, BadCopyFieldTest, TestSearcherReuse, DistributedFacetPivotWhiteBoxTest, TestSolrIndexConfig, BlockDirectoryTest, TestSearchPerf, TestOverriddenPrefixQueryForCustomFieldType, TestUniqueKeyFieldResource, TestZkChroot, TestDefaultSearchFieldResource, TestBulkSchemaAPI, HdfsUnloadDistributedZkTest, TestJsonRequest, AutoCommitTest, TestUseDocValuesAsStored2, HighlighterConfigTest, ClusterStateTest, TestSolrDeletionPolicy1, TestMaxScoreQueryParser, VMParamsZkACLAndCredentialsProvidersTest, DistributedFacetPivotLongTailTest, TestJmxMonitoredMap, SuggesterTSTTest, TestSha256AuthenticationProvider, DistributedQueueTest, TestRequestForwarding, HdfsChaosMonkeyNothingIsSafeTest, DocValuesMissingTest, TestStressRecovery, BasicDistributedZk2Test, SearchHandlerTest, TestBulkSchemaConcurrent, TestClassicSimilarityFactory, TestConfigSetsAPIZkFailure, TestStressReorder, TestAuthorizationFramework, CoreSorterTest, BinaryUpdateRequestHandlerTest, ConfigSetsAPITest, DistribCursorPagingTest, TestRecovery, TestCollectionAPI, TestPerFieldSimilarityWithDefaultOverride, OverseerRolesTest, TestMinMaxOnMultiValuedField, TestHdfsUpdateLog, CursorMarkTest, TestCloudSchemaless, SuggestComponentContextFilterQueryTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.seed=374B7A504FDF3891 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/test-data/enwiki.random.lines.txt -Dtests.locale=pl-PL -Dtests.timezone=Africa/Maputo -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J2 | CollectionsAPIDistributedZkTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 3 threads leaked from SUITE scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest:
[junit4] > 1) Thread[id=28823, name=searcherExecutor-5870-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > 2) Thread[id=28781, name=searcherExecutor-5861-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > 3) Thread[id=28771, name=searcherExecutor-5860-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([374B7A504FDF3891]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=28781, name=searcherExecutor-5861-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > 2) Thread[id=28823, name=searcherExecutor-5870-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > 3) Thread[id=28771, name=searcherExecutor-5860-thread-1, state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([374B7A504FDF3891]:0)
[junit4] Completed [288/575 (3!)] on J2 in 1006.70s, 1 test, 3 errors <<< FAILURES!
[...truncated 895 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/build.xml:757: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/build.xml:694: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/common-build.xml:534: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/common-build.xml:1477: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/common-build.xml:1033: There were test failures: 575 suites (7 ignored), 2323 tests, 3 suite-level errors, 1 error, 1 failure, 1066 ignored (16 assumptions) [seed: 374B7A504FDF3891]
Total time: 182 minutes 24 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any