You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/11/15 17:34:48 UTC
[JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_144) - Build #
7018 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7018/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseParallelGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestCloudRecovery
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001
at __randomizedtesting.SeedInfo.seed([2E3C47FDDB242EE2]: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.autoscaling.TriggerIntegrationTest.testEventQueue
Error Message:
action wasn't interrupted
Stack Trace:
java.lang.AssertionError: action wasn't interrupted
at __randomizedtesting.SeedInfo.seed([2E3C47FDDB242EE2:E7890553D243E817]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue(TriggerIntegrationTest.java:694)
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 12036 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_2E3C47FDDB242EE2-001\init-core-data-001
[junit4] 2> 464780 WARN (SUITE-TriggerIntegrationTest-seed#[2E3C47FDDB242EE2]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=24 numCloses=24
[junit4] 2> 464780 INFO (SUITE-TriggerIntegrationTest-seed#[2E3C47FDDB242EE2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 464785 INFO (SUITE-TriggerIntegrationTest-seed#[2E3C47FDDB242EE2]-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> 464785 INFO (SUITE-TriggerIntegrationTest-seed#[2E3C47FDDB242EE2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 464786 INFO (SUITE-TriggerIntegrationTest-seed#[2E3C47FDDB242EE2]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_2E3C47FDDB242EE2-001\tempDir-001
[junit4] 2> 464787 INFO (SUITE-TriggerIntegrationTest-seed#[2E3C47FDDB242EE2]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 464787 INFO (Thread-1025) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 464787 INFO (Thread-1025) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 464791 ERROR (Thread-1025) [ ] 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> 464888 INFO (SUITE-TriggerIntegrationTest-seed#[2E3C47FDDB242EE2]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:63736
[junit4] 2> 464895 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 0x15fc07f780d0000, 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> 464898 INFO (jetty-launcher-1454-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 464899 INFO (jetty-launcher-1454-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ab99a6{/solr,null,AVAILABLE}
[junit4] 2> 464900 INFO (jetty-launcher-1454-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7299f6{HTTP/1.1,[http/1.1]}{127.0.0.1:63741}
[junit4] 2> 464901 INFO (jetty-launcher-1454-thread-2) [ ] o.e.j.s.Server Started @472668ms
[junit4] 2> 464901 INFO (jetty-launcher-1454-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63741}
[junit4] 2> 464901 ERROR (jetty-launcher-1454-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 464901 INFO (jetty-launcher-1454-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 464901 INFO (jetty-launcher-1454-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 464901 INFO (jetty-launcher-1454-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 464901 INFO (jetty-launcher-1454-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-15T16:25:00.284Z
[junit4] 2> 464903 INFO (jetty-launcher-1454-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 464907 INFO (jetty-launcher-1454-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1beb03b{/solr,null,AVAILABLE}
[junit4] 2> 464908 INFO (jetty-launcher-1454-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a09d1c{HTTP/1.1,[http/1.1]}{127.0.0.1:63746}
[junit4] 2> 464909 INFO (jetty-launcher-1454-thread-1) [ ] o.e.j.s.Server Started @472676ms
[junit4] 2> 464909 INFO (jetty-launcher-1454-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63746}
[junit4] 2> 464910 ERROR (jetty-launcher-1454-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 464910 INFO (jetty-launcher-1454-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 464910 INFO (jetty-launcher-1454-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 464910 INFO (jetty-launcher-1454-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 464910 INFO (jetty-launcher-1454-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-15T16:25:00.293Z
[junit4] 2> 464911 INFO (jetty-launcher-1454-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 464914 INFO (jetty-launcher-1454-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 464928 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 0x15fc07f780d0001, 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> 464928 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 0x15fc07f780d0002, 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> 464933 INFO (jetty-launcher-1454-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63736/solr
[junit4] 2> 464933 INFO (jetty-launcher-1454-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63736/solr
[junit4] 2> 464937 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 0x15fc07f780d0003, 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> 464937 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 0x15fc07f780d0004, 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> 465057 INFO (jetty-launcher-1454-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 465057 INFO (jetty-launcher-1454-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:63741_solr
[junit4] 2> 465059 INFO (jetty-launcher-1454-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.Overseer Overseer (id=99009370532937734-127.0.0.1:63741_solr-n_0000000000) starting
[junit4] 2> 465063 INFO (jetty-launcher-1454-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 465064 INFO (jetty-launcher-1454-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63746_solr
[junit4] 2> 465066 INFO (zkCallback-1466-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 465067 INFO (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 465070 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 465071 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 465072 INFO (jetty-launcher-1454-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63741_solr
[junit4] 2> 465072 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:63746_solr]
[junit4] 2> 465072 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 465072 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 465074 INFO (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 465074 INFO (zkCallback-1466-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 465076 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 465076 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 465077 DEBUG (ScheduledTrigger-1910-thread-1) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63741_solr]
[junit4] 2> 465382 INFO (jetty-launcher-1454-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63746.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 465384 INFO (jetty-launcher-1454-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63741.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 465400 INFO (jetty-launcher-1454-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63746.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 465401 INFO (jetty-launcher-1454-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63746.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 465402 INFO (jetty-launcher-1454-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63741.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 465402 INFO (jetty-launcher-1454-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63741.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 465402 INFO (jetty-launcher-1454-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_2E3C47FDDB242EE2-001\tempDir-001\node2\.
[junit4] 2> 465404 INFO (jetty-launcher-1454-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_2E3C47FDDB242EE2-001\tempDir-001\node1\.
[junit4] 2> 465466 INFO (SUITE-TriggerIntegrationTest-seed#[2E3C47FDDB242EE2]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 465468 INFO (SUITE-TriggerIntegrationTest-seed#[2E3C47FDDB242EE2]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63736/solr ready
[junit4] 2> 465493 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEventQueue
[junit4] 2> 465494 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
[junit4] 2> 465495 DEBUG (zkCallback-1466-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 465495 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 465497 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 465497 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 465498 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 465501 INFO (qtp10150096-5159) [n:127.0.0.1:63746_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 465512 INFO (qtp10150096-5159) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 465519 INFO (qtp10150096-5158) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 465520 INFO (qtp10150096-5158) [n:127.0.0.1:63746_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63736/solr ready
[junit4] 2> 465521 INFO (qtp10150096-5156) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 465523 INFO (qtp2224634-5161) [n:127.0.0.1:63741_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 465523 DEBUG (qtp10150096-5158) [n:127.0.0.1:63746_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 465523 INFO (qtp10150096-5158) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 465527 DEBUG (zkCallback-1466-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 465527 INFO (zkCallback-1466-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.a.TriggerIntegrationTest TestEventQueueAction instantiated
[junit4] 2> 465528 DEBUG (zkCallback-1466-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63741_solr]
[junit4] 2> 465528 DEBUG (zkCallback-1466-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventQueueAction}], enabled=true}
[junit4] 2> 465528 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 465530 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 465530 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 465531 DEBUG (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 465531 DEBUG (ScheduledTrigger-1910-thread-1) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 465531 DEBUG (ScheduledTrigger-1910-thread-1) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63741_solr]
[junit4] 2> 465531 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 465531 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f1a20{/solr,null,AVAILABLE}
[junit4] 2> 465532 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3e705{HTTP/1.1,[http/1.1]}{127.0.0.1:63777}
[junit4] 2> 465532 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.Server Started @473300ms
[junit4] 2> 465532 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63777}
[junit4] 2> 465533 ERROR (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 465533 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 465533 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 465533 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 465533 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-15T16:25:00.916Z
[junit4] 2> 465537 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 465552 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63736/solr
[junit4] 2> 465567 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 465570 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63777_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 465573 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63777_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63777_solr
[junit4] 2> 465575 INFO (zkCallback-1481-thread-1-processing-n:127.0.0.1:63777_solr) [n:127.0.0.1:63777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 465575 INFO (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 465576 INFO (zkCallback-1473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 465576 INFO (zkCallback-1466-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 465733 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63777.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 465752 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63777.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 465752 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63777.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 465756 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63777_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_2E3C47FDDB242EE2-001\tempDir-001\node3\.
[junit4] 2> 466533 DEBUG (ScheduledTrigger-1910-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 466533 DEBUG (ScheduledTrigger-1910-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63741_solr, 127.0.0.1:63777_solr]
[junit4] 2> 466533 DEBUG (ScheduledTrigger-1910-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:63777_solr at time 1510763101916000000
[junit4] 2> 467506 INFO (OverseerCollectionConfigSetProcessor-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 467535 DEBUG (ScheduledTrigger-1910-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 467535 DEBUG (ScheduledTrigger-1910-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63741_solr, 127.0.0.1:63777_solr]
[junit4] 2> 467535 DEBUG (ScheduledTrigger-1910-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing registered processor for nodes: [127.0.0.1:63777_solr] added at times [1510763101916000000], now=1510763102918000000
[junit4] 2> 467535 DEBUG (ScheduledTrigger-1910-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"14f74f096d786f00Tbotfkew14b3z4azmhdg79w5rp",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":1510763101916000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1510763101916000000],
[junit4] 2> "nodeNames":["127.0.0.1:63777_solr"]}}
[junit4] 2> 467537 INFO (ScheduledTrigger-1910-thread-2) [n:127.0.0.1:63741_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging.
[junit4] 2> 467539 DEBUG (AutoscalingActionExecutor-1911-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"14f74f096d786f00Tbotfkew14b3z4azmhdg79w5rp",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":1510763101916000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1510763101916000000],
[junit4] 2> "_enqueue_time_":1510763102918000000,
[junit4] 2> "nodeNames":["127.0.0.1:63777_solr"]}}
[junit4] 2> 467539 INFO (AutoscalingActionExecutor-1911-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.a.TriggerIntegrationTest -- event: {
[junit4] 2> "id":"14f74f096d786f00Tbotfkew14b3z4azmhdg79w5rp",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":1510763101916000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1510763101916000000],
[junit4] 2> "_enqueue_time_":1510763102918000000,
[junit4] 2> "nodeNames":["127.0.0.1:63777_solr"]}}
[junit4] 2> 467542 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7299f6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 467543 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=26639104
[junit4] 2> 467544 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 467544 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@32f5c5: rootName = solr_63741, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@11250f7
[junit4] 2> 467557 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 467557 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b5e06: rootName = solr_63741, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@bdeb15
[junit4] 2> 467566 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 467566 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4620af: rootName = solr_63741, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@82651a
[junit4] 2> 467569 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:63741_solr
[junit4] 2> 467571 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 467571 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 467571 INFO (zkCallback-1466-thread-1-processing-n:127.0.0.1:63741_solr) [n:127.0.0.1:63741_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 467571 INFO (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 467571 INFO (zkCallback-1481-thread-1-processing-n:127.0.0.1:63777_solr) [n:127.0.0.1:63777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 467572 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.Overseer Overseer (id=99009370532937734-127.0.0.1:63741_solr-n_0000000000) closing
[junit4] 2> 467572 INFO (zkCallback-1473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 467572 INFO (OverseerStateUpdate-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:63741_solr
[junit4] 2> 467573 DEBUG (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 467574 WARN (OverseerAutoScalingTriggerThread-99009370532937734-127.0.0.1:63741_solr-n_0000000000) [n:127.0.0.1:63741_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 467576 INFO (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:63746_solr
[junit4] 2> 467576 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1ab99a6{/solr,null,UNAVAILABLE}
[junit4] 2> 467579 INFO (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.Overseer Overseer (id=99009370532937733-127.0.0.1:63746_solr-n_0000000001) starting
[junit4] 2> 467589 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 467590 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 467590 INFO (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.TriggerIntegrationTest TestEventQueueAction instantiated
[junit4] 2> 467590 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 467590 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventQueueAction}], enabled=true}
[junit4] 2> 467591 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 467591 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion -1
[junit4] 2> 467591 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 467594 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 467594 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 467594 DEBUG (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 467595 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 467595 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 468595 DEBUG (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 468597 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 468597 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 469597 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 469598 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 469598 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 470598 DEBUG (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 470598 DEBUG (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 470598 DEBUG (ScheduledTrigger-1925-thread-4) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 471600 DEBUG (ScheduledTrigger-1925-thread-4) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 471600 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 471600 DEBUG (ScheduledTrigger-1925-thread-4) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 472600 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 472600 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 472600 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 473601 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 473601 DEBUG (ScheduledTrigger-1925-thread-4) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 473601 DEBUG (ScheduledTrigger-1925-thread-4) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 474602 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 474602 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 474602 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 475579 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEventQueue
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TriggerIntegrationTest -Dtests.method=testEventQueue -Dtests.seed=2E3C47FDDB242EE2 -Dtests.slow=true -Dtests.locale=sr-CS -Dtests.timezone=Asia/Hong_Kong -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 10.1s J0 | TriggerIntegrationTest.testEventQueue <<<
[junit4] > Throwable #1: java.lang.AssertionError: action wasn't interrupted
[junit4] > at __randomizedtesting.SeedInfo.seed([2E3C47FDDB242EE2:E7890553D243E817]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue(TriggerIntegrationTest.java:694)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 475603 DEBUG (ScheduledTrigger-1925-thread-4) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 475603 DEBUG (ScheduledTrigger-1925-thread-4) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 475603 DEBUG (ScheduledTrigger-1925-thread-2) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 475615 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTrigger
[junit4] 2> 475615 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 5
[junit4] 2> 475615 DEBUG (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 475615 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 475621 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 475621 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 475622 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 475624 INFO (qtp10150096-5154) [n:127.0.0.1:63746_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 475632 INFO (qtp10150096-5154) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 475638 INFO (qtp10150096-5155) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 475639 INFO (qtp10150096-5155) [n:127.0.0.1:63746_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63736/solr ready
[junit4] 2> 475644 INFO (qtp10150096-5162) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 475646 INFO (qtp18430007-5210) [n:127.0.0.1:63777_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 475648 DEBUG (qtp10150096-5155) [n:127.0.0.1:63746_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 475649 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 0x15fc07f780d000d, 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> 475650 INFO (qtp10150096-5155) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 475652 DEBUG (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 475652 DEBUG (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 475653 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 475655 INFO (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 475655 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 475655 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3e705{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 475656 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=6058471
[junit4] 2> 475656 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 475656 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@149d924: rootName = solr_63777, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@19e12e1
[junit4] 2> 475657 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 475657 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: [127.0.0.1:63746_solr, 127.0.0.1:63777_solr]
[junit4] 2> 475661 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 475661 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d6f562: rootName = solr_63777, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@bdeb15
[junit4] 2> 475671 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 475671 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b9085: rootName = solr_63777, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@82651a
[junit4] 2> 475673 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:63777_solr
[junit4] 2> 475674 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 475675 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 475675 INFO (zkCallback-1481-thread-1-processing-n:127.0.0.1:63777_solr) [n:127.0.0.1:63777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 475675 INFO (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 475675 INFO (zkCallback-1473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 475677 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 0x15fc07f780d000c, 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> 475677 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2f1a20{/solr,null,UNAVAILABLE}
[junit4] 2> 476659 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: [127.0.0.1:63746_solr]
[junit4] 2> 476659 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:63777_solr
[junit4] 2> 477629 INFO (OverseerCollectionConfigSetProcessor-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 477665 DEBUG (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: [127.0.0.1:63746_solr]
[junit4] 2> 478667 DEBUG (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: [127.0.0.1:63746_solr]
[junit4] 2> 478667 DEBUG (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:63777_solr]
[junit4] 2> 478667 DEBUG (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"14f74f0bc906ee80Tbotfkew14b3z4azmhdg79w5rr",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":1510763112042000000,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1510763112042000000],
[junit4] 2> "nodeNames":["127.0.0.1:63777_solr"]}}
[junit4] 2> 478670 INFO (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging.
[junit4] 2> 478671 DEBUG (AutoscalingActionExecutor-1926-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"14f74f0bc906ee80Tbotfkew14b3z4azmhdg79w5rr",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":1510763112042000000,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1510763112042000000],
[junit4] 2> "_enqueue_time_":1510763114050000000,
[junit4] 2> "nodeNames":["127.0.0.1:63777_solr"]}}
[junit4] 2> 478684 INFO (qtp10150096-5159) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 478689 INFO (qtp10150096-5159) [n:127.0.0.1:63746_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63736/solr ready
[junit4] 2> 478691 INFO (qtp10150096-5157) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 478692 DEBUG (qtp10150096-5159) [n:127.0.0.1:63746_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 478693 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 0x15fc07f780d000e, 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> 478696 INFO (qtp10150096-5159) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=21
[junit4] 2> 478697 DEBUG (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7
[junit4] 2> 478698 DEBUG (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:63746_solr]
[junit4] 2> 478698 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 478699 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 478699 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 479675 DEBUG (ScheduledTrigger-1925-thread-1) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: [127.0.0.1:63746_solr]
[junit4] 2> 480677 DEBUG (ScheduledTrigger-1925-thread-3) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: [127.0.0.1:63746_solr]
[junit4] 2> 480698 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTrigger
[junit4] 2> 480707 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling
[junit4] 2> 480708 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 8
[junit4] 2> 480708 DEBUG (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8
[junit4] 2> 480708 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8
[junit4] 2> 480710 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 480710 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 480710 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 480711 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 480712 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e426ca{/solr,null,AVAILABLE}
[junit4] 2> 480713 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1af3da1{HTTP/1.1,[http/1.1]}{127.0.0.1:63822}
[junit4] 2> 480713 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.e.j.s.Server Started @488480ms
[junit4] 2> 480713 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63822}
[junit4] 2> 480713 ERROR (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 480713 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 480713 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 480713 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 480713 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-15T16:25:16.096Z
[junit4] 2> 480717 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 480733 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63736/solr
[junit4] 2> 480736 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 0x15fc07f780d0010, 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> 480748 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63822_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 480750 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63822_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 480753 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63822_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63822_solr
[junit4] 2> 480756 INFO (zkCallback-1473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 480756 INFO (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 480756 INFO (zkCallback-1494-thread-1-processing-n:127.0.0.1:63822_solr) [n:127.0.0.1:63822_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 481014 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63822_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63822.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 481027 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63822_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63822.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 481027 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63822_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63822.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fba722
[junit4] 2> 481029 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2E3C47FDDB242EE2]) [n:127.0.0.1:63822_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_2E3C47FDDB242EE2-001\tempDir-001\node4\.
[junit4] 2> 481066 INFO (qtp10150096-5156) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 481068 INFO (qtp10150096-5156) [n:127.0.0.1:63746_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63736/solr ready
[junit4] 2> 481068 INFO (qtp10150096-5158) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 481071 INFO (qtp22759267-5256) [n:127.0.0.1:63822_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 481071 DEBUG (qtp10150096-5156) [n:127.0.0.1:63746_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 481072 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 0x15fc07f780d0012, 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> 481073 INFO (qtp10150096-5156) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 481073 DEBUG (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 9
[junit4] 2> 481074 DEBUG (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63822_solr]
[junit4] 2> 481074 DEBUG (zkCallback-1465-thread-1-processing-n:127.0.0.1:63746_solr) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true}
[junit4] 2> 481074 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 9
[junit4] 2> 481077 INFO (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 481077 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 481077 DEBUG (OverseerAutoScalingTriggerThread-99009370532937733-127.0.0.1:63746_solr-n_0000000001) [n:127.0.0.1:63746_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9, lastZnodeVersion 9
[junit4] 2> 481078 DEBUG (ScheduledTrigger-1925-thread-4) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 481078 DEBUG (ScheduledTrigger-1925-thread-4) [n:127.0.0.1:63746_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63746_solr, 127.0.0.1:63822_solr]
[junit4] 2> 481079 INFO (qtp10150096-5154) [n:127.0.0.1:63746_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 481080 INFO (qtp10150096-5154) [n:127.0.0.1:63746_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63736/solr ready
[junit4] 2> 481080 INFO (qtp10150096-5162) [n:127.0.0.1:63746_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 481081 INFO (qtp22759267-5257) [n:127.0.0.1:63822_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 481082 DEBUG (qtp10150096-5154) [n:127.0.0.1:63746_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 481082 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIO
[...truncated too long message...]
tHandlerTest, BadIndexSchemaTest, ShufflingReplicaListTransformerTest, HdfsThreadLeakTest, TestAuthenticationFramework, TestCloudRecovery]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCloudRecovery -Dtests.seed=2E3C47FDDB242EE2 -Dtests.slow=true -Dtests.locale=he -Dtests.timezone=Asia/Colombo -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestCloudRecovery (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process.
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data\tlog
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4\data
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard2_replica_n4
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process.
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data\tlog
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1\data
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2\collection1_shard1_replica_n1
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node2
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process.
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data\tlog
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6\data
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard2_replica_n6
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001: The process cannot access the file because it is being used by another process.
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data\tlog
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2\data
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1\collection1_shard1_replica_n2
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001\node1
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001\tempDir-001
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_2E3C47FDDB242EE2-001
[junit4] > at __randomizedtesting.SeedInfo.seed([2E3C47FDDB242EE2]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [328/749 (2!)] on J0 in 22.11s, 2 tests, 1 error <<< FAILURES!
[...truncated 44428 lines...]
[JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_144) - Build #
7019 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7019/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseConcMarkSweepGC
4 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication
Error Message:
Index: 0, Size: 0
Stack Trace:
java.lang.IndexOutOfBoundsException: Index: 0, Size: 0
at __randomizedtesting.SeedInfo.seed([5491B9F67D9D87FD:40D9E2A35E9A3AE3]:0)
at java.util.ArrayList.rangeCheck(ArrayList.java:653)
at java.util.ArrayList.get(ArrayList.java:429)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:561)
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: junit.framework.TestSuite.org.apache.solr.security.hadoop.TestZkAclsWithHadoopAuth
Error Message:
Hadoop does not work on Windows
Stack Trace:
com.carrotsearch.randomizedtesting.InternalAssumptionViolatedException: Hadoop does not work on Windows
at __randomizedtesting.SeedInfo.seed([5491B9F67D9D87FD]:0)
at com.carrotsearch.randomizedtesting.RandomizedTest.assumeTrue(RandomizedTest.java:722)
at com.carrotsearch.randomizedtesting.RandomizedTest.assumeFalse(RandomizedTest.java:730)
at org.apache.lucene.util.LuceneTestCase.assumeFalse(LuceneTestCase.java:866)
at org.apache.solr.security.hadoop.TestZkAclsWithHadoopAuth.setupClass(TestZkAclsWithHadoopAuth.java:61)
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$6.evaluate(RandomizedRunner.java:874)
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: junit.framework.TestSuite.org.apache.solr.security.hadoop.TestZkAclsWithHadoopAuth
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.hadoop.TestZkAclsWithHadoopAuth_5491B9F67D9D87FD-001\init-core-data-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.hadoop.TestZkAclsWithHadoopAuth_5491B9F67D9D87FD-001\init-core-data-001 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.hadoop.TestZkAclsWithHadoopAuth_5491B9F67D9D87FD-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.hadoop.TestZkAclsWithHadoopAuth_5491B9F67D9D87FD-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.hadoop.TestZkAclsWithHadoopAuth_5491B9F67D9D87FD-001\init-core-data-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.hadoop.TestZkAclsWithHadoopAuth_5491B9F67D9D87FD-001\init-core-data-001
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.hadoop.TestZkAclsWithHadoopAuth_5491B9F67D9D87FD-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.hadoop.TestZkAclsWithHadoopAuth_5491B9F67D9D87FD-001
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: junit.framework.TestSuite.org.apache.solr.client.solrj.embedded.MergeIndexesEmbeddedTest
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005\index: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005\index C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005\index: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005\index
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005
at __randomizedtesting.SeedInfo.seed([B61D6E2685DD83C9]: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)
Build Log:
[...truncated 12679 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\init-core-data-001
[junit4] 2> 1614455 WARN (SUITE-TestReplicationHandler-seed#[5491B9F67D9D87FD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
[junit4] 2> 1614456 INFO (SUITE-TestReplicationHandler-seed#[5491B9F67D9D87FD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1614460 INFO (SUITE-TestReplicationHandler-seed#[5491B9F67D9D87FD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1614460 INFO (SUITE-TestReplicationHandler-seed#[5491B9F67D9D87FD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1614462 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexAndConfigAliasReplication
[junit4] 2> 1614462 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-001\collection1
[junit4] 2> 1614471 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1614471 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f9701b{/solr,null,AVAILABLE}
[junit4] 2> 1614473 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@8415dc{HTTP/1.1,[http/1.1]}{127.0.0.1:61646}
[junit4] 2> 1614473 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.e.j.s.Server Started @1622213ms
[junit4] 2> 1614473 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=61646}
[junit4] 2> 1614474 ERROR (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1614474 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 1614474 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1614474 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1614474 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-16T02:57:21.703Z
[junit4] 2> 1614475 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-001\solr.xml
[junit4] 2> 1614484 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1614484 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1614485 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1614580 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084
[junit4] 2> 1614592 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084
[junit4] 2> 1614592 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084
[junit4] 2> 1614599 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-001\.
[junit4] 2> 1614599 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1614599 INFO (coreLoadExecutor-5626-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1614628 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1614644 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1614649 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1614649 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-001\.\collection1, trusted=true
[junit4] 2> 1614650 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084
[junit4] 2> 1614650 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1614650 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 1614654 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.28564156194739154]
[junit4] 2> 1614752 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1614752 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1614753 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2745791996576036]
[junit4] 2> 1614753 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@15144b3[collection1] main]
[junit4] 2> 1614754 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-001\collection1\conf
[junit4] 2> 1614754 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1614754 INFO (coreLoadExecutor-5626-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1614757 INFO (searcherExecutor-5627-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15144b3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1614760 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-002\collection1
[junit4] 2> 1614769 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1614770 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5c15f6{/solr,null,AVAILABLE}
[junit4] 2> 1614770 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@99fb3b{HTTP/1.1,[http/1.1]}{127.0.0.1:61650}
[junit4] 2> 1614770 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.e.j.s.Server Started @1622511ms
[junit4] 2> 1614770 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=61650}
[junit4] 2> 1614771 ERROR (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1614771 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 1614771 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1614771 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1614771 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-16T02:57:22Z
[junit4] 2> 1614773 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-002\solr.xml
[junit4] 2> 1614787 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1614788 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1614789 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1614873 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084
[junit4] 2> 1614884 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084
[junit4] 2> 1614884 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084
[junit4] 2> 1614891 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-002\.
[junit4] 2> 1614891 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[5491B9F67D9D87FD]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1614892 INFO (coreLoadExecutor-5636-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1614927 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1614936 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1614940 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1614940 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-002\.\collection1, trusted=true
[junit4] 2> 1614941 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a084
[junit4] 2> 1614941 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1614941 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 1614943 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.28564156194739154]
[junit4] 2> 1615051 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1615051 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1615053 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2745791996576036]
[junit4] 2> 1615053 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f070d[collection1] main]
[junit4] 2> 1615054 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5491B9F67D9D87FD-001\solr-instance-002\collection1\conf
[junit4] 2> 1615057 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1615058 INFO (coreLoadExecutor-5636-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1615059 INFO (searcherExecutor-5637-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4f070d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1615062 INFO (qtp24389992-12974) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1615062 INFO (qtp24389992-12975) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1615063 INFO (indexFetcher-5641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1615063 INFO (indexFetcher-5641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1615063 INFO (indexFetcher-5641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1615063 INFO (indexFetcher-5641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1615063 INFO (indexFetcher-5641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher New index in Master. Deleting mine...
[junit4] 2> 1615063 INFO (indexFetcher-5641-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1615063 INFO (indexFetcher-5641-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1bc7311 commitCommandVersion:0
[junit4] 2> 1615064 INFO (indexFetcher-5641-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@14d8e49[collection1] main]
[junit4] 2> 1615065 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 2
[junit4] 2> 1615066 INFO (indexFetcher-5641-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1615067 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1615067 INFO (searcherExecutor-5637-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14d8e49[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1615068 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1615069 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1615071 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1615071 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1615073 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1615074 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1615075 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1615076 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1615077 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 1615079 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1615080 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1615081 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 1615082 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 1615083 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 1615083 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 1615084 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 1615085 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 1615086 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 1615087 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1615087 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 1615088 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 1615090 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 1615091 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 1615092 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 1615093 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 1615094 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 1615094 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1615095 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 1615096 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1615097 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 1615098 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 1615098 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1615099 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1615100 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1615101 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1615102 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1615103 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1615104 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 1615105 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1615106 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1615107 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1615107 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 1615109 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 1615110 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1615110 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1615111 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 1615112 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1615113 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1615114 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1615115 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1615116 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1615116 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1615117 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 1615118 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 1615119 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1615120 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1615121 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1615122 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1615123 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 1615123 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1615124 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1615125 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 1615126 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 1615127 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 1615127 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1615128 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1615129 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1615130 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1615131 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1615132 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 1615132 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1615133 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1615134 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1615135 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1615136 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 1615136 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 1615137 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 1615138 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 1615140 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 1615140 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 1615141 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 1615141 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 1615142 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 1615143 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 1615144 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 1615145 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 1615146 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 1615146 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 1615147 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 1615148 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 1615149 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 1615150 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 1615151 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 1615151 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 1615152 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 1615153 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 1615154 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
[junit4] 2> 1615155 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
[junit4] 2> 1615156 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 1615156 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 1615157 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 1615158 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 1615159 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 1615160 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 1615160 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 1615161 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 1615163 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 1615164 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 1615164 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 1615165 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 1615166 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 1615167 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 1615168 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 1615168 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 1615169 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 1615170 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 1615171 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 1615172 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 1615173 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 1615173 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 1615174 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 1615175 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[123]} 0 0
[junit4] 2> 1615175 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 1615176 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 1615177 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 1615178 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[127]} 0 0
[junit4] 2> 1615178 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 1615179 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 1615180 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 1615181 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 1615181 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 1615182 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 1615183 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 1615184 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 1615184 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 1615185 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 1615186 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[138]} 0 0
[junit4] 2> 1615187 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[139]} 0 0
[junit4] 2> 1615187 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[140]} 0 0
[junit4] 2> 1615188 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[141]} 0 0
[junit4] 2> 1615190 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[142]} 0 0
[junit4] 2> 1615190 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[143]} 0 0
[junit4] 2> 1615191 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[144]} 0 0
[junit4] 2> 1615191 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[145]} 0 0
[junit4] 2> 1615192 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[146]} 0 0
[junit4] 2> 1615193 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[147]} 0 0
[junit4] 2> 1615194 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[148]} 0 0
[junit4] 2> 1615195 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[149]} 0 0
[junit4] 2> 1615195 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[150]} 0 0
[junit4] 2> 1615196 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[151]} 0 0
[junit4] 2> 1615197 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[152]} 0 0
[junit4] 2> 1615199 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[153]} 0 0
[junit4] 2> 1615199 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[154]} 0 0
[junit4] 2> 1615200 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[155]} 0 0
[junit4] 2> 1615201 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[156]} 0 0
[junit4] 2> 1615202 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[157]} 0 0
[junit4] 2> 1615202 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[158]} 0 0
[junit4] 2> 1615203 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[159]} 0 0
[junit4] 2> 1615204 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[160]} 0 0
[junit4] 2> 1615204 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[161]} 0 0
[junit4] 2> 1615205 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[162]} 0 0
[junit4] 2> 1615206 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[163]} 0 0
[junit4] 2> 1615207 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[164]} 0 0
[junit4] 2> 1615207 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[165]} 0 0
[junit4] 2> 1615207 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[166]} 0 0
[junit4] 2> 1615209 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[167]} 0 0
[junit4] 2> 1615209 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[168]} 0 0
[junit4] 2> 1615210 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[169]} 0 0
[junit4] 2> 1615211 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[170]} 0 0
[junit4] 2> 1615211 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[171]} 0 0
[junit4] 2> 1615212 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[172]} 0 0
[junit4] 2> 1615213 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[173]} 0 0
[junit4] 2> 1615214 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[174]} 0 0
[junit4] 2> 1615214 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[175]} 0 0
[junit4] 2> 1615215 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[176]} 0 0
[junit4] 2> 1615216 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[177]} 0 0
[junit4] 2> 1615217 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[178]} 0 0
[junit4] 2> 1615217 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[179]} 0 0
[junit4] 2> 1615218 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[180]} 0 0
[junit4] 2> 1615219 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[181]} 0 0
[junit4] 2> 1615220 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[182]} 0 0
[junit4] 2> 1615221 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[183]} 0 0
[junit4] 2> 1615222 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[184]} 0 0
[junit4] 2> 1615223 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[185]} 0 0
[junit4] 2> 1615225 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[186]} 0 0
[junit4] 2> 1615225 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[187]} 0 0
[junit4] 2> 1615226 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[188]} 0 0
[junit4] 2> 1615227 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[189]} 0 0
[junit4] 2> 1615228 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[190]} 0 0
[junit4] 2> 1615228 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[191]} 0 0
[junit4] 2> 1615229 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[192]} 0 0
[junit4] 2> 1615230 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[193]} 0 0
[junit4] 2> 1615231 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[194]} 0 0
[junit4] 2> 1615231 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[195]} 0 0
[junit4] 2> 1615232 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[196]} 0 0
[junit4] 2> 1615233 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[197]} 0 0
[junit4] 2> 1615233 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[198]} 0 0
[junit4] 2> 1615234 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[199]} 0 0
[junit4] 2> 1615235 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[200]} 0 0
[junit4] 2> 1615236 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[201]} 0 0
[junit4] 2> 1615236 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[202]} 0 0
[junit4] 2> 1615237 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[203]} 0 0
[junit4] 2> 1615238 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[204]} 0 0
[junit4] 2> 1615239 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[205]} 0 0
[junit4] 2> 1615239 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[206]} 0 0
[junit4] 2> 1615240 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[207]} 0 0
[junit4] 2> 1615241 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[208]} 0 0
[junit4] 2> 1615241 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[209]} 0 0
[junit4] 2> 1615242 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[210]} 0 0
[junit4] 2> 1615243 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[211]} 0 0
[junit4] 2> 1615243 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[212]} 0 0
[junit4] 2> 1615244 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[213]} 0 0
[junit4] 2> 1615245 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[214]} 0 0
[junit4] 2> 1615245 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[215]} 0 0
[junit4] 2> 1615247 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[216]} 0 0
[junit4] 2> 1615248 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[217]} 0 0
[junit4] 2> 1615249 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[218]} 0 0
[junit4] 2> 1615249 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[219]} 0 0
[junit4] 2> 1615250 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[220]} 0 0
[junit4] 2> 1615250 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[221]} 0 0
[junit4] 2> 1615251 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[222]} 0 0
[junit4] 2> 1615251 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[223]} 0 0
[junit4] 2> 1615252 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[224]} 0 0
[junit4] 2> 1615253 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[225]} 0 0
[junit4] 2> 1615254 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[226]} 0 0
[junit4] 2> 1615254 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[227]} 0 0
[junit4] 2> 1615255 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[228]} 0 0
[junit4] 2> 1615256 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[229]} 0 0
[junit4] 2> 1615257 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[230]} 0 0
[junit4] 2> 1615257 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[231]} 0 0
[junit4] 2> 1615258 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[232]} 0 0
[junit4] 2> 1615259 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[233]} 0 0
[junit4] 2> 1615260 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[234]} 0 0
[junit4] 2> 1615260 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[235]} 0 0
[junit4] 2> 1615261 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[236]} 0 0
[junit4] 2> 1615261 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[237]} 0 0
[junit4] 2> 1615262 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[238]} 0 0
[junit4] 2> 1615263 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[239]} 0 0
[junit4] 2> 1615263 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[240]} 0 0
[junit4] 2> 1615264 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[241]} 0 0
[junit4] 2> 1615265 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[242]} 0 0
[junit4] 2> 1615265 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[243]} 0 0
[junit4] 2> 1615266 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[244]} 0 0
[junit4] 2> 1615267 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[245]} 0 0
[junit4] 2> 1615268 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[246]} 0 0
[junit4] 2> 1615269 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[247]} 0 0
[junit4] 2> 1615269 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[248]} 0 0
[junit4] 2> 1615270 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[249]} 0 0
[junit4] 2> 1615271 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[250]} 0 0
[junit4] 2> 1615271 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[251]} 0 0
[junit4] 2> 1615273 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[252]} 0 0
[junit4] 2> 1615273 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[253]} 0 0
[junit4] 2> 1615273 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[254]} 0 0
[junit4] 2> 1615275 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[255]} 0 0
[junit4] 2> 1615275 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[256]} 0 0
[junit4] 2> 1615275 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[257]} 0 0
[junit4] 2> 1615277 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[258]} 0 0
[junit4] 2> 1615277 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[259]} 0 0
[junit4] 2> 1615277 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[260]} 0 0
[junit4] 2> 1615278 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[261]} 0 0
[junit4] 2> 1615279 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[262]} 0 0
[junit4] 2> 1615280 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[263]} 0 0
[junit4] 2> 1615280 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[264]} 0 0
[junit4] 2> 1615282 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[265]} 0 0
[junit4] 2> 1615283 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[266]} 0 0
[junit4] 2> 1615284 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[267]} 0 0
[junit4] 2> 1615284 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[268]} 0 0
[junit4] 2> 1615285 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[269]} 0 0
[junit4] 2> 1615286 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[270]} 0 0
[junit4] 2> 1615286 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[271]} 0 0
[junit4] 2> 1615287 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[272]} 0 0
[junit4] 2> 1615288 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[273]} 0 0
[junit4] 2> 1615289 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[274]} 0 0
[junit4] 2> 1615289 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[275]} 0 0
[junit4] 2> 1615290 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[276]} 0 0
[junit4] 2> 1615290 INFO (qtp24389992-12972) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[277]} 0 0
[junit4] 2> 1615291 INFO (qtp24389992-12974) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[278]} 0 0
[junit4] 2> 1615292 INFO (qtp24389992-12975) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[279]} 0 0
[junit4] 2> 1615293 INFO (qtp24389992-12976) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[280]} 0 0
[junit4] 2> 1615294 INFO (qtp24389992-12977) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[281]} 0 0
[junit4] 2> 1615294 INFO (qtp24389992-12978) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[282]} 0 0
[junit4] 2> 1615295 INFO (qtp24389992-12979) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[283]} 0 0
[junit4] 2> 1615295 INFO (qtp24389992
[...truncated too long message...]
l path=/update params={commit=true&softCommit=false&waitSearcher=true}{add=[BBB (1584192276858404864)],commit=} 0 24
[junit4] 2> 43526 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0
[junit4] 2> 43527 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0
[junit4] 2> 43528 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0
[junit4] 2> 43528 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=0 status=0 QTime=0
[junit4] 2> 43528 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:AAA} hits=0 status=0 QTime=0
[junit4] 2> 43529 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.c.S.Request [core1] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0
[junit4] 2> 43538 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.u.DirectUpdateHandler2 start mergeIndexes{SimpleFSDirectory@C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-006\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@15027f7}
[junit4] 2> 43554 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.u.DirectUpdateHandler2 end_mergeIndexes
[junit4] 2> 43555 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1584192276915027968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 43555 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1312540 commitCommandVersion:1584192276915027968
[junit4] 2> 43571 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@70484[core0] main]
[junit4] 2> 43572 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 43573 INFO (searcherExecutor-96-thread-1) [ ] o.a.s.c.SolrCore [core0] Registered new searcher Searcher@70484[core0] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1)))}
[junit4] 2> 43574 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [core0] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true}{commit=} 0 18
[junit4] 2> 43574 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:AAA} hits=1 status=0 QTime=0
[junit4] 2> 43575 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.c.S.Request [core0] webapp=null path=/select params={q=id:BBB} hits=1 status=0 QTime=0
[junit4] 2> 43576 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMergeIndexesByDirName
[junit4] 2> 43576 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=5020349
[junit4] 2> 43576 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 43576 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d341d2: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@144c6fe
[junit4] 2> 43583 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 43583 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b9cdc: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@12aaf3d
[junit4] 2> 43592 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 43592 INFO (TEST-MergeIndexesEmbeddedTest.testMergeIndexesByDirName-seed#[B61D6E2685DD83C9]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@30c4a6: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@12cda38
[junit4] 2> 43593 INFO (coreCloseExecutor-106-thread-1) [ x:core1] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@80ab4d
[junit4] 2> 43593 INFO (coreCloseExecutor-106-thread-2) [ x:core0] o.a.s.c.SolrCore [core0] CLOSING SolrCore org.apache.solr.core.SolrCore@10331a3
[junit4] 2> 43593 INFO (coreCloseExecutor-106-thread-1) [ x:core1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core1, tag=8432461
[junit4] 2> 43594 INFO (coreCloseExecutor-106-thread-1) [ x:core1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c0788: rootName = null, domain = solr.core.core1, service url = null, agent id = null] for registry solr.core.core1 / com.codahale.metrics.MetricRegistry@66bc03
[junit4] 2> 43606 INFO (coreCloseExecutor-106-thread-2) [ x:core0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core0, tag=16986531
[junit4] 2> 43606 INFO (coreCloseExecutor-106-thread-2) [ x:core0] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@bee5db: rootName = null, domain = solr.core.core0, service url = null, agent id = null] for registry solr.core.core0 / com.codahale.metrics.MetricRegistry@127e7ad
[junit4] 2> 43625 INFO (SUITE-MergeIndexesEmbeddedTest-seed#[B61D6E2685DD83C9]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name=PostingsFormat(name=Direct), id=PostingsFormat(name=Direct)}, docValues:{_version_=DocValuesFormat(name=Direct), name=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1462, maxMBSortInHeap=5.889002678268955, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@bb17cb), locale=ms, timezone=Turkey
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=18495560,total=52490240
[junit4] 2> NOTE: All tests run in this JVM: [LargeVolumeJettyTest, StreamExpressionToExplanationTest, BasicHttpSolrClientTest, LBHttpSolrClientBuilderTest, SolrExampleBinaryTest, SolrSchemalessExampleTest, ContentStreamTest, HyperbolicTangentEvaluatorTest, SolrZkClientTest, CorrelationEvaluatorTest, StreamExpressionParserTest, QueryResponseTest, DivideEvaluatorTest, OrEvaluatorTest, MergeIndexesEmbeddedTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MergeIndexesEmbeddedTest -Dtests.seed=B61D6E2685DD83C9 -Dtests.slow=true -Dtests.locale=ms -Dtests.timezone=Turkey -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | MergeIndexesEmbeddedTest (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005\index: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005\index
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.MergeIndexesEmbeddedTest_B61D6E2685DD83C9-001\tempDir-005
[junit4] > at __randomizedtesting.SeedInfo.seed([B61D6E2685DD83C9]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [33/146 (1!)] on J1 in 3.45s, 3 tests, 1 error <<< FAILURES!
[...truncated 42461 lines...]