Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 919B7200C68 for ; Wed, 19 Apr 2017 02:43:26 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 8FF6C160BAC; Wed, 19 Apr 2017 00:43:26 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 6828B160BA1 for ; Wed, 19 Apr 2017 02:43:24 +0200 (CEST) Received: (qmail 45762 invoked by uid 500); 19 Apr 2017 00:43:21 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 45752 invoked by uid 99); 19 Apr 2017 00:43:21 -0000 Received: from crius.apache.org (HELO crius.apache.org) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 19 Apr 2017 00:43:21 +0000 Received: from crius.apache.org (localhost [127.0.0.1]) by crius.apache.org (ASF Mail Server at crius.apache.org) with ESMTP id 700FC9C00A6 for ; Wed, 19 Apr 2017 00:43:21 +0000 (UTC) Date: Wed, 19 Apr 2017 00:39:16 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <776385894.1239.1492562601461.JavaMail.jenkins@crius> Subject: [JENKINS] Lucene-Solr-Tests-master - Build # 1775 - Unstable MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_1238_2056756582.1492562601150" X-Jenkins-Job: Lucene-Solr-Tests-master X-Jenkins-Result: UNSTABLE archived-at: Wed, 19 Apr 2017 00:43:26 -0000 ------=_Part_1238_2056756582.1492562601150 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1775/ 1 tests failed. FAILED: org.apache.solr.update.AutoCommitTest.testCommitWithin Error Message: Exception during query Stack Trace: java.lang.RuntimeException: Exception during query =09at __randomizedtesting.SeedInfo.seed([99AD8954415D9E27:237FE62CC2737032]= :0) =09at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:896) =09at org.apache.solr.update.AutoCommitTest.testCommitWithin(AutoCommitTest= .java:353) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1713) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:907) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:957) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:916) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:802) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:852) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:863) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:745) Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=3D//result[@nu= mFound=3D1] =09xml response was: 00 =09request was:q=3Did:529&qt=3Dstandard&start=3D0&rows=3D20&version=3D2.2 =09at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:889) =09... 40 more Build Log: [...truncated 11606 lines...] [junit4] Suite: org.apache.solr.update.AutoCommitTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Luce= ne-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoComm= itTest_99AD8954415D9E27-001/init-core-data-001 [junit4] 2> 895029 WARN (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3D14 = numCloses=3D14 [junit4] 2> 895030 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields [junit4] 2> 895031 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth = (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D, ssl=3DNaN, value= =3DNaN, clientAuth=3DNaN) [junit4] 2> 895031 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 895032 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classload= er, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-mast= er/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collectio= n1/lib/classes] [junit4] 2> 895069 WARN (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.c.Config Beginning with Solr 5.5, is de= precated, use instead. [junit4] 2> 895070 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 895095 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.s.IndexSchema [null] Schema name=3Dtest [junit4] 2> 895213 WARN (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.s.IndexSchema [null] default search field in schema i= s text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 895216 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid fi= eld id [junit4] 2> 895269 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (r= egistry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanSer= ver@5631b867 [junit4] 2> 895274 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (re= gistry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServe= r@5631b867 [junit4] 2> 895274 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (= registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanS= erver@5631b867 [junit4] 2> 895276 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from= paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/= core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspa= ce/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/c= lasses] [junit4] 2> 895313 WARN (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.c.Config Beginning with Solr 5.5, is deprecated= , use instead. [junit4] 2> 895315 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 895339 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 895457 WARN (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema [collection1] default search field in schema is= text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 895459 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 895466 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configu= ration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tes= ts-master/solr/core/src/test-files/solr/collection1, trusted=3Dtrue [junit4] 2> 895466 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1= ' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanse= rver.JmxMBeanServer@5631b867 [junit4] 2> 895467 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 895467 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenk= ins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-fil= es/solr/collection1], dataDir=3D[/x1/jenkins/jenkins-slave/workspace/Lucene= -Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoCommit= Test_99AD8954415D9E27-001/init-core-data-001/] [junit4] 2> 895469 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D37,= maxMergeAtOnceExplicit=3D47, maxMergedSegmentMB=3D10.9560546875, floorSegm= entMB=3D1.2958984375, forceMergeDeletesPctAllowed=3D12.181894932934876, seg= mentsPerTier=3D48.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D0.47983326777750035 [junit4] 2> 895595 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.so= lr.update.UpdateLog [junit4] 2> 895596 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLev= el=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets= =3D65536 [junit4] 2> 895597 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 895597 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 895598 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D14,= maxMergeAtOnceExplicit=3D42, maxMergedSegmentMB=3D90.294921875, floorSegme= ntMB=3D1.6396484375, forceMergeDeletesPctAllowed=3D13.223040614813025, segm= entsPerTier=3D26.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D0.8350331255169214 [junit4] 2> 895598 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7494a437[collection1] m= ain] [junit4] 2> 895599 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 895606 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.s.DirectSolrSpellChecker init: {name=3Ddirect,classname=3DDir= ectSolrSpellChecker,field=3Dlowerfilt,minQueryLength=3D3} [junit4] 2> 895641 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 895642 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: default [junit4] 2> 895642 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: direct [junit4] 2> 895642 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: wordbreak [junit4] 2> 895642 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: multipleFields [junit4] 2> 895643 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: jarowinkler [junit4] 2> 895643 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: external [junit4] 2> 895643 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: freq [junit4] 2> 895643 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: fqcn [junit4] 2> 895643 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: perDict [junit4] 2> 895645 INFO (searcherExecutor-2462-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@7494a437[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 895645 INFO (coreLoadExecutor-2461-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Could not find max version in index or recent upd= ates, using new clock 1565061258762256384 [junit4] 2> 895649 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 895651 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs [junit4] 2> 895652 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL= DOCUMENTS FROM INDEX [junit4] 2> 895653 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@17c6386d[co= llection1] realtime] [junit4] 2> 895653 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] weba= pp=3Dnull path=3Dnull params=3D{_version_=3D-9223372036854775807&update.dis= trib=3DFROMLEADER&versions=3Dtrue&wt=3Djson&indent=3Dtrue}{deleteByQuery=3D= *:* (-9223372036854775807)} 0 1 [junit4] 2> 895654 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descri= ptor from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr= /core/src/test-files/solr/collection1/conf/solrcore.properties:java.nio.fil= e.NoSuchFileException: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Test= s-master/solr/core/src/test-files/solr/collection1/conf/solrcore.properties [junit4] 2> 895654 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to class= loader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-= master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-s= lave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/colle= ction1/lib/classes] [junit4] 2> 895703 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.c.Config Beginning with Solr 5.5, i= s deprecated, use instead. [junit4] 2> 895705 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 895880 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 896031 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.s.IndexSchema [collection1] default search field = in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 896033 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniquei= d field id [junit4] 2> 896039 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' = using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lu= cene-Solr-Tests-master/solr/core/src/test-files/solr/collection1 [junit4] 2> 896071 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring f= or 'solr.core.collection1' (registry 'solr.core.collection1') enabled at se= rver: com.sun.jmx.mbeanserver.JmxMBeanServer@5631b867 [junit4] 2> 896072 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening = new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-mast= er/solr/core/src/test-files/solr/collection1], dataDir=3D[/x1/jenkins/jenki= ns-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/te= mp/solr.update.AutoCommitTest_99AD8954415D9E27-001/init-core-data-001/] [junit4] 2> 896171 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: dis= abled [junit4] 2> 896171 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: dis= abled [junit4] 2> 896172 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searche= r@3258fbee[collection1] main] [junit4] 2> 896173 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing= spell checkers [junit4] 2> 896180 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4] 2> 896187 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be= reserved for 10000 [junit4] 2> 896188 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.UpdateLog Could not find max versi= on in index or recent updates, using new clock 1565061259331633152 [junit4] 2> 896198 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: default [junit4] 2> 896198 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: direct [junit4] 2> 896199 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: wordbreak [junit4] 2> 896199 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: multipleFields [junit4] 2> 896199 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: jarowinkler [junit4] 2> 896199 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: external [junit4] 2> 896200 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: freq [junit4] 2> 896200 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: fqcn [junit4] 2> 896200 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: perDict [junit4] 2> 896203 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@3258fbee[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 896220 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolic= y wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSi= zeMergePolicy: minMergeSize=3D1677721, mergeFactor=3D36, maxMergeSize=3D214= 7483648, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeByD= eletes=3Dfalse, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.79609302= 2207999E12, noCFSRatio=3D0.6384018218426953] [junit4] 2> 896221 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWrit= er is ready to be used. [junit4] 2> 896221 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searche= r@40f5faf3[collection1] main] [junit4] 2> 896221 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING So= lrCore org.apache.solr.core.SolrCore@52a203d5 [junit4] 2> 896224 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@40f5faf3[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 896224 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric r= eporters for registry=3Dsolr.core.collection1, tag=3D1386349525 [junit4] 2> 896438 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[0 (1565061259590631424)]} 0 3 [junit4] 2> 896439 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[1 (1565061259593777152)]} 0 4 [junit4] 2> 896439 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[2 (1565061259594825728)]} 0 4 [junit4] 2> 896442 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[3 (1565061259594825729)]} 0 7 [junit4] 2> 896442 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[4 (1565061259597971456)]} 0 7 [junit4] 2> 896443 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[5 (1565061259597971457)]} 0 8 [junit4] 2> 896443 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[6 (1565061259599020032)]} 0 8 [junit4] 2> 896444 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[7 (1565061259599020033)]} 0 9 [junit4] 2> 896444 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[8 (1565061259600068608)]} 0 9 [junit4] 2> 896444 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[9 (1565061259600068609)]} 0 9 [junit4] 2> 896445 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[10 (1565061259600068610)]} 0 10 [junit4] 2> 896448 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[11 (1565061259601117184)]} 0 13 [junit4] 2> 896448 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[12 (1565061259604262912)]} 0 13 [junit4] 2> 896448 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[13 (1565061259604262913)]} 0 13 [junit4] 2> 896449 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:1&qt=3Dstandard&start=3D0&rows=3D20&versi= on=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 896453 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[14 (1565061259608457216)]} 0 18 [junit4] 2> 896456 INFO (commitScheduler-2470-thread-1) [ x:collec= tion1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=3Dfalse,openSear= cher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dtrue,pr= epareCommit=3Dfalse} [junit4] 2> 896463 INFO (commitScheduler-2470-thread-1) [ x:collec= tion1] o.a.s.s.SolrIndexSearcher Opening [Searcher@765a4eb8[collection1] ma= in] [junit4] 2> 896465 INFO (searcherExecutor-2467-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@765a4eb8[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader(Uninverting(_0(7.0.0):c15)))} [junit4] 2> 896465 INFO (commitScheduler-2470-thread-1) [ x:collec= tion1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 896705 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[15 (1565061259872698368)]} 0 270 [junit4] 2> 896705 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:14&qt=3Dstandard&start=3D0&rows=3D20&vers= ion=3D2.2} hits=3D1 status=3D0 QTime=3D0 [junit4] 2> 896706 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:15&qt=3Dstandard&start=3D0&rows=3D20&vers= ion=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 896707 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs [junit4] 2> 896710 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting test= CommitWithin [junit4] 2> 896715 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collect= ion1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 896716 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Se= archer@25054c95[collection1] realtime] [junit4] 2> 896716 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [= collection1] webapp=3Dnull path=3Dnull params=3D{_version_=3D-922337203685= 4775807&update.distrib=3DFROMLEADER&versions=3Dtrue&wt=3Djson&indent=3Dtrue= }{deleteByQuery=3D*:* (-9223372036854775807)} 0 6 [junit4] 2> 896716 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn'= t load core descriptor from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr= -Tests-master/solr/core/src/test-files/solr/collection1/conf/solrcore.prope= rties:java.nio.file.NoSuchFileException: /x1/jenkins/jenkins-slave/workspac= e/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/s= olrcore.properties [junit4] 2> 896717 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Add= ed 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/= Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1= /jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/tes= t-files/solr/collection1/lib/classes] [junit4] 2> 896748 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.Config Beginning with Solr 5.= 5, is deprecated, use instead. [junit4] 2> 896750 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene Match= Version: 7.0.0 [junit4] 2> 896774 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Sch= ema name=3Dtest [junit4] 2> 896893 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.s.IndexSchema [collection1] def= ault search field in schema is text. WARNING: Deprecated, please use 'df' o= n request instead. [junit4] 2> 896895 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema tes= t/1.0 with uniqueid field id [junit4] 2> 896902 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrC= ore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-= slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/coll= ection1 [junit4] 2> 896920 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitor= ing for 'solr.core.collection1' (registry 'solr.core.collection1') enabled = at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5631b867 [junit4] 2> 896920 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Ope= ning new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests= -master/solr/core/src/test-files/solr/collection1], dataDir=3D[/x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/= J2/temp/solr.update.AutoCommitTest_99AD8954415D9E27-001/init-core-data-001/= ] [junit4] 2> 897007 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit= : disabled [junit4] 2> 897007 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit= : disabled [junit4] 2> 897009 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Se= archer@5fb22217[collection1] main] [junit4] 2> 897009 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initial= izing spell checkers [junit4] 2> 897020 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: = {name=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQuer= yLength=3D3} [junit4] 2> 897023 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.h.ReplicationHandler Commits wi= ll be reserved for 10000 [junit4] 2> 897026 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: default [junit4] 2> 897026 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: direct [junit4] 2> 897026 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: wordbreak [junit4] 2> 897026 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: multipleFields [junit4] 2> 897027 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: jarowinkler [junit4] 2> 897027 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: external [junit4] 2> 897027 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: freq [junit4] 2> 897028 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: fqcn [junit4] 2> 897028 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMerge= Policy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogB= yteSizeMergePolicy: minMergeSize=3D1677721, mergeFactor=3D36, maxMergeSize= =3D2147483648, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateS= izeByDeletes=3Dfalse, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.79= 6093022207999E12, noCFSRatio=3D0.5578842725192601] [junit4] 2> 897028 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: perDict [junit4] 2> 897031 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@5fb22217[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 897031 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New Inde= xWriter is ready to be used. [junit4] 2> 897031 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Se= archer@1fcd3d4f[collection1] main] [junit4] 2> 897032 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSI= NG SolrCore org.apache.solr.core.SolrCore@5dfd9709 [junit4] 2> 897033 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.m.SolrMetricManager Closing met= ric reporters for registry=3Dsolr.core.collection1, tag=3D1576900361 [junit4] 2> 897037 INFO (searcherExecutor-2471-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@1fcd3d4f[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 897038 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [= collection1] {add=3D[529 (1565061260219777024)]} 0 2 [junit4] 2> 897038 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] weba= pp=3Dnull path=3Dnull params=3D{q=3Did:529&qt=3Dstandard&start=3D0&rows=3D2= 0&version=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 897039 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [= collection1] {add=3D[530 (1565061260223971328)]} 0 4 [junit4] 2> 897039 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] weba= pp=3Dnull path=3Dnull params=3D{q=3Did:530&qt=3Dstandard&start=3D0&rows=3D2= 0&version=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 897040 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [= collection1] {delete=3D[529 (-1565061260225019904)]} 0 4 [junit4] 2> 897040 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] weba= pp=3Dnull path=3Dnull params=3D{q=3Did:529&qt=3Dstandard&start=3D0&rows=3D2= 0&version=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 897040 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: = xpath=3D//result[@numFound=3D1] [junit4] 2> =09xml response was: [junit4] 2> [junit4] 2> 0<= int name=3D"QTime">0 [junit4] 2> [junit4] 2>=20 [junit4] 2> =09request was:q=3Did:529&qt=3Dstandard&start=3D0&rows=3D2= 0&version=3D2.2 [junit4] 2> 897041 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: = q=3Did:529&qt=3Dstandard&start=3D0&rows=3D20&version=3D2.2:java.lang.Runtim= eException: REQUEST FAILED: xpath=3D//result[@numFound=3D1] [junit4] 2> =09xml response was: [junit4] 2> [junit4] 2> 0<= int name=3D"QTime">0 [junit4] 2> [junit4] 2>=20 [junit4] 2> =09request was:q=3Did:529&qt=3Dstandard&start=3D0&rows=3D2= 0&version=3D2.2 [junit4] 2> =09at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ= 4.java:889) [junit4] 2> =09at org.apache.solr.update.AutoCommitTest.testCommitWith= in(AutoCommitTest.java:353) [junit4] 2> =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native = Method) [junit4] 2> =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMe= thodAccessorImpl.java:62) [junit4] 2> =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(Dele= gatingMethodAccessorImpl.java:43) [junit4] 2> =09at java.lang.reflect.Method.invoke(Method.java:498) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner.= invoke(RandomizedRunner.java:1713) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner$= 8.evaluate(RandomizedRunner.java:907) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner$= 9.evaluate(RandomizedRunner.java:943) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner$= 10.evaluate(RandomizedRunner.java:957) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.SystemPrope= rtiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> =09at org.apache.lucene.util.TestRuleSetupTeardownChained$= 1.evaluate(TestRuleSetupTeardownChained.java:49) [junit4] 2> =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.eva= luate(AbstractBeforeAfterRule.java:45) [junit4] 2> =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.e= valuate(TestRuleThreadAndTestName.java:48) [junit4] 2> =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailure= s$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluat= e(TestRuleMarkFailure.java:47) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.ThreadLeakControl= $StatementRunner.run(ThreadLeakControl.java:368) [junit4] 2> =09at com.carrotsearch.randomizedtesting.ThreadLeakControl= .forkTimeoutingTask(ThreadLeakControl.java:817) [junit4] 2> =09at com.carrotsearch.randomizedtesting.ThreadLeakControl= $3.evaluate(ThreadLeakControl.java:468) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner.= runSingleTest(RandomizedRunner.java:916) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner$= 5.evaluate(RandomizedRunner.java:802) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner$= 6.evaluate(RandomizedRunner.java:852) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner$= 7.evaluate(RandomizedRunner.java:863) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.SystemPrope= rtiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.eva= luate(AbstractBeforeAfterRule.java:45) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at org.apache.lucene.util.TestRuleStoreClassName$1.eval= uate(TestRuleStoreClassName.java:41) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.NoShadowing= OrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.jav= a:40) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.NoShadowing= OrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.jav= a:40) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.= evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluat= e(TestRuleMarkFailure.java:47) [junit4] 2> =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailure= s$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.ev= aluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.ThreadLeakControl= $StatementRunner.run(ThreadLeakControl.java:368) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2>=20 [junit4] 2> 897041 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[9= 9AD8954415D9E27]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCo= mmitWithin [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DAutoCommitTes= t -Dtests.method=3DtestCommitWithin -Dtests.seed=3D99AD8954415D9E27 -Dtests= .multiplier=3D2 -Dtests.slow=3Dtrue -Dtests.locale=3Des-SV -Dtests.timezone= =3DPacific/Funafuti -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 0.34s J2 | AutoCommitTest.testCommitWithin <<< [junit4] > Throwable #1: java.lang.RuntimeException: Exception during= query [junit4] > =09at __randomizedtesting.SeedInfo.seed([99AD8954415D9E27:= 237FE62CC2737032]:0) [junit4] > =09at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ= 4.java:896) [junit4] > =09at org.apache.solr.update.AutoCommitTest.testCommitWith= in(AutoCommitTest.java:353) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpa= th=3D//result[@numFound=3D1] [junit4] > =09xml response was: [junit4] > [junit4] > 0<= int name=3D"QTime">0 [junit4] > [junit4] > =09request was:q=3Did:529&qt=3Dstandard&start=3D0&rows=3D2= 0&version=3D2.2 [junit4] > =09at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ= 4.java:889) [junit4] > =09... 40 more [junit4] 2> 897052 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxTi= me [junit4] 2> 897053 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1]= REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 897053 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searche= r@41598836[collection1] realtime] [junit4] 2> 897053 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] webapp=3Dnull path=3Dnull params=3D{_version_=3D-92233720368547758= 07&update.distrib=3DFROMLEADER&versions=3Dtrue&wt=3Djson&indent=3Dtrue}{del= eteByQuery=3D*:* (-9223372036854775807)} 0 0 [junit4] 2> 897054 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn't loa= d core descriptor from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Test= s-master/solr/core/src/test-files/solr/collection1/conf/solrcore.properties= :java.nio.file.NoSuchFileException: /x1/jenkins/jenkins-slave/workspace/Luc= ene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrco= re.properties [junit4] 2> 897054 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 = libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucen= e-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenk= ins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-fil= es/solr/collection1/lib/classes] [junit4] 2> 897086 WARN (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.Config Beginning with Solr 5.5, is deprecated, use instead. [junit4] 2> 897087 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersi= on: 7.0.0 [junit4] 2> 897112 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema n= ame=3Dtest [junit4] 2> 897352 WARN (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.IndexSchema [collection1] default = search field in schema is text. WARNING: Deprecated, please use 'df' on req= uest instead. [junit4] 2> 897354 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0= with uniqueid field id [junit4] 2> 897361 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore '= collection1' using configuration from instancedir /x1/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collectio= n1 [junit4] 2> 897381 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring f= or 'solr.core.collection1' (registry 'solr.core.collection1') enabled at se= rver: com.sun.jmx.mbeanserver.JmxMBeanServer@5631b867 [junit4] 2> 897381 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening = new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-mast= er/solr/core/src/test-files/solr/collection1], dataDir=3D[/x1/jenkins/jenki= ns-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/te= mp/solr.update.AutoCommitTest_99AD8954415D9E27-001/init-core-data-001/] [junit4] 2> 897446 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: dis= abled [junit4] 2> 897447 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: dis= abled [junit4] 2> 897449 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searche= r@5580bf31[collection1] main] [junit4] 2> 897450 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing= spell checkers [junit4] 2> 897450 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4] 2> 897454 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be= reserved for 10000 [junit4] 2> 897456 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: default [junit4] 2> 897456 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: direct [junit4] 2> 897456 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: wordbreak [junit4] 2> 897457 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: multipleFields [junit4] 2> 897457 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: jarowinkler [junit4] 2> 897457 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: external [junit4] 2> 897457 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: freq [junit4] 2> 897457 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: fqcn [junit4] 2> 897458 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: perDict [junit4] 2> 897461 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@5580bf31[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 897461 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolic= y wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePol= icy: maxMergeAtOnce=3D32, maxMergeAtOnceExplicit=3D10, maxMergedSegmentMB= =3D3.1552734375, floorSegmentMB=3D1.7099609375, forceMergeDeletesPctAllowed= =3D27.773653136817696, segmentsPerTier=3D29.0, maxCFSSegmentSizeMB=3D8.7960= 93022207999E12, noCFSRatio=3D0.0 [junit4] 2> 897461 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWrit= er is ready to be used. [junit4] 2> 897461 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searche= r@3b3a8657[collection1] main] [junit4] 2> 897462 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING So= lrCore org.apache.solr.core.SolrCore@44c1bfff [junit4] 2> 897462 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric r= eporters for registry=3Dsolr.core.collection1, tag=3D1153548287 [junit4] 2> 897473 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@3b3a8657[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 897477 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[529 (1565061260682199040)]} 0 1 [junit4] 2> 897478 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:529&qt=3Dstandard&start=3D0&rows=3D20&ver= sion=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 898978 INFO (commitScheduler-2478-thread-1) [ x:collec= tion1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=3Dfalse,openSear= cher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dtrue,pr= epareCommit=3Dfalse} [junit4] 2> 898982 INFO (commitScheduler-2478-thread-1) [ x:collec= tion1] o.a.s.s.SolrIndexSearcher Opening [Searcher@197957b6[collection1] ma= in] [junit4] 2> 898984 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@197957b6[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader(Uninverting(_3(7.0.0):c1)))} [junit4] 2> 898985 INFO (commitScheduler-2478-thread-1) [ x:collec= tion1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 899234 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[530 (1565061262521401344)]} 0 1757 [junit4] 2> 899234 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:529&qt=3Dstandard&start=3D0&rows=3D20&ver= sion=3D2.2} hits=3D1 status=3D0 QTime=3D0 [junit4] 2> 899235 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:530&qt=3Dstandard&start=3D0&rows=3D20&ver= sion=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 899240 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] webapp=3Dnull path=3Dnull params=3D{}{delete=3D[529 (-156506126252= 7692800)]} 0 4 [junit4] 2> 899241 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:529&qt=3Dstandard&start=3D0&rows=3D20&ver= sion=3D2.2} hits=3D1 status=3D0 QTime=3D0 [junit4] 2> 900734 INFO (commitScheduler-2478-thread-1) [ x:collec= tion1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=3Dfalse,openSear= cher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dtrue,pr= epareCommit=3Dfalse} [junit4] 2> 900740 INFO (commitScheduler-2478-thread-1) [ x:collec= tion1] o.a.s.s.SolrIndexSearcher Opening [Searcher@711bfd3d[collection1] ma= in] [junit4] 2> 900742 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@711bfd3d[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader(Uninverting(_4(7.0.0):c1)))} [junit4] 2> 900743 INFO (commitScheduler-2478-thread-1) [ x:collec= tion1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 900744 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[550 (1565061264107896832)]} 0 3267 [junit4] 2> 900744 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:529&qt=3Dstandard&start=3D0&rows=3D20&ver= sion=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 900745 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264108945408)]} 0 3268 [junit4] 2> 900745 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264109993984)]} 0 3269 [junit4] 2> 900745 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264109993985)]} 0 3269 [junit4] 2> 900746 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264109993986)]} 0 3269 [junit4] 2> 900746 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264111042560)]} 0 3269 [junit4] 2> 900746 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264111042561)]} 0 3270 [junit4] 2> 900746 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264111042562)]} 0 3270 [junit4] 2> 900747 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264112091136)]} 0 3270 [junit4] 2> 900747 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264112091137)]} 0 3271 [junit4] 2> 900747 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[500 (1565061264112091138)]} 0 3271 [junit4] 2> 900748 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:500&qt=3Dstandard&start=3D0&rows=3D20&ver= sion=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 902244 INFO (commitScheduler-2478-thread-1) [ x:collec= tion1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=3Dfalse,openSear= cher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dtrue,pr= epareCommit=3Dfalse} [junit4] 2> 902247 INFO (commitScheduler-2478-thread-1) [ x:collec= tion1] o.a.s.s.SolrIndexSearcher Opening [Searcher@75b7c747[collection1] ma= in] [junit4] 2> 902249 INFO (searcherExecutor-2475-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@75b7c747[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader(Uninverting(_4(7.0.0):c1) Uninverting(_5(7.0.0):c1= 1/9:delGen=3D1)))} [junit4] 2> 902250 INFO (commitScheduler-2478-thread-1) [ x:collec= tion1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 902250 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle= ction1] {add=3D[531 (1565061265688100864)]} 0 4774 [junit4] 2> 902251 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=3D= null path=3Dnull params=3D{q=3Did:500&qt=3Dstandard&start=3D0&rows=3D20&ver= sion=3D2.2} hits=3D1 status=3D0 QTime=3D0 [junit4] 2> 902251 INFO (TEST-AutoCommitTest.testMaxTime-seed#[99AD89= 54415D9E27]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime [junit4] 2> 902252 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 902252 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContai= ner instance=3D1146371260 [junit4] 2> 902252 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric repor= ters for registry=3Dsolr.node, tag=3Dnull [junit4] 2> 902253 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric repor= ters for registry=3Dsolr.jvm, tag=3Dnull [junit4] 2> 902255 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric repor= ters for registry=3Dsolr.jetty, tag=3Dnull [junit4] 2> 902255 INFO (SUITE-AutoCommitTest-seed#[99AD8954415D9E27]= -worker) [ x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocati= ng transient cache for 2147483647 transient cores [junit4] 2> 902255 INFO (coreCloseExecutor-2479-thread-1-processing-x= :collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING S= olrCore org.apache.solr.core.SolrCore@5d1d15cb [junit4] 2> 902256 INFO (coreCloseExecutor-2479-thread-1-processing-x= :collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing o= n IndexWriter close. [junit4] 2> 902256 INFO (coreCloseExecutor-2479-thread-1-processing-x= :collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommit= Data with IW:org.apache.solr.update.SolrIndexWriter@6017a9d1 commitCommandV= ersion:0 [junit4] 2> 902291 INFO (coreCloseExecutor-2479-thread-1-processing-x= :collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric = reporters for registry=3Dsolr.core.collection1, tag=3D1562187211 [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenk= ins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/t= emp/solr.update.AutoCommitTest_99AD8954415D9E27-001 [junit4] 2> NOTE: test params are: codec=3DHighCompressionCompressingS= toredFields(storedFieldsFormat=3DCompressingStoredFieldsFormat(compressionM= ode=3DHIGH_COMPRESSION, chunkSize=3D8, maxDocsPerChunk=3D947, blockSize=3D2= ), termVectorsFormat=3DCompressingTermVectorsFormat(compressionMode=3DHIGH_= COMPRESSION, chunkSize=3D8, blockSize=3D2)), sim=3DRandomSimilarity(queryNo= rm=3Dfalse): {}, locale=3Des-SV, timezone=3DPacific/Funafuti [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8= .0_121 (64-bit)/cpus=3D4,threads=3D1,free=3D235430288,total=3D513277952 [junit4] 2> NOTE: All tests run in this JVM: [TestFieldCacheReopen, Di= stributedVersionInfoTest, SpatialHeatmapFacetsTest, TestOrdValues, DirectUp= dateHandlerTest, TestCoreDiscovery, GraphQueryTest, AddBlockUpdateTest, Tes= tClassNameShortening, TestPhraseSuggestions, MultiThreadedOCPTest, TestQuer= ySenderNoQuery, TestEmbeddedSolrServerSchemaAPI, TestTolerantSearch, Remote= QueryErrorTest, AnalyticsQueryTest, TestIndexingPerformance, TestDistribDoc= BasedVersion, DistributedFacetPivotSmallTest, BlockDirectoryTest, TestBinar= yField, TestMissingGroups, SuggesterWFSTTest, JvmMetricsTest, ShowFileReque= stHandlerTest, TestSolrQueryParserResource, TestOverriddenPrefixQueryForCus= tomFieldType, PrimitiveFieldTypeTest, TestDistribIDF, ParsingFieldUpdatePro= cessorsTest, VersionInfoTest, TestBinaryResponseWriter, CreateCollectionCle= anupTest, TestIBSimilarityFactory, TestCollectionAPIs, TestConfig, Segments= InfoRequestHandlerTest, TestSolrCoreSnapshots, QueryResultKeyTest, ChaosMon= keyNothingIsSafeTest, UUIDUpdateProcessorFallbackTest, CoreAdminHandlerTest= , TestDocSet, OverseerModifyCollectionTest, DataDrivenBlockJoinTest, TestSm= ileRequest, DeleteLastCustomShardedReplicaTest, TestSchemaVersionResource, = TestFieldResource, HardAutoCommitTest, CloneFieldUpdateProcessorFactoryTest= , TestManagedSynonymFilterFactory, TestGeoJSONResponseWriter, ExternalFileF= ieldSortTest, RestartWhileUpdatingTest, TestConfigSetsAPIZkFailure, SolrTes= tCaseJ4Test, TestStressInPlaceUpdates, ClusterStateTest, TestReversedWildca= rdFilterFactory, EchoParamsTest, DirectUpdateHandlerOptimizeTest, TestClass= icSimilarityFactory, TestCollectionAPI, TestUseDocValuesAsStored, ResponseH= eaderTest, TestConfigReload, TestReloadDeadlock, TestNRTOpen, AutoCommitTes= t] [junit4] Completed [208/702 (1!)] on J2 in 7.28s, 3 tests, 1 error <<< F= AILURES! [...truncated 44974 lines...] ------=_Part_1238_2056756582.1492562601150 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_1238_2056756582.1492562601150--