You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/06/27 17:43:45 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-10) - Build # 7384
- Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7384/
Java: 64bit/jdk-10 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.embedded.MergeIndexesEmbeddedTest
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-006: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-006 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-006: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-006
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001
at __randomizedtesting.SeedInfo.seed([11D9DC41604C7670]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:318)
at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
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:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 16141 lines...]
[junit4] Suite: org.apache.solr.client.solrj.embedded.MergeIndexesEmbeddedTest
[junit4] 2> 153235 INFO (SUITE-MergeIndexesEmbeddedTest-seed#[11D9DC41604C7670]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\init-core-data-001
[junit4] 2> 153236 WARN (SUITE-MergeIndexesEmbeddedTest-seed#[11D9DC41604C7670]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 153236 INFO (SUITE-MergeIndexesEmbeddedTest-seed#[11D9DC41604C7670]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 153237 INFO (SUITE-MergeIndexesEmbeddedTest-seed#[11D9DC41604C7670]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 153238 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMergeIndexesByCoreName
[junit4] 2> 153239 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\solr.xml
[junit4] 2> 153242 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 153436 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 153436 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 153449 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 153454 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 153455 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 153459 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.CorePropertiesLocator Found 2 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore
[junit4] 2> 153459 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [core0, core1]
[junit4] 2> 153476 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 153482 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.s.IndexSchema [core0] Schema name=example core zero
[junit4] 2> 153483 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.s.IndexSchema Loaded schema example core zero/1.1 with uniqueid field id
[junit4] 2> 153483 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.c.CoreContainer Creating SolrCore 'core0' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0, trusted=true
[junit4] 2> 153483 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.core0' (registry 'solr.core.core0') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 153487 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 153487 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.c.SolrCore [[core0] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-001\]
[junit4] 2> 153504 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 153509 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.s.IndexSchema [core1] Schema name=example core one
[junit4] 2> 153511 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.s.IndexSchema Loaded schema example core one/1.1 with uniqueid field id
[junit4] 2> 153511 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1, trusted=true
[junit4] 2> 153512 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.core1' (registry 'solr.core.core1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 153512 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 153512 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-002\]
[junit4] 2> 153900 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 153900 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-002 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 153902 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 153902 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 153902 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 153902 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-001 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 153904 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d25ea21[core1] main]
[junit4] 2> 153905 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 153905 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 153905 WARN (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1\conf; switching to use InMemory storage instead.
[junit4] 2> 153907 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 153907 INFO (coreLoadExecutor-501-thread-2) [ x:core1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604447385221595136
[junit4] 2> 153909 INFO (searcherExecutor-503-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@3d25ea21[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 153909 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e5b138f[core0] main]
[junit4] 2> 153909 WARN (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0\conf; switching to use InMemory storage instead.
[junit4] 2> 153910 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 153910 INFO (coreLoadExecutor-501-thread-1) [ x:core0] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604447385224740864
[junit4] 2> 153910 INFO (searcherExecutor-502-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@3e5b138f[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 153911 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.s.MergeIndexesExampleTestBase CORES=org.apache.solr.core.CoreContainer@7b1755f5 : [core0, core1]
[junit4] 2> 153914 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447385228935168,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 153914 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 153915 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 153915 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{deleteByQuery=*:* (-1604447385225789440),commit=} 0 3
[junit4] 2> 153917 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447385232080896,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 153917 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 153917 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 153917 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{deleteByQuery=*:* (-1604447385229983744),commit=} 0 2
[junit4] 2> 153919 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447385234178048,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 153919 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5416ea2e commitCommandVersion:1604447385234178048
[junit4] 2> 153974 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6917a992[core0] main]
[junit4] 2> 153975 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 153975 INFO (searcherExecutor-502-thread-1) [ ] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@6917a992[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))}
[junit4] 2> 153975 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{add=[AAA (1604447385233129472)],commit=} 0 58
[junit4] 2> 153977 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447385294995456,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 153977 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a1e7e0b commitCommandVersion:1604447385294995456
[junit4] 2> 154029 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a85ee2c[core1] main]
[junit4] 2> 154029 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 154030 INFO (searcherExecutor-503-thread-1) [ ] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@3a85ee2c[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))}
[junit4] 2> 154030 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{add=[BBB (1604447385293946880)],commit=} 0 54
[junit4] 2> 154030 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0
[junit4] 2> 154031 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0
[junit4] 2> 154031 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0
[junit4] 2> 154031 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=0 status=0 QTime=0
[junit4] 2> 154031 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0
[junit4] 2> 154031 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0
[junit4] 2> 154035 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 start mergeIndexes{MMapDirectory@C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-002\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7c4385eb}
[junit4] 2> 154052 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 end_mergeIndexes
[junit4] 2> 154052 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447385373638656,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 154052 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5416ea2e commitCommandVersion:1604447385373638656
[junit4] 2> 154140 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@256de3b6[core0] main]
[junit4] 2> 154140 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 154141 INFO (searcherExecutor-502-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@256de3b6[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1)))}
[junit4] 2> 154141 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{commit=} 0 88
[junit4] 2> 154141 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0
[junit4] 2> 154142 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0
[junit4] 2> 154142 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.SolrTestCaseJ4 ###Ending testMergeIndexesByCoreName
[junit4] 2> 154142 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2065126901
[junit4] 2> 154142 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 154142 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b4a9f4b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@64300d81
[junit4] 2> 154146 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 154146 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@20468a61: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5273b0c8
[junit4] 2> 154148 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 154148 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByCoreName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@493f00ef: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@58f2429
[junit4] 2> 154149 INFO (coreCloseExecutor-512-thread-1-processing-x:core0) [ x:core1] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@6f05b48b
[junit4] 2> 154149 INFO (coreCloseExecutor-512-thread-1-processing-x:core0) [ x:core1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core1, tag=6f05b48b
[junit4] 2> 154149 INFO (coreCloseExecutor-512-thread-2-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] CLOSING SolrCore org.apache.solr.core.SolrCore@4be024fa
[junit4] 2> 154149 INFO (coreCloseExecutor-512-thread-1-processing-x:core0) [ x:core1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5dbf81fa: rootName = null, domain = solr.core.core1, service url = null, agent id = null] for registry solr.core.core1 / com.codahale.metrics.MetricRegistry@37a03670
[junit4] 2> 154154 INFO (coreCloseExecutor-512-thread-2-processing-x:core0) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core0, tag=4be024fa
[junit4] 2> 154154 INFO (coreCloseExecutor-512-thread-2-processing-x:core0) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7110ab06: rootName = null, domain = solr.core.core0, service url = null, agent id = null] for registry solr.core.core0 / com.codahale.metrics.MetricRegistry@72c945e7
[junit4] 2> 154166 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.SolrTestCaseJ4 ###Starting testMergeIndexesByDirName
[junit4] 2> 154168 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\solr.xml
[junit4] 2> 154172 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 154287 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 154287 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 154304 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 154313 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 154313 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 154396 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.CorePropertiesLocator Found 2 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore
[junit4] 2> 154396 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.CorePropertiesLocator Cores are: [core0, core1]
[junit4] 2> 154424 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 154449 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.s.IndexSchema [core0] Schema name=example core zero
[junit4] 2> 154466 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 154486 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.s.IndexSchema Loaded schema example core zero/1.1 with uniqueid field id
[junit4] 2> 154486 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.c.CoreContainer Creating SolrCore 'core0' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0, trusted=true
[junit4] 2> 154487 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.core0' (registry 'solr.core.core0') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 154487 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 154487 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [[core0] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-003\]
[junit4] 2> 154496 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.s.IndexSchema [core1] Schema name=example core one
[junit4] 2> 154497 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.s.IndexSchema Loaded schema example core one/1.1 with uniqueid field id
[junit4] 2> 154497 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1, trusted=true
[junit4] 2> 154498 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.core1' (registry 'solr.core.core1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 154498 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 154498 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-004\]
[junit4] 2> 154610 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 154610 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-004 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 154610 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 154610 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-003 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 154611 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 154611 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 154611 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 154611 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 154614 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@31d54c20[core1] main]
[junit4] 2> 154614 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@78eb8ded[core0] main]
[junit4] 2> 154614 WARN (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0\conf; switching to use InMemory storage instead.
[junit4] 2> 154614 WARN (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1\conf; switching to use InMemory storage instead.
[junit4] 2> 154615 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 154615 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 154615 INFO (coreLoadExecutor-522-thread-1-processing-x:core0) [ x:core0] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604447385963986944
[junit4] 2> 154615 INFO (coreLoadExecutor-522-thread-2-processing-x:core0) [ x:core1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604447385963986944
[junit4] 2> 154615 INFO (searcherExecutor-523-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@78eb8ded[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 154616 INFO (searcherExecutor-524-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@31d54c20[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 154617 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.s.MergeIndexesExampleTestBase CORES=org.apache.solr.core.CoreContainer@2487f1f5 : [core0, core1]
[junit4] 2> 154619 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447385968181248,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 154619 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 154620 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 154620 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{deleteByQuery=*:* (-1604447385966084096),commit=} 0 2
[junit4] 2> 154622 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447385971326976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 154622 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 154623 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 154623 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.p.LogUpdateProcessorFactory [core1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{deleteByQuery=*:* (-1604447385969229824),commit=} 0 2
[junit4] 2> 154624 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447385973424128,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 154624 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2236240e commitCommandVersion:1604447385973424128
[junit4] 2> 154695 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@46e51bd7[core0] main]
[junit4] 2> 154697 INFO (searcherExecutor-523-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@46e51bd7[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))}
[junit4] 2> 154697 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 154697 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{add=[AAA (1604447385972375552)],commit=} 0 75
[junit4] 2> 154700 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447386053115904,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 154700 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@18a84a51 commitCommandVersion:1604447386053115904
[junit4] 2> 154802 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@65effeee[core1] main]
[junit4] 2> 154804 INFO (searcherExecutor-524-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@65effeee[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))}
[junit4] 2> 154804 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 154804 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.p.LogUpdateProcessorFactory [core1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{add=[BBB (1604447386051018752)],commit=} 0 106
[junit4] 2> 154805 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0
[junit4] 2> 154805 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0
[junit4] 2> 154805 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0
[junit4] 2> 154805 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=0 status=0 QTime=0
[junit4] 2> 154806 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0
[junit4] 2> 154806 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0
[junit4] 2> 154820 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 start mergeIndexes{MMapDirectory@C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-004\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7c4385eb}
[junit4] 2> 154839 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 end_mergeIndexes
[junit4] 2> 154839 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604447386198867968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 154839 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2236240e commitCommandVersion:1604447386198867968
[junit4] 2> 154934 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@52815baf[core0] main]
[junit4] 2> 154934 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 154935 INFO (searcherExecutor-523-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@52815baf[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1)))}
[junit4] 2> 154935 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{commit=} 0 96
[junit4] 2> 154936 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0
[junit4] 2> 154936 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0
[junit4] 2> 154937 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.SolrTestCaseJ4 ###Ending testMergeIndexesByDirName
[junit4] 2> 154937 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.CoreContainer Shutting down CoreContainer instance=612889077
[junit4] 2> 154937 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 154937 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3fa3174b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3b068dc0
[junit4] 2> 154941 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 154941 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@65873ad8: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5273b0c8
[junit4] 2> 154942 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 154942 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5391b641: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@58f2429
[junit4] 2> 154945 INFO (coreCloseExecutor-533-thread-1-processing-x:core0) [ x:core1] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@17f18c22
[junit4] 2> 154945 INFO (coreCloseExecutor-533-thread-1-processing-x:core0) [ x:core1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core1, tag=17f18c22
[junit4] 2> 154945 INFO (coreCloseExecutor-533-thread-1-processing-x:core0) [ x:core1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7cdbf065: rootName = null, domain = solr.core.core1, service url = null, agent id = null] for registry solr.core.core1 / com.codahale.metrics.MetricRegistry@4b328644
[junit4] 2> 154948 INFO (coreCloseExecutor-533-thread-2-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] CLOSING SolrCore org.apache.solr.core.SolrCore@551a3ca4
[junit4] 2> 154950 INFO (coreCloseExecutor-533-thread-2-processing-x:core0) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core0, tag=551a3ca4
[junit4] 2> 154950 INFO (coreCloseExecutor-533-thread-2-processing-x:core0) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@21020505: rootName = null, domain = solr.core.core0, service url = null, agent id = null] for registry solr.core.core0 / com.codahale.metrics.MetricRegistry@33485837
[junit4] 2> 154962 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.SolrTestCaseJ4 ###Starting testMergeMultipleRequest
[junit4] 2> 154964 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\solr.xml
[junit4] 2> 154967 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 155404 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 155405 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 155429 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 155439 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 155439 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 155445 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.CorePropertiesLocator Found 2 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore
[junit4] 2> 155446 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.CorePropertiesLocator Cores are: [core0, core1]
[junit4] 2> 155462 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 155485 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 155490 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.s.IndexSchema [core1] Schema name=example core one
[junit4] 2> 155491 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.s.IndexSchema [core0] Schema name=example core zero
[junit4] 2> 155493 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.s.IndexSchema Loaded schema example core zero/1.1 with uniqueid field id
[junit4] 2> 155493 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.c.CoreContainer Creating SolrCore 'core0' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0, trusted=true
[junit4] 2> 155494 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.core0' (registry 'solr.core.core0') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 155494 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 155494 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [[core0] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-005\]
[junit4] 2> 155500 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.s.IndexSchema Loaded schema example core one/1.1 with uniqueid field id
[junit4] 2> 155500 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1, trusted=true
[junit4] 2> 155500 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.core1' (registry 'solr.core.core1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63225847
[junit4] 2> 155500 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 155502 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-006\]
[junit4] 2> 155689 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 155689 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-005 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 155691 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 155691 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 155693 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 155693 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-006 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 155693 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d5d37e9[core0] main]
[junit4] 2> 155694 WARN (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core0\conf; switching to use InMemory storage instead.
[junit4] 2> 155694 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 155694 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 155694 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 155694 INFO (coreLoadExecutor-543-thread-1-processing-x:core0) [ x:core0] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604447387095400448
[junit4] 2> 155696 INFO (searcherExecutor-544-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@1d5d37e9[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 155697 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5285141[core1] main]
[junit4] 2> 155697 WARN (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\solrj\src\test-files\solrj\solr\multicore\core1\conf; switching to use InMemory storage instead.
[junit4] 2> 155698 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 155699 INFO (coreLoadExecutor-543-thread-2-processing-x:core0) [ x:core1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604447387100643328
[junit4] 2> 155699 INFO (searcherExecutor-545-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@5285141[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 155700 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.s.MergeIndexesExampleTestBase CORES=org.apache.solr.core.CoreContainer@ac52cd1 : [core0, core1]
[junit4] 2> 155701 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.SolrTestCaseJ4 ###Ending testMergeMultipleRequest
[junit4] 2> 155701 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.c.CoreContainer Shutting down CoreContainer instance=180694225
[junit4] 2> 155701 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 155701 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@87e45ca: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@306c0cc7
[junit4] 2> 155705 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 155705 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@36cd0743: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5273b0c8
[junit4] 2> 155707 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 155707 INFO (TEST-MergeIndexesEmbeddedTest.testMergeMultipleRequest-seed#[11D9DC41604C7670]) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f557c44: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@58f2429
[junit4] 2> 155708 INFO (coreCloseExecutor-554-thread-1-processing-x:core0) [ x:core0] o.a.s.c.SolrCore [core0] CLOSING SolrCore org.apache.solr.core.SolrCore@7e6bae66
[junit4] 2> 155708 INFO (coreCloseExecutor-554-thread-1-processing-x:core0) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core0, tag=7e6bae66
[junit4] 2> 155708 INFO (coreCloseExecutor-554-thread-1-processing-x:core0) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2d1829e2: rootName = null, domain = solr.core.core0, service url = null, agent id = null] for registry solr.core.core0 / com.codahale.metrics.MetricRegistry@65255e1f
[junit4] 2> 155722 INFO (coreCloseExecutor-554-thread-2-processing-x:core0) [ x:core1] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@20e83f6d
[junit4] 2> 155722 INFO (coreCloseExecutor-554-thread-2-processing-x:core0) [ x:core1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core1, tag=20e83f6d
[junit4] 2> 155722 INFO (coreCloseExecutor-554-thread-2-processing-x:core0) [ x:core1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@318ca4a0: rootName = null, domain = solr.core.core1, service url = null, agent id = null] for registry solr.core.core1 / com.codahale.metrics.MetricRegistry@2b3956e
[junit4] 2> 155733 INFO (SUITE-MergeIndexesEmbeddedTest-seed#[11D9DC41604C7670]-worker) [ x:core0] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Jun 27, 2018 5:24:25 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{_version_=DocValuesFormat(name=Lucene70), name=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=993, maxMBSortInHeap=7.547559267518842, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@116893e3), locale=yo-NG, timezone=Canada/Mountain
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 10 (64-bit)/cpus=3,threads=1,free=87402008,total=386195456
[junit4] 2> NOTE: All tests run in this JVM: [LargeVolumeBinaryJettyTest, TestUpdateRequestCodec, StreamExpressionParserTest, NaturalLogEvaluatorTest, TestToleratedUpdateError, SolrExampleStreamingBinaryTest, CloudSolrClientCacheTest, TestNamedListCodec, LessThanEqualToEvaluatorTest, TangentEvaluatorTest, CloudSolrClientTest, TestXMLEscaping, StreamExpressionTest, ShardParamsTest, OperationsTest, ConcurrentUpdateSolrClientTest, HyperbolicCosineEvaluatorTest, AbsoluteValueEvaluatorTest, TestCloudSolrClientConnections, SquareRootEvaluatorTest, RawValueEvaluatorTest, TestBatchUpdate, CubedRootEvaluatorTest, SolrQueryTest, ArcTangentEvaluatorTest, DocumentAnalysisResponseTest, TestTimeSource, ClientUtilsTest, JdbcDriverTest, EqualToEvaluatorTest, AndEvaluatorTest, DivideEvaluatorTest, JDBCStreamTest, TestZkConfigManager, TestConfigSetAdminRequest, SolrExampleEmbeddedTest, GreaterThanEqualToEvaluatorTest, CorrelationEvaluatorTest, CommonParamsTest, UsingSolrJRefGuideExamplesTest, SolrExampleXMLTest, TestDocumentObjectBinder, TestPolicy, JettyWebappTest, LargeVolumeJettyTest, MergeIndexesEmbeddedTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MergeIndexesEmbeddedTest -Dtests.seed=11D9DC41604C7670 -Dtests.slow=true -Dtests.locale=yo-NG -Dtests.timezone=Canada/Mountain -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | MergeIndexesEmbeddedTest (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-006: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001\tempDir-006
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_11D9DC41604C7670-001
[junit4] > at __randomizedtesting.SeedInfo.seed([11D9DC41604C7670]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:318)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] Completed [90/154 (1!)] on J0 in 2.79s, 3 tests, 1 error <<< FAILURES!
[...truncated 43047 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7384/consoleText
[repro] Revision: 39fb4b8a189e2d72f2cedf0d5c9ff15db7153085
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\solrj
[repro] MergeIndexesEmbeddedTest
[repro] ant compile-test
[...truncated 2476 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.MergeIndexesEmbeddedTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=11D9DC41604C7670 -Dtests.slow=true -Dtests.locale=yo-NG -Dtests.timezone=Canada/Mountain -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 84 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.client.solrj.embedded.MergeIndexesEmbeddedTest
[repro] Exiting with code 0
[...truncated 75 lines...]
[JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_172) - Build #
7385 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7385/
Java: 32bit/jdk1.8.0_172 -server -XX:+UseParallelGC
4 tests failed.
FAILED: org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd.testFullImportBadConfig
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\J1\temp\solr.handler.dataimport.TestSolrEntityProcessorEndToEnd_1ED50EB27141BF4D-001\tempDir-005\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\J1\temp\solr.handler.dataimport.TestSolrEntityProcessorEndToEnd_1ED50EB27141BF4D-001\tempDir-005\collection1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\J1\temp\solr.handler.dataimport.TestSolrEntityProcessorEndToEnd_1ED50EB27141BF4D-001\tempDir-005: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\J1\temp\solr.handler.dataimport.TestSolrEntityProcessorEndToEnd_1ED50EB27141BF4D-001\tempDir-005
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\J1\temp\solr.handler.dataimport.TestSolrEntityProcessorEndToEnd_1ED50EB27141BF4D-001\tempDir-005\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\J1\temp\solr.handler.dataimport.TestSolrEntityProcessorEndToEnd_1ED50EB27141BF4D-001\tempDir-005\collection1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\J1\temp\solr.handler.dataimport.TestSolrEntityProcessorEndToEnd_1ED50EB27141BF4D-001\tempDir-005: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\J1\temp\solr.handler.dataimport.TestSolrEntityProcessorEndToEnd_1ED50EB27141BF4D-001\tempDir-005
at __randomizedtesting.SeedInfo.seed([1ED50EB27141BF4D:51A3D83139CD24A6]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:318)
at org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd$SolrInstance.tearDown(TestSolrEntityProcessorEndToEnd.java:360)
at org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd.tearDown(TestSolrEntityProcessorEndToEnd.java:142)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerTest.testTrigger
Error Message:
expected:<3> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<2>
at __randomizedtesting.SeedInfo.seed([C4AD92A428E80998:A766A426B1277AB5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerTest.scheduledTriggerTest(ScheduledTriggerTest.java:112)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerTest.testTrigger(ScheduledTriggerTest.java:65)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerTest.testTrigger
Error Message:
expected:<3> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<2>
at __randomizedtesting.SeedInfo.seed([C4AD92A428E80998:A766A426B1277AB5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerTest.scheduledTriggerTest(ScheduledTriggerTest.java:112)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerTest.testTrigger(ScheduledTriggerTest.java:65)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.client.solrj.io.stream.MathExpressionTest.testDistributions
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([CD5D7010F654AC78:72A231BA28AE4CE4]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.client.solrj.io.stream.MathExpressionTest.testDistributions(MathExpressionTest.java:1490)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14347 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerTest_C4AD92A428E80998-001\init-core-data-001
[junit4] 2> 2765077 WARN (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 2765077 INFO (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2765081 INFO (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2765081 INFO (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2765082 INFO (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerTest_C4AD92A428E80998-001\tempDir-001
[junit4] 2> 2765082 INFO (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2765083 INFO (Thread-4642) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2765083 INFO (Thread-4642) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2765087 ERROR (Thread-4642) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 2765184 INFO (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:57988
[junit4] 2> 2765189 INFO (zkConnectionManagerCallback-8945-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2765196 INFO (jetty-launcher-8942-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 2765196 INFO (jetty-launcher-8942-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2765196 INFO (jetty-launcher-8942-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2765196 INFO (jetty-launcher-8942-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2765197 INFO (jetty-launcher-8942-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b9ff9{/solr,null,AVAILABLE}
[junit4] 2> 2765199 INFO (jetty-launcher-8942-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@dc72de{HTTP/1.1,[http/1.1]}{127.0.0.1:57992}
[junit4] 2> 2765199 INFO (jetty-launcher-8942-thread-1) [ ] o.e.j.s.Server Started @2765248ms
[junit4] 2> 2765199 INFO (jetty-launcher-8942-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57992}
[junit4] 2> 2765199 ERROR (jetty-launcher-8942-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2765199 INFO (jetty-launcher-8942-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2765199 INFO (jetty-launcher-8942-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 2765199 INFO (jetty-launcher-8942-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2765199 INFO (jetty-launcher-8942-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2765199 INFO (jetty-launcher-8942-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-06-28T04:17:25.790Z
[junit4] 2> 2765203 INFO (zkConnectionManagerCallback-8947-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2765204 INFO (jetty-launcher-8942-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2766248 INFO (jetty-launcher-8942-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57988/solr
[junit4] 2> 2766250 INFO (zkConnectionManagerCallback-8951-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2766252 INFO (zkConnectionManagerCallback-8953-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2766338 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2766338 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57992_solr
[junit4] 2> 2766339 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.c.Overseer Overseer (id=72139373483655171-127.0.0.1:57992_solr-n_0000000000) starting
[junit4] 2> 2766347 INFO (zkConnectionManagerCallback-8960-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2766349 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57988/solr ready
[junit4] 2> 2766350 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57992_solr
[junit4] 2> 2766350 DEBUG (OverseerAutoScalingTriggerThread-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 2766350 DEBUG (OverseerAutoScalingTriggerThread-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 2766351 INFO (OverseerStateUpdate-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2766351 DEBUG (OverseerAutoScalingTriggerThread-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 2766351 DEBUG (OverseerAutoScalingTriggerThread-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2766352 INFO (zkCallback-8959-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2766354 DEBUG (OverseerAutoScalingTriggerThread-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:57992_solr]
[junit4] 2> 2766354 DEBUG (OverseerAutoScalingTriggerThread-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2766354 DEBUG (OverseerAutoScalingTriggerThread-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2766354 DEBUG (ScheduledTrigger-11156-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2766399 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2766450 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57992.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13bc29a
[junit4] 2> 2766463 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57992.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13bc29a
[junit4] 2> 2766463 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57992.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13bc29a
[junit4] 2> 2766464 INFO (jetty-launcher-8942-thread-1) [n:127.0.0.1:57992_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerTest_C4AD92A428E80998-001\tempDir-001\node1\.
[junit4] 2> 2766486 INFO (zkConnectionManagerCallback-8963-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2766492 INFO (zkConnectionManagerCallback-8968-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2766494 INFO (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2766495 INFO (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57988/solr ready
[junit4] 2> 2766545 INFO (TEST-ScheduledTriggerTest.testIgnoredEvent-seed#[C4AD92A428E80998]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testIgnoredEvent
[junit4] 2> 2766552 WARN (TEST-ScheduledTriggerTest.testIgnoredEvent-seed#[C4AD92A428E80998]) [ ] o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at scheduled time: 2018-06-27T04:17:27.136Z. Now: 2018-06-28T04:17:27.140Z
[junit4] 2> 2766553 INFO (TEST-ScheduledTriggerTest.testIgnoredEvent-seed#[C4AD92A428E80998]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testIgnoredEvent
[junit4] 2> 2766595 INFO (TEST-ScheduledTriggerTest.testTrigger-seed#[C4AD92A428E80998]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTrigger
[junit4] 2> 2767359 DEBUG (ScheduledTrigger-11156-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2768360 DEBUG (ScheduledTrigger-11156-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2769361 DEBUG (ScheduledTrigger-11156-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2770362 DEBUG (ScheduledTrigger-11156-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2771363 DEBUG (ScheduledTrigger-11156-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2772363 DEBUG (ScheduledTrigger-11156-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2772598 WARN (TEST-ScheduledTriggerTest.testTrigger-seed#[C4AD92A428E80998]) [ ] o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at scheduled time: 2018-06-28T04:17:30.186Z. Now: 2018-06-28T04:17:33.185Z
[junit4] 2> 2773365 DEBUG (ScheduledTrigger-11156-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2774366 DEBUG (ScheduledTrigger-11156-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2775367 DEBUG (ScheduledTrigger-11156-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 2775598 WARN (TEST-ScheduledTriggerTest.testTrigger-seed#[C4AD92A428E80998]) [ ] o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at scheduled time: 2018-06-28T04:17:33.186Z. Now: 2018-06-28T04:17:36.185Z
[junit4] 2> 2775598 INFO (TEST-ScheduledTriggerTest.testTrigger-seed#[C4AD92A428E80998]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerTest -Dtests.method=testTrigger -Dtests.seed=C4AD92A428E80998 -Dtests.slow=true -Dtests.locale=ga -Dtests.timezone=America/Virgin -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 9.05s J0 | ScheduledTriggerTest.testTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<3> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([C4AD92A428E80998:A766A426B1277AB5]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerTest.scheduledTriggerTest(ScheduledTriggerTest.java:112)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerTest.testTrigger(ScheduledTriggerTest.java:65)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2775601 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1004a60c2d00006, likely client has closed socket
[junit4] 2> 2775604 INFO (jetty-closer-8943-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@dc72de{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2775605 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=3819199
[junit4] 2> 2775605 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2775605 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f8797d: rootName = solr_57992, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@11b7882
[junit4] 2> 2775609 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2775609 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@256b9f: rootName = solr_57992, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@ef755
[junit4] 2> 2775611 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2775611 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@17c174b: rootName = solr_57992, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1251d3e
[junit4] 2> 2775612 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:57992_solr
[junit4] 2> 2775613 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2775613 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2775613 INFO (jetty-closer-8943-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72139373483655171-127.0.0.1:57992_solr-n_0000000000) closing
[junit4] 2> 2775615 WARN (OverseerAutoScalingTriggerThread-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2775616 INFO (zkCallback-8959-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 2775616 DEBUG (jetty-closer-8943-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 2775616 INFO (zkCallback-8952-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 2775616 DEBUG (jetty-closer-8943-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 2775616 DEBUG (jetty-closer-8943-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 2775616 DEBUG (jetty-closer-8943-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 2775616 INFO (OverseerStateUpdate-72139373483655171-127.0.0.1:57992_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:57992_solr
[junit4] 2> 2775617 DEBUG (jetty-closer-8943-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 2775617 DEBUG (jetty-closer-8943-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 2775619 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1004a60c2d00004, likely client has closed socket
[junit4] 2> 2775621 INFO (jetty-closer-8943-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b9ff9{/solr,null,UNAVAILABLE}
[junit4] 2> 2775622 INFO (jetty-closer-8943-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2775623 ERROR (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 2775623 INFO (SUITE-ScheduledTriggerTest-seed#[C4AD92A428E80998]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57988 57988
[junit4] 2> 2775625 INFO (Thread-4642) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57988 57988
[junit4] 2> 2775629 WARN (Thread-4642) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerTest_C4AD92A428E80998-001
[junit4] 2> Jun 28, 2018 4:17:36 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 4 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6db2c7), locale=ga, timezone=America/Virgin
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_172 (32-bit)/cpus=3,threads=1,free=200140584,total=513015808
[junit4] 2> NOTE: All tests run in this JVM: [DirectUpdateHandlerTest, JSONWriterTest, TestPayloadScoreQParserPlugin, TestCollationFieldDocValues, MetricsHistoryHandlerTest, IgnoreLargeDocumentProcessorFactoryTest, ShardRoutingTest, PrimUtilsTest, HdfsTlogReplayBufferedWhileIndexingTest, TestExecutePlanAction, SpellCheckComponentTest, NotRequiredUniqueKeyTest, TestPointFields, TestTrieFacet, DirectSolrSpellCheckerTest, DistributedFacetExistsSmallTest, TestScoreJoinQPNoScore, TestSimpleQParserPlugin, TestSolr4Spatial, TestDistributedSearch, BlockJoinFacetDistribTest, DistributedTermsComponentTest, TestSha256AuthenticationProvider, ExplicitHLLTest, NodeLostTriggerTest, TestSolrCloudWithSecureImpersonation, TestFieldTypeCollectionResource, TestStressVersions, TestSolrCoreParser, TestLargeCluster, JvmMetricsTest, ResourceLoaderTest, TestSystemCollAutoCreate, TestRangeQuery, TestMergePolicyConfig, TestSolrCloudWithDelegationTokens, TestMultiWordSynonyms, TestCodecSupport, CheckHdfsIndexTest, DefaultValueUpdateProcessorTest, SolrSlf4jReporterTest, CdcrUpdateLogTest, SimpleMLTQParserTest, LeaderElectionTest, CurrencyFieldTypeTest, OverseerCollectionConfigSetProcessorTest, V2StandaloneTest, ConcurrentCreateRoutedAliasTest, SolrMetricReporterTest, TestInfoStreamLogging, HdfsChaosMonkeyNothingIsSafeTest, SystemInfoHandlerTest, BigEndianAscendingWordSerializerTest, TestFieldCacheSort, NodeAddedTriggerTest, TestSimDistributedQueue, NumberUtilsTest, TestManagedStopFilterFactory, TestFieldCacheVsDocValues, TestSubQueryTransformer, TestInPlaceUpdatesStandalone, TestSerializedLuceneMatchVersion, TestFieldCache, UUIDUpdateProcessorFallbackTest, TestJmxIntegration, DistributedQueryComponentOptimizationTest, TestSearchPerf, PluginInfoTest, TestSchemaNameResource, TestClassicSimilarityFactory, TestGeoJSONResponseWriter, TestUnInvertedFieldException, TestZkChroot, TestCustomSort, TestNumericRangeQuery32, TestCloudConsistency, ActionThrottleTest, DocValuesNotIndexedTest, CdcrReplicationHandlerTest, TestSort, TestCloudJSONFacetSKG, HighlighterConfigTest, UpdateRequestProcessorFactoryTest, RollingRestartTest, TestRawTransformer, ConfigureRecoveryStrategyTest, TermsComponentTest, SyncSliceTest, ScriptEngineTest, UUIDFieldTest, SolrCloudExampleTest, HdfsSyncSliceTest, ChaosMonkeySafeLeaderTest, TestTestInjection, SecurityConfHandlerTest, ConvertedLegacyTest, SpatialFilterTest, TestSolrCLIRunExample, LeaderElectionContextKeyTest, TolerantUpdateProcessorTest, DOMUtilTest, SchemaApiFailureTest, UninvertDocValuesMergePolicyTest, TestCoreContainer, SolrCoreMetricManagerTest, AtomicUpdateProcessorFactoryTest, SolrGangliaReporterTest, OutputWriterTest, QueryParsingTest, TestBackupRepositoryFactory, OpenExchangeRatesOrgProviderTest, DistanceFunctionTest, ZkControllerTest, TestQuerySenderListener, TestSolrDeletionPolicy1, BlockDirectoryTest, TestSizeLimitedDistributedMap, TestRTimerTree, TestLegacyFieldCache, DocValuesMultiTest, TestElisionMultitermQuery, HdfsAutoAddReplicasIntegrationTest, TestReload, FullSolrCloudDistribCmdsTest, TestPhraseSuggestions, AnalyticsQueryTest, TestSolrIndexConfig, TestRequestForwarding, OverseerRolesTest, OutOfBoxZkACLAndCredentialsProvidersTest, SoftAutoCommitTest, TestLeaderInitiatedRecoveryThread, TestDFRSimilarityFactory, TestUseDocValuesAsStored, TestFastLRUCache, SuggestComponentContextFilterQueryTest, TestDirectoryFactory, TriLevelCompositeIdRoutingTest, ReplicationFactorTest, TestFaceting, SolrIndexSplitterTest, VMParamsZkACLAndCredentialsProvidersTest, TestUpdate, TestLMDirichletSimilarityFactory, TestDistribIDF, ClassificationUpdateProcessorFactoryTest, TestComplexPhraseQParserPlugin, CollectionStateFormat2Test, TriggerCooldownIntegrationTest, TestWordDelimiterFilterFactory, TestBinaryResponseWriter, MBeansHandlerTest, ComputePlanActionTest, TestLuceneIndexBackCompat, TestXmlQParser, ConnectionManagerTest, DateRangeFieldTest, HdfsDirectoryFactoryTest, CoreAdminOperationTest, TestReqParamsAPI, TestPartialUpdateDeduplication, TestRemoteStreaming, SystemLogListenerTest, XsltUpdateRequestHandlerTest, SolrInfoBeanTest, TestBulkSchemaAPI, SolrPluginUtilsTest, ProtectedTermFilterFactoryTest, SearchRateTriggerIntegrationTest, TestCustomDocTransformer, TestFuzzyAnalyzedSuggestions, TestRandomRequestDistribution, SearchHandlerTest, TestSimpleTextCodec, TestCloudPseudoReturnFields, TemplateUpdateProcessorTest, TestEmbeddedSolrServerConstructors, HdfsRecoveryZkTest, BaseCdcrDistributedZkTest, TestTolerantUpdateProcessorRandomCloud, FieldAnalysisRequestHandlerTest, DeleteLastCustomShardedReplicaTest, TestLuceneMatchVersion, TestReRankQParserPlugin, TestFilteredDocIdSet, WordBreakSolrSpellCheckerTest, TestPerFieldSimilarityWithDefaultOverride, AnalysisAfterCoreReloadTest, TestXIncludeConfig, ConcurrentDeleteAndCreateCollectionTest, TestCloudSearcherWarming, TestConfigOverlay, TestCorePropertiesReload, TestManagedSchema, DisMaxRequestHandlerTest, DistributedIntervalFacetingTest, EchoParamsTest, MinimalSchemaTest, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedGrouping, TestDistributedMissingSort, TestGroupingSearch, TestHighlightDedupGrouping, TestJoin, TestRandomDVFaceting, TestRandomFaceting, TestSimpleTrackingShardHandler, TestSolrCoreProperties, TestTolerantSearch, TestTrie, PathHierarchyTokenizerFactoryTest, TestJettySolrRunner, ConnectionReuseTest, AliasIntegrationTest, BasicDistributedZkTest, BasicZkTest, ChaosMonkeyNothingIsSafeTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DeleteInactiveReplicaTest, DeleteReplicaTest, DistribDocExpirationUpdateProcessorTest, HttpPartitionOnCommitTest, HttpPartitionTest, LIROnShardRestartTest, LIRRollingUpdatesTest, MetricsHistoryIntegrationTest, PeerSyncReplicationTest, RecoveryAfterSoftCommitTest, RemoteQueryErrorTest, ReplaceNodeTest, SliceStateTest, SolrCLIZkUtilsTest, TestCloudInspectUtil, TestCloudJSONFacetJoinDomain, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDeleteCollectionOnDownNodes, TestDistributedMap, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestHashPartitioner, TestLeaderElectionZkExpiry, TestMiniSolrCloudClusterSSL, TestPullReplica, TestRandomFlRTGCloud, TestSegmentSorting, TestShortCircuitedRequests, TestStressInPlaceUpdates, TestStressLiveNodes, TestTlogReplica, TestTolerantUpdateProcessorCloud, ZkNodePropsTest, ZkShardTermsTest, AssignTest, CollectionsAPIAsyncDistributedZkTest, CollectionsAPIDistributedZkTest, TestReplicaProperties, AutoAddReplicasPlanActionTest, AutoScalingHandlerTest, IndexSizeTriggerTest, MetricTriggerIntegrationTest, MetricTriggerTest, NodeAddedTriggerIntegrationTest, ScheduledMaintenanceTriggerTest, ScheduledTriggerIntegrationTest, ScheduledTriggerTest]
[junit4] Completed [534/820 (1!)] on J0 in 10.83s, 2 tests, 1 failure <<< FAILURES!
[...truncated 1629 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.MathExpressionTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\init-core-data-001
[junit4] 2> 64547 INFO (SUITE-MathExpressionTest-seed#[CD5D7010F654AC78]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 64548 INFO (SUITE-MathExpressionTest-seed#[CD5D7010F654AC78]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 64566 INFO (SUITE-MathExpressionTest-seed#[CD5D7010F654AC78]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 64567 INFO (SUITE-MathExpressionTest-seed#[CD5D7010F654AC78]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\tempDir-001
[junit4] 2> 64567 INFO (SUITE-MathExpressionTest-seed#[CD5D7010F654AC78]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 64571 INFO (Thread-62) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 64571 INFO (Thread-62) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 64578 ERROR (Thread-62) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 64668 INFO (SUITE-MathExpressionTest-seed#[CD5D7010F654AC78]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:62727
[junit4] 2> 64673 INFO (zkConnectionManagerCallback-134-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 64709 INFO (jetty-launcher-131-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 64711 INFO (jetty-launcher-131-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 64711 INFO (jetty-launcher-131-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 64711 INFO (jetty-launcher-131-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 64711 INFO (jetty-launcher-131-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7a5ae7{/solr,null,AVAILABLE}
[junit4] 2> 64711 INFO (jetty-launcher-131-thread-4) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 64711 INFO (jetty-launcher-131-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 64711 INFO (jetty-launcher-131-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 64711 INFO (jetty-launcher-131-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 64711 INFO (jetty-launcher-131-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1cc8784{/solr,null,AVAILABLE}
[junit4] 2> 64714 INFO (jetty-launcher-131-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 64714 INFO (jetty-launcher-131-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 64714 INFO (jetty-launcher-131-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 64714 INFO (jetty-launcher-131-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 64714 INFO (jetty-launcher-131-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@188ee79{/solr,null,AVAILABLE}
[junit4] 2> 64727 INFO (jetty-launcher-131-thread-3) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 64727 INFO (jetty-launcher-131-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 64727 INFO (jetty-launcher-131-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 64727 INFO (jetty-launcher-131-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 64727 INFO (jetty-launcher-131-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19942d6{/solr,null,AVAILABLE}
[junit4] 2> 64749 INFO (jetty-launcher-131-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1d11730{SSL,[ssl, http/1.1]}{127.0.0.1:62732}
[junit4] 2> 64749 INFO (jetty-launcher-131-thread-4) [ ] o.e.j.s.Server Started @64783ms
[junit4] 2> 64749 INFO (jetty-launcher-131-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62732}
[junit4] 2> 64749 ERROR (jetty-launcher-131-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 64749 INFO (jetty-launcher-131-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 64749 INFO (jetty-launcher-131-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 64749 INFO (jetty-launcher-131-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 64750 INFO (jetty-launcher-131-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 64750 INFO (jetty-launcher-131-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-06-28T04:30:01.807Z
[junit4] 2> 64751 INFO (jetty-launcher-131-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@384c78{SSL,[ssl, http/1.1]}{127.0.0.1:62731}
[junit4] 2> 64751 INFO (jetty-launcher-131-thread-1) [ ] o.e.j.s.Server Started @64785ms
[junit4] 2> 64751 INFO (jetty-launcher-131-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62731}
[junit4] 2> 64753 ERROR (jetty-launcher-131-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 64753 INFO (jetty-launcher-131-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 64753 INFO (jetty-launcher-131-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 64753 INFO (jetty-launcher-131-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 64753 INFO (jetty-launcher-131-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 64753 INFO (jetty-launcher-131-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-06-28T04:30:01.810Z
[junit4] 2> 64758 INFO (jetty-launcher-131-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2fec76{SSL,[ssl, http/1.1]}{127.0.0.1:62734}
[junit4] 2> 64758 INFO (jetty-launcher-131-thread-3) [ ] o.e.j.s.Server Started @64793ms
[junit4] 2> 64758 INFO (jetty-launcher-131-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62734}
[junit4] 2> 64759 ERROR (jetty-launcher-131-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 64759 INFO (jetty-launcher-131-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 64759 INFO (jetty-launcher-131-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 64759 INFO (jetty-launcher-131-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 64759 INFO (jetty-launcher-131-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 64759 INFO (jetty-launcher-131-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-06-28T04:30:01.816Z
[junit4] 2> 64762 INFO (jetty-launcher-131-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@a58de7{SSL,[ssl, http/1.1]}{127.0.0.1:62733}
[junit4] 2> 64762 INFO (jetty-launcher-131-thread-2) [ ] o.e.j.s.Server Started @64797ms
[junit4] 2> 64762 INFO (jetty-launcher-131-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62733}
[junit4] 2> 64762 ERROR (jetty-launcher-131-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 64762 INFO (jetty-launcher-131-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 64762 INFO (jetty-launcher-131-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 64763 INFO (jetty-launcher-131-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 64763 INFO (jetty-launcher-131-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 64763 INFO (jetty-launcher-131-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-06-28T04:30:01.820Z
[junit4] 2> 64775 INFO (zkConnectionManagerCallback-136-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 64777 INFO (zkConnectionManagerCallback-142-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 64777 INFO (zkConnectionManagerCallback-138-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 64778 INFO (zkConnectionManagerCallback-140-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 64779 INFO (jetty-launcher-131-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 64779 INFO (jetty-launcher-131-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 64782 INFO (jetty-launcher-131-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 64782 INFO (jetty-launcher-131-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 65226 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1004a6c4bc40001, likely client has closed socket
[junit4] 2> 65387 INFO (jetty-launcher-131-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62727/solr
[junit4] 2> 65459 INFO (jetty-launcher-131-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62727/solr
[junit4] 2> 65480 INFO (jetty-launcher-131-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62727/solr
[junit4] 2> 65569 INFO (zkConnectionManagerCallback-146-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65579 INFO (zkConnectionManagerCallback-150-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65579 INFO (zkConnectionManagerCallback-154-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65616 INFO (zkConnectionManagerCallback-156-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65619 INFO (zkConnectionManagerCallback-160-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65619 INFO (zkConnectionManagerCallback-158-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65698 WARN (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 65700 WARN (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 65715 WARN (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 65906 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 65906 INFO (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 65907 INFO (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 65908 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62733_solr
[junit4] 2> 65909 INFO (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62731_solr
[junit4] 2> 65910 INFO (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62734_solr
[junit4] 2> 65910 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.c.Overseer Overseer (id=72139423025987594-127.0.0.1:62733_solr-n_0000000000) starting
[junit4] 2> 66008 INFO (zkCallback-159-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 66036 INFO (zkConnectionManagerCallback-181-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 66037 INFO (zkCallback-157-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 66058 INFO (zkConnectionManagerCallback-180-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 66064 INFO (zkConnectionManagerCallback-171-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 66064 INFO (zkCallback-155-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 66066 INFO (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 66068 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 66092 INFO (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 66095 INFO (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62727/solr ready
[junit4] 2> 66095 INFO (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 66139 INFO (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62734.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 66142 INFO (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62734.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 66143 INFO (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62734.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 66144 INFO (jetty-launcher-131-thread-3) [n:127.0.0.1:62734_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\tempDir-001\node4\.
[junit4] 2> 66145 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62727/solr ready
[junit4] 2> 66158 INFO (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62727/solr ready
[junit4] 2> 66158 INFO (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 66183 INFO (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62731.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 66186 INFO (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62731.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 66187 INFO (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62731.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 66188 INFO (jetty-launcher-131-thread-1) [n:127.0.0.1:62731_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\tempDir-001\node1\.
[junit4] 2> 66209 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 66209 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62733_solr
[junit4] 2> 66222 INFO (zkCallback-159-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 66222 INFO (zkCallback-155-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 66222 INFO (zkCallback-157-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 66261 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 66285 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62733.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 66291 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62733.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 66292 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62733.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 66293 INFO (jetty-launcher-131-thread-2) [n:127.0.0.1:62733_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\tempDir-001\node2\.
[junit4] 2> 66338 INFO (zkCallback-179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 66339 INFO (zkCallback-178-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 66341 INFO (zkCallback-170-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 67062 INFO (jetty-launcher-131-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62727/solr
[junit4] 2> 67065 INFO (zkConnectionManagerCallback-188-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 67068 INFO (zkConnectionManagerCallback-190-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 67079 WARN (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 67081 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 67084 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 67087 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 67087 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62732_solr
[junit4] 2> 67089 INFO (zkCallback-179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 67089 INFO (zkCallback-178-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 67089 INFO (zkCallback-157-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 67089 INFO (zkCallback-159-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 67089 INFO (zkCallback-155-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 67089 INFO (zkCallback-170-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 67091 INFO (zkCallback-189-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 67139 INFO (zkConnectionManagerCallback-197-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 67141 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 67142 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62727/solr ready
[junit4] 2> 67142 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 67162 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62732.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 67167 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62732.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 67167 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62732.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 67167 INFO (jetty-launcher-131-thread-4) [n:127.0.0.1:62732_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\tempDir-001\node3\.
[junit4] 2> 67198 INFO (zkConnectionManagerCallback-200-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 67211 INFO (zkConnectionManagerCallback-205-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 67213 INFO (SUITE-MathExpressionTest-seed#[CD5D7010F654AC78]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 67214 INFO (SUITE-MathExpressionTest-seed#[CD5D7010F654AC78]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62727/solr ready
[junit4] 2> 67341 INFO (qtp11904678-768) [n:127.0.0.1:62732_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 67347 INFO (OverseerThreadFactory-512-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 67460 INFO (OverseerStateUpdate-72139423025987594-127.0.0.1:62733_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:62731/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 67464 INFO (OverseerStateUpdate-72139423025987594-127.0.0.1:62733_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:62732/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 67683 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr x:collection1_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 67719 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr x:collection1_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 67720 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr x:collection1_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 68714 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 68723 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 68739 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 68751 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 68770 WARN (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 68772 WARN (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 68772 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 68772 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 68773 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62732.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 68773 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 68773 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\tempDir-001\node3\collection1_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\tempDir-001\node3\.\collection1_shard2_replica_n2\data\]
[junit4] 2> 68804 WARN (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 68804 WARN (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 68804 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 68804 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 68805 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62731.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed55e5
[junit4] 2> 68805 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 68805 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\tempDir-001\node1\collection1_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.MathExpressionTest_CD5D7010F654AC78-001\tempDir-001\node1\.\collection1_shard1_replica_n1\data\]
[junit4] 2> 68886 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 68886 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 68886 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 68886 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 68889 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 68889 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 68889 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 68889 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 68890 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@16af398[collection1_shard2_replica_n2] main]
[junit4] 2> 68890 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d7f09a[collection1_shard1_replica_n1] main]
[junit4] 2> 68892 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 68892 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 68892 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 68892 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 68893 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 68893 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604489268114227200
[junit4] 2> 68893 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 68893 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604489268114227200
[junit4] 2> 68895 INFO (searcherExecutor-526-thread-1-processing-n:127.0.0.1:62731_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@1d7f09a[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 68895 INFO (searcherExecutor-525-thread-1-processing-n:127.0.0.1:62732_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@16af398[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 68902 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 68902 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 68905 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 68905 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 68905 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:62732/solr/collection1_shard2_replica_n2/
[junit4] 2> 68905 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 68905 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 68905 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:62731/solr/collection1_shard1_replica_n1/
[junit4] 2> 68906 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 68906 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 68906 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy https://127.0.0.1:62732/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 68906 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 68906 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:62731/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 68906 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 68910 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:62732/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 68910 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:62731/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 69064 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 69064 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 69070 INFO (qtp18296004-757) [n:127.0.0.1:62731_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1350
[junit4] 2> 69071 INFO (qtp11904678-765) [n:127.0.0.1:62732_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1389
[junit4] 2> 69075 INFO (qtp11904678-768) [n:127.0.0.1:62732_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[...truncated too long message...]
ressionTest.testFuzzyKmeans
[junit4] OK 0.05s J0 | MathExpressionTest.testEbeMultiply
[junit4] OK 0.13s J0 | MathExpressionTest.testAnalyzeEvaluator
[junit4] OK 0.05s J0 | MathExpressionTest.testNormalizeSum
[junit4] OK 0.06s J0 | MathExpressionTest.testAscend
[junit4] OK 0.19s J0 | MathExpressionTest.testConvertEvaluator
[junit4] OK 0.19s J0 | MathExpressionTest.testCopyOfRange
[junit4] OK 0.08s J0 | MathExpressionTest.testMatrixMath
[junit4] OK 0.07s J0 | MathExpressionTest.testZeros
[junit4] OK 0.07s J0 | MathExpressionTest.testMatrix
[junit4] OK 0.06s J0 | MathExpressionTest.testCumulativeProbability
[junit4] OK 0.11s J0 | MathExpressionTest.testRegressAndPredict
[junit4] OK 0.58s J0 | MathExpressionTest.testBetaDistribution
[junit4] OK 0.05s J0 | MathExpressionTest.testCorrMatrix
[junit4] OK 0.07s J0 | MathExpressionTest.testMeanDifference
[junit4] OK 0.12s J0 | MathExpressionTest.testCovariance
[junit4] OK 0.08s J0 | MathExpressionTest.testPercentile
[junit4] OK 0.09s J0 | MathExpressionTest.testZipFDistribution
[junit4] OK 0.04s J0 | MathExpressionTest.testMonteCarloWithVariables
[junit4] OK 0.05s J0 | MathExpressionTest.testMultiKmeans
[junit4] OK 0.04s J0 | MathExpressionTest.testAnova
[junit4] OK 0.07s J0 | MathExpressionTest.testMovingAverage
[junit4] OK 0.05s J0 | MathExpressionTest.testSumSq
[junit4] OK 0.04s J0 | MathExpressionTest.testNestedDoubleTimeDifference
[junit4] OK 0.04s J0 | MathExpressionTest.testMonteCarlo
[junit4] OK 0.16s J0 | MathExpressionTest.testTriangularDistribution
[junit4] OK 0.05s J0 | MathExpressionTest.testMovingMedian
[junit4] OK 0.11s J0 | MathExpressionTest.testPoissonDistribution
[junit4] OK 0.05s J0 | MathExpressionTest.testChiSquareDataSet
[junit4] OK 0.04s J0 | MathExpressionTest.testPlot
[junit4] OK 0.05s J0 | MathExpressionTest.testTimeDifferencingDefinedLag
[junit4] OK 0.33s J0 | MathExpressionTest.testGammaDistribution
[junit4] OK 0.04s J0 | MathExpressionTest.testEbeDivide
[junit4] OK 0.04s J0 | MathExpressionTest.testSetAndGetValue
[junit4] OK 0.05s J0 | MathExpressionTest.testGtestDataSet
[junit4] OK 0.05s J0 | MathExpressionTest.testProbabilityRange
[junit4] OK 0.05s J0 | MathExpressionTest.testMinMaxScale
[junit4] OK 0.04s J0 | MathExpressionTest.testTranspose
[junit4] OK 0.08s J0 | MathExpressionTest.testLength
[junit4] OK 0.04s J0 | MathExpressionTest.testDistance
[junit4] OK 0.04s J0 | MathExpressionTest.testDotProduct
[junit4] OK 0.04s J0 | MathExpressionTest.testMatrixMult
[junit4] OK 0.06s J0 | MathExpressionTest.testGeometricDistribution
[junit4] OK 0.05s J0 | MathExpressionTest.testSpline
[junit4] OK 0.06s J0 | MathExpressionTest.testDescribe
[junit4] OK 0.05s J0 | MathExpressionTest.testMean
[junit4] OK 0.06s J0 | MathExpressionTest.testSumDifference
[junit4] OK 0.05s J0 | MathExpressionTest.testMemsetSize
[junit4] OK 0.07s J0 | MathExpressionTest.testRankTransform
[junit4] OK 0.04s J0 | MathExpressionTest.testCosineSimilarity
[junit4] OK 0.07s J0 | MathExpressionTest.testMemsetTimeSeries
[junit4] OK 0.06s J0 | MathExpressionTest.testEnumeratedDistribution
[junit4] OK 0.04s J0 | MathExpressionTest.testTimeDifferencingDefaultLag
[junit4] OK 0.05s J0 | MathExpressionTest.testTtest
[junit4] OK 0.09s J0 | MathExpressionTest.testBinomialDistribution
[junit4] OK 0.04s J0 | MathExpressionTest.testPrecision
[junit4] OK 0.04s J0 | MathExpressionTest.testStandardize
[junit4] OK 0.27s J0 | MathExpressionTest.testLogNormalDistribution
[junit4] OK 0.08s J0 | MathExpressionTest.testConvolution
[junit4] OK 0.08s J0 | MathExpressionTest.testScale
[junit4] OK 0.05s J0 | MathExpressionTest.testOnes
[junit4] OK 0.35s J0 | MathExpressionTest.testWeibullDistribution
[junit4] OK 0.10s J0 | MathExpressionTest.testFinddelay
[junit4] OK 0.06s J0 | MathExpressionTest.testMemset
[junit4] OK 0.05s J0 | MathExpressionTest.testIntegrate
[junit4] OK 0.06s J0 | MathExpressionTest.testPolyfit
[junit4] OK 0.07s J0 | MathExpressionTest.testValueAt
[junit4] OK 0.05s J0 | MathExpressionTest.testFFT
[junit4] OK 0.08s J0 | MathExpressionTest.testBicubicSpline
[junit4] OK 0.10s J0 | MathExpressionTest.testCopyOf
[junit4] OK 0.05s J0 | MathExpressionTest.testFreqTable
[junit4] OK 0.05s J0 | MathExpressionTest.testLerp
[junit4] OK 0.12s J0 | MathExpressionTest.testCorrelationStream
[junit4] OK 0.12s J0 | MathExpressionTest.testKnn
[junit4] OK 0.07s J0 | MathExpressionTest.testHist
[junit4] OK 0.06s J0 | MathExpressionTest.testMarkovChain
[junit4] OK 0.07s J0 | MathExpressionTest.testTermVectors
[junit4] OK 0.05s J0 | MathExpressionTest.testEbeAdd
[junit4] OK 0.05s J0 | MathExpressionTest.testKmeans
[junit4] OK 0.05s J0 | MathExpressionTest.testHarmonicFit
[junit4] OK 0.05s J0 | MathExpressionTest.testAkima
[junit4] OK 0.05s J0 | MathExpressionTest.testMannWhitney
[junit4] OK 0.05s J0 | MathExpressionTest.testEbeSubtract
[junit4] OK 0.06s J0 | MathExpressionTest.testDistributions
[junit4] OK 0.06s J0 | MathExpressionTest.testUniformIntegerDistribution
[junit4] OK 0.05s J0 | MathExpressionTest.testUnitize
[junit4] OK 0.09s J0 | MathExpressionTest.testReverse
[junit4] OK 0.07s J0 | MathExpressionTest.testExponentialMovingAverage
[junit4] OK 0.06s J0 | MathExpressionTest.testOlsRegress
[junit4] OK 0.07s J0 | MathExpressionTest.testBinomialCoefficient
[junit4] OK 0.09s J0 | MathExpressionTest.testNorms
[junit4] OK 0.05s J0 | MathExpressionTest.testLoess
[junit4] OK 0.06s J0 | MathExpressionTest.testPrimes
[junit4] OK 0.04s J0 | MathExpressionTest.testArray
[junit4] OK 0.04s J0 | MathExpressionTest.testCovMatrix
[junit4] Completed [5/5] on J0 in 14.64s, 97 tests
[junit4]
[junit4] JVM J0: 1.00 .. 83.38 = 82.38s
[junit4] JVM J1: 0.79 .. 70.50 = 69.72s
[junit4] Execution time total: 1 minute 23 seconds
[junit4] Tests summary: 5 suites, 485 tests
[junit4] Could not remove temporary path: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp])
[junit4] Could not remove temporary path: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp])
[echo] 5 slowest tests:
[junit4:tophints] 75.90s | org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4:tophints] 44.19s | org.apache.solr.common.cloud.TestCloudCollectionsListeners
[junit4:tophints] 44.01s | org.apache.solr.client.solrj.request.SchemaTest
[junit4:tophints] 40.69s | org.apache.solr.client.solrj.impl.CloudSolrClientTest
[junit4:tophints] 28.07s | org.apache.solr.client.solrj.io.graph.GraphExpressionTest
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 1 minute 25 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.MathExpressionTest
[repro] 0/5 failed: org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd
[repro] 1/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=d2ac32368ee5547a995da83ccd82b96960902adf, workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows
[WARNINGS] Computing warning deltas based on reference build #7384
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2