Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/289/
Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseG1GC
5 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.lucene.store.TestHardLinkCopyDirectoryWrapper
Error Message:
Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001\testThreadSafety-001:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001\testThreadSafety-001
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of
attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001\testThreadSafety-001:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001\testThreadSafety-001
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001
at __randomizedtesting.SeedInfo.seed([1781D893AE08CF23]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
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.lang.Thread.run(Thread.java:748)
FAILED:
org.apache.lucene.replicator.IndexReplicationClientTest.testConsistencyOnExceptions
Error Message:
Captured an uncaught exception in thread: Thread[id=18,
name=ReplicationThread-index, state=RUNNABLE,
group=TGRP-IndexReplicationClientTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=18, name=ReplicationThread-index,
state=RUNNABLE, group=TGRP-IndexReplicationClientTest]
at
__randomizedtesting.SeedInfo.seed([E454D962E14189BF:6BDA3EC2F32D7A40]:0)
Caused by: java.lang.AssertionError: handler failed too many times: -1
at __randomizedtesting.SeedInfo.seed([E454D962E14189BF]:0)
at
org.apache.lucene.replicator.IndexReplicationClientTest$4.handleUpdateException(IndexReplicationClientTest.java:304)
at
org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.BasicZkTest
Error Message:
Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq\segments_1:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq\segments_1
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of
attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq\segments_1:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq\segments_1
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001
at __randomizedtesting.SeedInfo.seed([64582DF60B061A85]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
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.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.TestSkipOverseerOperations.testSkipDownOperations
Error Message:
Error from server at http://127.0.0.1:63492/solr: Timed out waiting to see all
replicas: [collection2_shard1_replica_n1, collection2_shard1_replica_n2,
collection2_shard2_replica_n3, collection2_shard2_replica_n4] in cluster state.
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:63492/solr: Timed out waiting to see all
replicas: [collection2_shard1_replica_n1, collection2_shard1_replica_n2,
collection2_shard2_replica_n3, collection2_shard2_replica_n4] in cluster state.
at
__randomizedtesting.SeedInfo.seed([64582DF60B061A85:A3CA5FB0F64AA153]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1096)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:875)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:808)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:183)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:200)
at
org.apache.solr.cloud.TestSkipOverseerOperations.testSkipDownOperations(TestSkipOverseerOperations.java:91)
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.TestSkipOverseerOperations.testSkipLeaderOperations
Error Message:
Error from server at http://127.0.0.1:63492/solr: Could not fully create
collection: collection1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:63492/solr: Could not fully create collection:
collection1
at
__randomizedtesting.SeedInfo.seed([64582DF60B061A85:94B2FE0AD4256AEF]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1096)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:875)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:808)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:183)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:200)
at
org.apache.solr.cloud.TestSkipOverseerOperations.testSkipLeaderOperations(TestSkipOverseerOperations.java:56)
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 7920 lines...]
[junit4] Suite: org.apache.lucene.store.TestHardLinkCopyDirectoryWrapper
[junit4] IGNOR/A 0.01s J1 |
TestHardLinkCopyDirectoryWrapper.testPendingDeletions
[junit4] > Assumption #1: we can only install VirusCheckingFS on an
FSDirectory
[junit4] IGNOR/A 0.02s J1 |
TestHardLinkCopyDirectoryWrapper.testCopyHardLinks
[junit4] > Assumption #1: hardlinks are not supported
[junit4] IGNOR/A 0.00s J1 |
TestHardLinkCopyDirectoryWrapper.testFsyncDoesntCreateNewFiles
[junit4] > Assumption #1: test only works for FSDirectory subclasses
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=902, maxMBSortInHeap=6.948792967056098,
sim=RandomSimilarity(queryNorm=false): {}, locale=es-AR, timezone=PLT
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144
(64-bit)/cpus=3,threads=1,free=40178032,total=100663296
[junit4] 2> NOTE: All tests run in this JVM:
[TestHardLinkCopyDirectoryWrapper]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestHardLinkCopyDirectoryWrapper -Dtests.seed=1781D893AE08CF23
-Dtests.slow=true -Dtests.locale=es-AR -Dtests.timezone=PLT
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | TestHardLinkCopyDirectoryWrapper (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-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001\testThreadSafety-001:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001\testThreadSafety-001
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestHardLinkCopyDirectoryWrapper_1781D893AE08CF23-001
[junit4] > at
__randomizedtesting.SeedInfo.seed([1781D893AE08CF23]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [2/13 (1!)] on J1 in 4.64s, 43 tests, 1 error, 3 skipped
<<< FAILURES!
[...truncated 753 lines...]
[junit4] Suite: org.apache.lucene.replicator.IndexReplicationClientTest
[junit4] 2> Nov 05, 2017 7:14:02 AM
com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler
uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread:
Thread[ReplicationThread-index,5,TGRP-IndexReplicationClientTest]
[junit4] 2> java.lang.AssertionError: handler failed too many times: -1
[junit4] 2> at
__randomizedtesting.SeedInfo.seed([E454D962E14189BF]:0)
[junit4] 2> at
org.apache.lucene.replicator.IndexReplicationClientTest$4.handleUpdateException(IndexReplicationClientTest.java:304)
[junit4] 2> at
org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=IndexReplicationClientTest
-Dtests.method=testConsistencyOnExceptions -Dtests.seed=E454D962E14189BF
-Dtests.slow=true -Dtests.locale=ro -Dtests.timezone=Pacific/Rarotonga
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 1.28s J1 |
IndexReplicationClientTest.testConsistencyOnExceptions <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=18, name=ReplicationThread-index,
state=RUNNABLE, group=TGRP-IndexReplicationClientTest]
[junit4] > at
__randomizedtesting.SeedInfo.seed([E454D962E14189BF:6BDA3EC2F32D7A40]:0)
[junit4] > Caused by: java.lang.AssertionError: handler failed too many
times: -1
[junit4] > at
__randomizedtesting.SeedInfo.seed([E454D962E14189BF]:0)
[junit4] > at
org.apache.lucene.replicator.IndexReplicationClientTest$4.handleUpdateException(IndexReplicationClientTest.java:304)
[junit4] > at
org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1041, maxMBSortInHeap=5.797589262081328,
sim=RandomSimilarity(queryNorm=false): {}, locale=ro, timezone=Pacific/Rarotonga
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144
(64-bit)/cpus=3,threads=1,free=60005032,total=100663296
[junit4] 2> NOTE: All tests run in this JVM:
[IndexAndTaxonomyRevisionTest, IndexRevisionTest, IndexReplicationClientTest]
[junit4] Completed [3/9 (1!)] on J1 in 1.97s, 4 tests, 1 error <<< FAILURES!
[...truncated 3059 lines...]
[junit4] Suite: org.apache.solr.cloud.BasicZkTest
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001
[junit4] 2> 461875 WARN
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.SolrTestCaseJ4
startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 461875 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.SolrTestCaseJ4
Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 461880 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-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> 461880 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.SolrTestCaseJ4
SecureRandom sanity checks: test.solr.allowed.securerandom=null &
java.security.egd=file:/dev/./urandom
[junit4] 2> 461881 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.c.ZkTestServer
STARTING ZK TEST SERVER
[junit4] 2> 461908 INFO (Thread-547) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 461908 INFO (Thread-547) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 461911 ERROR (Thread-547) [ ] 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> 461983 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.c.ZkTestServer
start zk server on port:59163
[junit4] 2> 461998 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f8d36a48a0000, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 462051 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 462053 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 462062 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 462064 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 462067 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 462071 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 462072 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 462075 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 462079 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 462083 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 462085 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 462090 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.SolrTestCaseJ4
####initCore
[junit4] 2> 462092 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 462180 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.2.0
[junit4] 2> 462205 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.s.IndexSchema
[null] Schema name=test
[junit4] 2> 462425 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 462457 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:59163/solr
[junit4] 2> 462461 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f8d36a48a0002, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 462577 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 462578 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 88.99.242.118:8983_
[junit4] 2> 462579 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.c.Overseer Overseer
(id=98952982653698051-88.99.242.118:8983_-n_0000000000) starting
[junit4] 2> 462584 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/88.99.242.118:8983_
[junit4] 2> 462694 INFO
(zkCallback-945-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 462719 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 462724 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 462724 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 462772 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 462779 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 462859 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 462882 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 463018 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 463041 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1, trusted=true
[junit4] 2> 463042 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.88.99.242.118:8983__collection1' (registry
'solr.core.collection1.shard1.88.99.242.118:8983__collection1') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 463042 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 463042 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\]
[junit4] 2> 463051 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=14, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=0.130859375,
floorSegmentMB=1.90234375, forceMergeDeletesPctAllowed=10.306854874369028,
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 463165 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 463165 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 463167 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 463167 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 463167 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=15, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=88.634765625,
floorSegmentMB=1.38671875, forceMergeDeletesPctAllowed=28.10848557363099,
segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 463168 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1cd05210[collection1] main]
[junit4] 2> 463169 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 463180 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 463249 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 463249 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for
spellchecker: default
[junit4] 2> 463249 INFO
(coreLoadExecutor-1402-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1583247723862163456
[junit4] 2> 463250 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for
spellchecker: direct
[junit4] 2> 463250 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for
spellchecker: wordbreak
[junit4] 2> 463250 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for
spellchecker: multipleFields
[junit4] 2> 463251 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for
spellchecker: jarowinkler
[junit4] 2> 463251 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for
spellchecker: external
[junit4] 2> 463253 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for
spellchecker: freq
[junit4] 2> 463254 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for
spellchecker: fqcn
[junit4] 2> 463254 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 463255 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for
spellchecker: perDict
[junit4] 2> 463259 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1cd05210[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 463263 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.SolrTestCaseJ4 ###Starting testBasic
[junit4] 2> 463264 INFO
(coreZkRegister-1394-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
r:88.99.242.118:8983__collection1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 463264 INFO
(coreZkRegister-1394-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
r:88.99.242.118:8983__collection1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 463264 INFO
(coreZkRegister-1394-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
r:88.99.242.118:8983__collection1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 463267 INFO
(coreZkRegister-1394-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
r:88.99.242.118:8983__collection1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://88.99.242.118:8983/collection1/ shard1
[junit4] 2> 463427 WARN
(coreZkRegister-1394-thread-1-processing-n:88.99.242.118:8983_ x:collection1
s:shard1 c:collection1) [n:88.99.242.118:8983_ c:collection1 s:shard1
r:88.99.242.118:8983__collection1 x:collection1] o.a.s.c.ZkController Skipping
recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 463427 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=qlkciyopsbgzyvkylsjhchghjrdf&qt=&start=0&rows=20&version=2.2} hits=0
status=0 QTime=0
[junit4] 2> 463441 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{delete=[42 (-1583247724060344320)]} 0 3
[junit4] 2> 463444 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[42 (1583247724064538624)]} 0 1
[junit4] 2> 463445 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583247724067684352,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 463445 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@690fbda6
commitCommandVersion:1583247724067684352
[junit4] 2> 463573 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.s.SolrIndexSearcher Opening [Searcher@5337874d[collection1] main]
[junit4] 2> 463574 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 463575 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@5337874d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C1)))}
[junit4] 2> 463576 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 131
[junit4] 2> 463584 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:42+AND+val_s:aa\;bb&qt=&start=0&rows=20&version=2.2} hits=1
status=0 QTime=7
[junit4] 2> 463587 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:42+AND+val_s:"aa;bb"&qt=&start=0&rows=20&version=2.2} hits=1
status=0 QTime=2
[junit4] 2> 463588 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:42+AND+val_s:aa&qt=&start=0&rows=20&version=2.2} hits=0 status=0
QTime=0
[junit4] 2> 463590 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{delete=[42 (-1583247724218679296)]} 0 1
[junit4] 2> 463590 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583247724219727872,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 463590 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@690fbda6
commitCommandVersion:1583247724219727872
[junit4] 2> 463597 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c034cf6[collection1] main]
[junit4] 2> 463600 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 463600 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@1c034cf6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 463601 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 11
[junit4] 2> 463602 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:42&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 463613 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[42 (1583247724232310784)]} 0 10
[junit4] 2> 463613 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[42 (1583247724243845120)]} 0 0
[junit4] 2> 463614 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583247724244893696,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 463614 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@690fbda6
commitCommandVersion:1583247724244893696
[junit4] 2> 463667 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e843891[collection1] main]
[junit4] 2> 463668 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 463669 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@4e843891[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.2.0):C2/1:delGen=1)))}
[junit4] 2> 463670 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 55
[junit4] 2> 463670 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:42&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 463672 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[42 (1583247724303613952)]} 0 2
[junit4] 2> 463673 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[42 (1583247724306759680)]} 0 0
[junit4] 2> 463674 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583247724307808256,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 463674 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@690fbda6
commitCommandVersion:1583247724307808256
[junit4] 2> 463699 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.s.SolrIndexSearcher Opening [Searcher@162205ac[collection1] main]
[junit4] 2> 463702 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@162205ac[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.2.0):C2/1:delGen=1)))}
[junit4] 2> 463702 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 463707 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 33
[junit4] 2> 463707 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:42&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 463713 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[101 (1583247724344508416)]} 0 3
[junit4] 2> 463714 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[101 (1583247724349751296)]} 0 1
[junit4] 2> 463716 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[105 (1583247724351848448)]} 0 0
[junit4] 2> 463717 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[102 (1583247724351848449)]} 0 0
[junit4] 2> 463718 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[103 (1583247724352897024)]} 0 0
[junit4] 2> 463718 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[101 (1583247724353945600)]} 0 0
[junit4] 2> 463718 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583247724353945601,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 463718 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@690fbda6
commitCommandVersion:1583247724353945601
[junit4] 2> 463734 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.s.SolrIndexSearcher Opening [Searcher@41a5928d[collection1] main]
[junit4] 2> 463735 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 463737 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@41a5928d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.2.0):C2/1:delGen=1)
Uninverting(_3(7.2.0):C6/2:delGen=1)))}
[junit4] 2> 463738 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 19
[junit4] 2> 463738 ERROR
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] 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> 463739 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59163 59163
[junit4] 2> 463740 INFO (Thread-547) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:59163 59163
[junit4] 2> 463743 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[201 (1583247724378062848)]} 0 2
[junit4] 2> 463847 WARN
(zkCallback-945-thread-3-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@20fcbdd3 name:
ZooKeeperConnection Watcher:127.0.0.1:59163/solr got event WatchedEvent
state:Disconnected type:None path:null path: null type: None
[junit4] 2> 463847 WARN
(zkCallback-945-thread-3-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.c.ConnectionManager zkClient has
disconnected
[junit4] 2> 464076 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 464076 INFO (Thread-549) [n:88.99.242.118:8983_ ]
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:59163
[junit4] 2> 464076 INFO (Thread-549) [n:88.99.242.118:8983_ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 464121 ERROR (Thread-549) [n:88.99.242.118:8983_ ]
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> 464177 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.ZkTestServer start zk server on port:59163
[junit4] 2> 464478 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:[100+TO+110]&rows=2147483647&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 464485 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:[100+TO+111]&rows=1147483647&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 464487 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:[100+TO+110]&qt=&start=0&rows=20&version=2.2} hits=4 status=0
QTime=0
[junit4] 2> 464488 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{delete=[102 (-1583247725161349120)]} 0 0
[junit4] 2> 464490 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583247725162397696,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 464490 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@690fbda6
commitCommandVersion:1583247725162397696
[junit4] 2> 464734 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.s.SolrIndexSearcher Opening [Searcher@709bb3a2[collection1] main]
[junit4] 2> 464737 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@709bb3a2[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.2.0):C2/1:delGen=1)
Uninverting(_3(7.2.0):C6/3:delGen=2) Uninverting(_4(7.2.0):C1)))}
[junit4] 2> 464737 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 464739 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 250
[junit4] 2> 464739 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:[100+TO+110]&qt=&start=0&rows=20&version=2.2} hits=3 status=0
QTime=0
[junit4] 2> 464741 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{delete=[105 (-1583247725425590272)]} 0 1
[junit4] 2> 464741 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583247725426638848,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 464741 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@690fbda6
commitCommandVersion:1583247725426638848
[junit4] 2> 464768 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.s.SolrIndexSearcher Opening [Searcher@358c6ba[collection1] main]
[junit4] 2> 464770 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 464772 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@358c6ba[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.2.0):C2/1:delGen=1)
Uninverting(_3(7.2.0):C6/4:delGen=3) Uninverting(_4(7.2.0):C1)))}
[junit4] 2> 464772 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 31
[junit4] 2> 464773 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:[100+TO+110]&qt=&start=0&rows=20&version=2.2} hits=2 status=0
QTime=0
[junit4] 2> 464778 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.s.SolrIndexSearcher Opening [Searcher@10a647b8[collection1] realtime]
[junit4] 2> 464779 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{deleteByQuery=id:[100 TO 110] (-1583247725461241856)} 0 5
[junit4] 2> 464781 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583247725468581888,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 464781 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@690fbda6
commitCommandVersion:1583247725468581888
[junit4] 2> 464782 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.s.SolrIndexSearcher Opening [Searcher@64c6f1db[collection1] main]
[junit4] 2> 464783 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 464784 INFO
(searcherExecutor-1404-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@64c6f1db[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.2.0):C2/1:delGen=1)
Uninverting(_4(7.2.0):C1)))}
[junit4] 2> 464785 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 5
[junit4] 2> 464786 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=id:[100+TO+110]&qt=&start=0&rows=20&version=2.2} hits=0 status=0
QTime=0
[junit4] 2> 465809 ERROR
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\core.properties
[junit4] 2> 465814 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 465903 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.c.Config Ignoring exception matching solrconfig.xml
[junit4] 2> 465903 INFO
(TEST-BasicZkTest.testBasic-seed#[64582DF60B061A85]) [n:88.99.242.118:8983_
] o.a.s.SolrTestCaseJ4 ###Ending testBasic
[junit4] 2> 465904 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 465906 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1511212338
[junit4] 2> 465906 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 465906 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@699b3279: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@23cfb96e
[junit4] 2> 465910 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 465911 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@19c98fd5: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@74b0ea73
[junit4] 2> 465915 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 465915 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@467fe425: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@376152cd
[junit4] 2> 465918 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/88.99.242.118:8983_
[junit4] 2> 465922 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 465925 INFO
(zkCallback-945-thread-13-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 465931 INFO
(coreCloseExecutor-1409-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 r:88.99.242.118:8983__collection1
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1e7dcf4b
[junit4] 2> 465931 INFO
(coreCloseExecutor-1409-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 r:88.99.242.118:8983__collection1
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1.shard1.88.99.242.118:8983__collection1,
tag=511561547
[junit4] 2> 465931 INFO
(coreCloseExecutor-1409-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 r:88.99.242.118:8983__collection1
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@44410e75: rootName = null,
domain = solr.core.collection1.shard1.88.99.242.118:8983__collection1, service
url = null, agent id = null] for registry
solr.core.collection1.shard1.88.99.242.118:8983__collection1 /
com.codahale.metrics.MetricRegistry@26a94a22
[junit4] 2> 465941 INFO
(coreCloseExecutor-1409-thread-1-processing-n:88.99.242.118:8983_)
[n:88.99.242.118:8983_ c:collection1 s:shard1 r:88.99.242.118:8983__collection1
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.collection1.shard1.leader, tag=511561547
[junit4] 2> 465965 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.c.Overseer Overseer
(id=98952982653698051-88.99.242.118:8983_-n_0000000000) closing
[junit4] 2> 465969 WARN
(OverseerAutoScalingTriggerThread-98952982653698051-88.99.242.118:8983_-n_0000000000)
[n:88.99.242.118:8983_ ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 465970 INFO
(OverseerStateUpdate-98952982653698051-88.99.242.118:8983_-n_0000000000)
[n:88.99.242.118:8983_ ] o.a.s.c.Overseer Overseer Loop exiting :
88.99.242.118:8983_
[junit4] 2> 465982 ERROR
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
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> 465983 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:59163 59163
[junit4] 2> 465985 INFO (Thread-549) [n:88.99.242.118:8983_ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:59163 59163
[junit4] 2> 465987 INFO
(SUITE-BasicZkTest-seed#[64582DF60B061A85]-worker) [n:88.99.242.118:8983_ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> Nov 05, 2017 5:24:53 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{multiDefault=PostingsFormat(name=Direct),
val_s=PostingsFormat(name=Asserting),
id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))},
docValues:{range_facet_l_dv=DocValuesFormat(name=Memory),
_version_=DocValuesFormat(name=Direct),
range_facet_i_dv=DocValuesFormat(name=Direct),
intDvoDefault=DocValuesFormat(name=Lucene70),
timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1234,
maxMBSortInHeap=5.193210209561637, sim=RandomSimilarity(queryNorm=true): {},
locale=el-CY, timezone=Singapore
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144
(64-bit)/cpus=3,threads=1,free=154510448,total=492830720
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomFlRTGCloud,
TestLeaderElectionWithEmptyReplica, HttpPartitionTest,
BigEndianAscendingWordDeserializerTest, SolrSlf4jReporterTest,
TestManagedStopFilterFactory, TestBadConfig, TestFoldingMultitermQuery,
PolyFieldTest, TestWordDelimiterFilterFactory, EchoParamsTest, TestPullReplica,
TestJsonFacets, TestTolerantUpdateProcessorCloud,
ClassificationUpdateProcessorTest, DocumentBuilderTest, TestFunctionQuery,
TestConfigOverlay, UpdateRequestProcessorFactoryTest, TestClusterStateMutator,
TestLockTree, BadComponentTest, TestXmlQParserPlugin, TestFieldCacheSort,
BufferStoreTest, DocValuesTest, SystemInfoHandlerTest, TestJettySolrRunner,
TestReload, OutOfBoxZkACLAndCredentialsProvidersTest, ReplaceNodeTest,
TestStressLiveNodes, TestCSVLoader, TestDistributedGrouping, SampleTest,
ZkNodePropsTest, TestDocSet, TestOnReconnectListenerSupport, BasicZkTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicZkTest
-Dtests.seed=64582DF60B061A85 -Dtests.slow=true -Dtests.locale=el-CY
-Dtests.timezone=Singapore -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | BasicZkTest (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-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq\segments_1:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001\init-core-data-001\spellcheckerFreq\segments_1
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.BasicZkTest_64582DF60B061A85-001
[junit4] > at
__randomizedtesting.SeedInfo.seed([64582DF60B061A85]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [72/747 (1!)] on J1 in 4.67s, 1 test, 1 error <<<
FAILURES!
[...truncated 418 lines...]
[junit4] Suite: org.apache.solr.cloud.TestSkipOverseerOperations
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSkipOverseerOperations_64582DF60B061A85-001\init-core-data-001
[junit4] 2> 805237 WARN
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 805237 INFO
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 805239 INFO
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 805239 INFO
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 805244 INFO
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSkipOverseerOperations_64582DF60B061A85-001\tempDir-001
[junit4] 2> 805244 INFO
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 805244 INFO (Thread-1544) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 805244 INFO (Thread-1544) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 805249 ERROR (Thread-1544) [ ] 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> 805345 INFO
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:63488
[junit4] 2> 805354 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f8d3be1b30000, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 805357 INFO (jetty-launcher-3137-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 805358 INFO (jetty-launcher-3137-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@8f4c35c{/solr,null,AVAILABLE}
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@22c5edcb{HTTP/1.1,[http/1.1]}{127.0.0.1:63492}
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-1) [ ]
o.e.j.s.Server Started @814134ms
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63492}
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-3) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 805359 ERROR (jetty-launcher-3137-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.2.0
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-05T17:30:32.866Z
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@54933db7{/solr,null,AVAILABLE}
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5744d745{HTTP/1.1,[http/1.1]}{127.0.0.1:63495}
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-3) [ ]
o.e.j.s.Server Started @814136ms
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63495}
[junit4] 2> 805359 ERROR (jetty-launcher-3137-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.2.0
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-05T17:30:32.866Z
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 805359 INFO (jetty-launcher-3137-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1b657f23{/solr,null,AVAILABLE}
[junit4] 2> 805363 INFO (jetty-launcher-3137-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@321f76fb{HTTP/1.1,[http/1.1]}{127.0.0.1:63501}
[junit4] 2> 805363 INFO (jetty-launcher-3137-thread-2) [ ]
o.e.j.s.Server Started @814139ms
[junit4] 2> 805363 INFO (jetty-launcher-3137-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63501}
[junit4] 2> 805364 ERROR (jetty-launcher-3137-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 805364 INFO (jetty-launcher-3137-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.2.0
[junit4] 2> 805364 INFO (jetty-launcher-3137-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 805364 INFO (jetty-launcher-3137-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 805364 INFO (jetty-launcher-3137-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-05T17:30:32.871Z
[junit4] 2> 805370 INFO (jetty-launcher-3137-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 805370 INFO (jetty-launcher-3137-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 805371 INFO (jetty-launcher-3137-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 805381 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f8d3be1b30003, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 805390 INFO (jetty-launcher-3137-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63488/solr
[junit4] 2> 805390 INFO (jetty-launcher-3137-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63488/solr
[junit4] 2> 805393 INFO (jetty-launcher-3137-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63488/solr
[junit4] 2> 805395 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f8d3be1b30005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 805398 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f8d3be1b30006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 805522 INFO (jetty-launcher-3137-thread-3)
[n:127.0.0.1:63495_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 805522 INFO (jetty-launcher-3137-thread-1)
[n:127.0.0.1:63492_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 805522 INFO (jetty-launcher-3137-thread-2)
[n:127.0.0.1:63501_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 805523 INFO (jetty-launcher-3137-thread-3)
[n:127.0.0.1:63495_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:63495_solr
[junit4] 2> 805523 INFO (jetty-launcher-3137-thread-1)
[n:127.0.0.1:63492_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63492_solr
[junit4] 2> 805523 INFO (jetty-launcher-3137-thread-2)
[n:127.0.0.1:63501_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63501_solr
[junit4] 2> 805523 INFO (jetty-launcher-3137-thread-3)
[n:127.0.0.1:63495_solr ] o.a.s.c.Overseer Overseer
(id=98953005154631688-127.0.0.1:63495_solr-n_0000000000) starting
[junit4] 2> 805527 INFO
(zkCallback-3154-thread-1-processing-n:127.0.0.1:63492_solr)
[n:127.0.0.1:63492_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 805527 INFO
(zkCallback-3153-thread-1-processing-n:127.0.0.1:63501_solr)
[n:127.0.0.1:63501_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 805527 INFO
(zkCallback-3152-thread-1-processing-n:127.0.0.1:63495_solr)
[n:127.0.0.1:63495_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 805532 INFO (jetty-launcher-3137-thread-3)
[n:127.0.0.1:63495_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63495_solr
[junit4] 2> 805554 INFO
(zkCallback-3152-thread-1-processing-n:127.0.0.1:63495_solr)
[n:127.0.0.1:63495_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 805554 INFO
(zkCallback-3154-thread-1-processing-n:127.0.0.1:63492_solr)
[n:127.0.0.1:63492_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 805554 INFO
(zkCallback-3153-thread-1-processing-n:127.0.0.1:63501_solr)
[n:127.0.0.1:63501_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 805644 INFO (jetty-launcher-3137-thread-2)
[n:127.0.0.1:63501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63501.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 805646 INFO (jetty-launcher-3137-thread-3)
[n:127.0.0.1:63495_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63495.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 805652 INFO (jetty-launcher-3137-thread-2)
[n:127.0.0.1:63501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63501.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 805653 INFO (jetty-launcher-3137-thread-2)
[n:127.0.0.1:63501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63501.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 805655 INFO (jetty-launcher-3137-thread-2)
[n:127.0.0.1:63501_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSkipOverseerOperations_64582DF60B061A85-001\tempDir-001\node2\.
[junit4] 2> 805655 INFO (jetty-launcher-3137-thread-1)
[n:127.0.0.1:63492_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63492.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 805658 INFO (jetty-launcher-3137-thread-3)
[n:127.0.0.1:63495_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63495.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 805658 INFO (jetty-launcher-3137-thread-3)
[n:127.0.0.1:63495_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63495.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 805659 INFO (jetty-launcher-3137-thread-3)
[n:127.0.0.1:63495_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSkipOverseerOperations_64582DF60B061A85-001\tempDir-001\node3\.
[junit4] 2> 805666 INFO (jetty-launcher-3137-thread-1)
[n:127.0.0.1:63492_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63492.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 805667 INFO (jetty-launcher-3137-thread-1)
[n:127.0.0.1:63492_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63492.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1b5a7a85
[junit4] 2> 805669 INFO (jetty-launcher-3137-thread-1)
[n:127.0.0.1:63492_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSkipOverseerOperations_64582DF60B061A85-001\tempDir-001\node1\.
[junit4] 2> 805754 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f8d3be1b3000a, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 805760 INFO
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]
[...truncated too long message...]
INFO (jetty-closer-3138-thread-2) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 866298 INFO (jetty-closer-3138-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 866298 INFO (jetty-closer-3138-thread-3) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 866298 INFO
(zkCallback-3152-thread-1-processing-n:127.0.0.1:63495_solr)
[n:127.0.0.1:63495_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (0)
[junit4] 2> 866298 INFO (jetty-closer-3138-thread-3) [ ]
o.a.s.c.Overseer Overseer
(id=98953005154631688-127.0.0.1:63495_solr-n_0000000000) closing
[junit4] 2> 866298 INFO
(zkCallback-3153-thread-1-processing-n:127.0.0.1:63501_solr)
[n:127.0.0.1:63501_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (0)
[junit4] 2> 866299 INFO
(OverseerStateUpdate-98953005154631688-127.0.0.1:63495_solr-n_0000000000)
[n:127.0.0.1:63495_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:63495_solr
[junit4] 2> 866299 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f8d3be1b30007, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 866300 WARN
(OverseerAutoScalingTriggerThread-98953005154631688-127.0.0.1:63495_solr-n_0000000000)
[n:127.0.0.1:63495_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 866300 INFO (jetty-closer-3138-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1b657f23{/solr,null,UNAVAILABLE}
[junit4] 2> 866300 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f8d3be1b30008, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 867800 WARN
(zkCallback-3152-thread-1-processing-n:127.0.0.1:63495_solr)
[n:127.0.0.1:63495_solr ] o.a.s.c.ZkController Unable to register nodeLost
path for 127.0.0.1:63501_solr
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:63501_solr
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:357)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:357)
[junit4] 2> at
org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:879)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$1(ZkStateReader.java:749)
[junit4] 2> at
java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4649)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:748)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.access$1000(ZkStateReader.java:72)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1154)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1149)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$0(SolrZkClient.java:262)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 867800 INFO (jetty-closer-3138-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@54933db7{/solr,null,UNAVAILABLE}
[junit4] 2> 867802 ERROR
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]-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> 867802 INFO
(SUITE-TestSkipOverseerOperations-seed#[64582DF60B061A85]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:63488 63488
[junit4] 2> 867803 INFO (Thread-1544) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:63488 63488
[junit4] 2> 867804 WARN (Thread-1544) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 4 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSkipOverseerOperations_64582DF60B061A85-001
[junit4] 2> Nov 05, 2017 5:31:35 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=Lucene70,
sim=RandomSimilarity(queryNorm=false): {}, locale=en-GB,
timezone=Europe/Guernsey
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144
(64-bit)/cpus=3,threads=1,free=282982168,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomFlRTGCloud,
TestLeaderElectionWithEmptyReplica, HttpPartitionTest,
BigEndianAscendingWordDeserializerTest, SolrSlf4jReporterTest,
TestManagedStopFilterFactory, TestBadConfig, TestFoldingMultitermQuery,
PolyFieldTest, TestWordDelimiterFilterFactory, EchoParamsTest, TestPullReplica,
TestJsonFacets, TestTolerantUpdateProcessorCloud,
ClassificationUpdateProcessorTest, DocumentBuilderTest, TestFunctionQuery,
TestConfigOverlay, UpdateRequestProcessorFactoryTest, TestClusterStateMutator,
TestLockTree, BadComponentTest, TestXmlQParserPlugin, TestFieldCacheSort,
BufferStoreTest, DocValuesTest, SystemInfoHandlerTest, TestJettySolrRunner,
TestReload, OutOfBoxZkACLAndCredentialsProvidersTest, ReplaceNodeTest,
TestStressLiveNodes, TestCSVLoader, TestDistributedGrouping, SampleTest,
ZkNodePropsTest, TestDocSet, TestOnReconnectListenerSupport, BasicZkTest,
AtomicUpdateProcessorFactoryTest, CollectionsAPIAsyncDistributedZkTest,
LegacyCloudClusterPropTest, MetricsHandlerTest, FacetPivotSmallTest,
HttpTriggerListenerTest, CopyFieldTest, JavabinLoaderTest,
PeerSyncWithIndexFingerprintCachingTest,
ConcurrentDeleteAndCreateCollectionTest, TestConfigSetsAPIExclusivity,
DOMUtilTest, SimpleFacetsTest, TestRandomFaceting, MetricUtilsTest,
TestPayloadCheckQParserPlugin, TestRemoteStreaming, RequestLoggingTest,
TestMacroExpander, ActionThrottleTest, SolrMetricReporterTest,
IndexSchemaRuntimeFieldTest, CdcrUpdateLogTest, TermVectorComponentTest,
TestFilteredDocIdSet, VersionInfoTest, DocumentAnalysisRequestHandlerTest,
AnalysisAfterCoreReloadTest, BaseCdcrDistributedZkTest,
TestEmbeddedSolrServerConstructors, DeleteStatusTest, TestObjectReleaseTracker,
JsonLoaderTest, TestSmileRequest, ExternalFileFieldSortTest, UpdateParamsTest,
MoveReplicaHDFSTest, TestHashPartitioner, AnalyticsQueryTest, BJQParserTest,
PluginInfoTest, CdcrReplicationDistributedZkTest, RuleEngineTest,
TestFastWriter, BasicAuthIntegrationTest, SolrCoreMetricManagerTest,
SimpleCollectionCreateDeleteTest, TestRawTransformer,
TestBlendedInfixSuggestions, MoreLikeThisHandlerTest, SimplePostToolTest,
CoreAdminCreateDiscoverTest, BasicDistributedZk2Test, LukeRequestHandlerTest,
HLLUtilTest, TestExtendedDismaxParser, TestAuthenticationFramework,
TestUseDocValuesAsStored2, SearchHandlerTest, TestPivotHelperCode, TestSort,
SoftAutoCommitTest, TestGroupingSearch, TestReqParamsAPI, TestCollectionAPIs,
TestManagedResourceStorage, HdfsNNFailoverTest,
TestExclusionRuleCollectionAccess, TestConfigSetProperties,
SynonymTokenizerTest, TestImpersonationWithHadoopAuth,
TestCollectionsAPIViaSolrCloudCluster, TestSearchPerf, TestNRTOpen,
TestSQLHandler, TestSolrQueryResponse, SolrShardReporterTest,
TestCrossCoreJoin, ResponseHeaderTest, DirectSolrSpellCheckerTest,
TestSkipOverseerOperations]
[junit4] Completed [184/747 (2!)] on J1 in 63.34s, 2 tests, 2 errors <<<
FAILURES!
[...truncated 49216 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
