Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/456/
Java: 64bit/jdk-9.0.1 -XX:+UseCompressedOops -XX:+UseSerialGC
3 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.LegacyCloudClusterPropTest
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.cloud.LegacyCloudClusterPropTest: 1) Thread[id=434,
name=qtp1682143640-434, state=TIMED_WAITING,
group=TGRP-LegacyCloudClusterPropTest] at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method) at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192)
at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.cloud.LegacyCloudClusterPropTest:
1) Thread[id=434, name=qtp1682143640-434, state=TIMED_WAITING,
group=TGRP-LegacyCloudClusterPropTest]
at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192)
at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
at __randomizedtesting.SeedInfo.seed([6FA3C0A887B255FB]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.LegacyCloudClusterPropTest
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=434, name=qtp1682143640-434, state=TIMED_WAITING,
group=TGRP-LegacyCloudClusterPropTest] at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method) at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192)
at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=434, name=qtp1682143640-434, state=TIMED_WAITING,
group=TGRP-LegacyCloudClusterPropTest]
at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192)
at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
at __randomizedtesting.SeedInfo.seed([6FA3C0A887B255FB]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory
Error Message:
Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-sent.bin:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-sent.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-tokenizer.bin:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-ner-person.bin:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-ner-person.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of
attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-sent.bin:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-sent.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-tokenizer.bin:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-ner-person.bin:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-ner-person.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001
at __randomizedtesting.SeedInfo.seed([54DD56B006A4AC23]: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.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12173 lines...]
[junit4] Suite: org.apache.solr.cloud.LegacyCloudClusterPropTest
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\init-core-data-001
[junit4] 2> 29658 INFO
(SUITE-LegacyCloudClusterPropTest-seed#[6FA3C0A887B255FB]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 29659 INFO
(SUITE-LegacyCloudClusterPropTest-seed#[6FA3C0A887B255FB]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 29660 INFO
(SUITE-LegacyCloudClusterPropTest-seed#[6FA3C0A887B255FB]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 29661 INFO
(SUITE-LegacyCloudClusterPropTest-seed#[6FA3C0A887B255FB]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001
[junit4] 2> 29661 INFO
(SUITE-LegacyCloudClusterPropTest-seed#[6FA3C0A887B255FB]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 29661 INFO (Thread-80) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 29661 INFO (Thread-80) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 29667 ERROR (Thread-80) [ ] 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> 29762 INFO
(SUITE-LegacyCloudClusterPropTest-seed#[6FA3C0A887B255FB]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:63061
[junit4] 2> 29766 INFO (zkConnectionManagerCallback-84-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 29771 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100841948470000, likely client has closed socket
[junit4] 2> 29801 INFO (jetty-launcher-81-thread-1) [ ] o.e.j.s.Server
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash:
82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 29801 INFO (jetty-launcher-81-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 29801 INFO (jetty-launcher-81-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 29801 INFO (jetty-launcher-81-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 29802 INFO (jetty-launcher-81-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6b7308da{/solr,null,AVAILABLE}
[junit4] 2> 29804 INFO (jetty-launcher-81-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@65e306ef{SSL,[ssl,
http/1.1]}{127.0.0.1:63065}
[junit4] 2> 29804 INFO (jetty-launcher-81-thread-1) [ ] o.e.j.s.Server
Started @39725ms
[junit4] 2> 29804 INFO (jetty-launcher-81-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63065}
[junit4] 2> 29805 ERROR (jetty-launcher-81-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 29805 INFO (jetty-launcher-81-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 29805 INFO (jetty-launcher-81-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 29805 INFO (jetty-launcher-81-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 29805 INFO (jetty-launcher-81-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T15:20:16.779876800Z
[junit4] 2> 29809 INFO (zkConnectionManagerCallback-86-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 29810 INFO (jetty-launcher-81-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 29820 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100841948470001, likely client has closed socket
[junit4] 2> 29823 INFO (jetty-launcher-81-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63061/solr
[junit4] 2> 29826 INFO (zkConnectionManagerCallback-90-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 29828 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100841948470002, likely client has closed socket
[junit4] 2> 29831 INFO
(zkConnectionManagerCallback-92-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 29952 INFO (jetty-launcher-81-thread-1)
[n:127.0.0.1:63065_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 29952 INFO (jetty-launcher-81-thread-1)
[n:127.0.0.1:63065_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:63065_solr
[junit4] 2> 29954 INFO (jetty-launcher-81-thread-1)
[n:127.0.0.1:63065_solr ] o.a.s.c.Overseer Overseer
(id=72202838159589379-127.0.0.1:63065_solr-n_0000000000) starting
[junit4] 2> 29963 INFO (jetty-launcher-81-thread-1)
[n:127.0.0.1:63065_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63065_solr
[junit4] 2> 29968 INFO
(OverseerStateUpdate-72202838159589379-127.0.0.1:63065_solr-n_0000000000)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 30031 INFO (jetty-launcher-81-thread-1)
[n:127.0.0.1:63065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 30057 INFO (jetty-launcher-81-thread-1)
[n:127.0.0.1:63065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 30057 INFO (jetty-launcher-81-thread-1)
[n:127.0.0.1:63065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 30059 INFO (jetty-launcher-81-thread-1)
[n:127.0.0.1:63065_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\.
[junit4] 2> 30098 INFO (zkConnectionManagerCallback-96-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30117 INFO (zkConnectionManagerCallback-100-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30121 INFO
(SUITE-LegacyCloudClusterPropTest-seed#[6FA3C0A887B255FB]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 30124 INFO
(SUITE-LegacyCloudClusterPropTest-seed#[6FA3C0A887B255FB]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63061/solr ready
[junit4] 2> 30141 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionSwitchLegacyCloud
[junit4] 2> 30203 INFO (qtp1682143640-440) [n:127.0.0.1:63065_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params
val=true&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 30213 INFO (qtp1682143640-440) [n:127.0.0.1:63065_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={val=true&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2}
status=0 QTime=9
[junit4] 2> 30223 INFO (qtp1682143640-430) [n:127.0.0.1:63065_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=legacyTrue&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 30231 INFO
(OverseerThreadFactory-177-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
legacyTrue
[junit4] 2> 30359 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=legacyTrue_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyTrue&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 30360 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 30472 INFO
(zkCallback-91-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyTrue/state.json] for collection [legacyTrue] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 31384 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.3.0
[junit4] 2> 31413 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.IndexSchema
[legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 31417 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 31417 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.CoreContainer
Creating SolrCore 'legacyTrue_shard1_replica_n1' using configuration from
collection legacyTrue, trusted=true
[junit4] 2> 31423 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.core.legacyTrue.shard1.replica_n1' (registry
'solr.core.legacyTrue.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 31423 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 31423 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore
[[legacyTrue_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\legacyTrue_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\.\legacyTrue_shard1_replica_n1\data\]
[junit4] 2> 31632 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 31632 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 31635 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 31636 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 31648 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@70488b68[legacyTrue_shard1_replica_n1] main]
[junit4] 2> 31656 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 31656 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 31658 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 31658 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1592480779345068032
[junit4] 2> 31690 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 31690 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 31690 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy
Sync replicas to https://127.0.0.1:63065/solr/legacyTrue_shard1_replica_n1/
[junit4] 2> 31690 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 31690 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy
https://127.0.0.1:63065/solr/legacyTrue_shard1_replica_n1/ has no replicas
[junit4] 2> 31690 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 31695 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:63065/solr/legacyTrue_shard1_replica_n1/ shard1
[junit4] 2> 31698 INFO
(searcherExecutor-180-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore
[legacyTrue_shard1_replica_n1] Registered new searcher
Searcher@70488b68[legacyTrue_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 31855 INFO
(zkCallback-91-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyTrue/state.json] for collection [legacyTrue] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 31860 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 31887 INFO (qtp1682143640-436) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=legacyTrue_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyTrue&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1528
[junit4] 2> 31908 INFO (qtp1682143640-430) [n:127.0.0.1:63065_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 32220 INFO
(zkCallback-91-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyTrue/state.json] for collection [legacyTrue] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 32246 INFO
(OverseerCollectionConfigSetProcessor-72202838159589379-127.0.0.1:63065_solr-n_0000000000)
[n:127.0.0.1:63065_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> 32910 INFO (qtp1682143640-430) [n:127.0.0.1:63065_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=legacyTrue&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=2686
[junit4] 2> 32934 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@65e306ef{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 32935 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=704964479
[junit4] 2> 32935 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 32936 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@50d1a9be: rootName =
solr_63065, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@88eb8be
[junit4] 2> 32944 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 32944 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@27928da9: rootName =
solr_63065, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@230a57fa
[junit4] 2> 32950 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 32950 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7e1e7d30: rootName =
solr_63065, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@571a59e4
[junit4] 2> 32952 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:63065_solr
[junit4] 2> 32954 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 32955 INFO (zkCallback-99-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 32955 INFO
(zkCallback-91-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 32960 INFO (coreCloseExecutor-185-thread-1)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1]
CLOSING SolrCore org.apache.solr.core.SolrCore@71f58544
[junit4] 2> 32960 INFO (coreCloseExecutor-185-thread-1)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=1911915844
[junit4] 2> 32960 INFO (coreCloseExecutor-185-thread-1)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@89e99af: rootName =
solr_63065, domain = solr.core.legacyTrue.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.legacyTrue.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@5182150c
[junit4] 2> 32974 INFO (coreCloseExecutor-185-thread-1)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.legacyTrue.shard1.leader, tag=1911915844
[junit4] 2> 32987 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.Overseer Overseer
(id=72202838159589379-127.0.0.1:63065_solr-n_0000000000) closing
[junit4] 2> 32987 INFO
(OverseerStateUpdate-72202838159589379-127.0.0.1:63065_solr-n_0000000000)
[n:127.0.0.1:63065_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:63065_solr
[junit4] 2> 32989 WARN
(OverseerAutoScalingTriggerThread-72202838159589379-127.0.0.1:63065_solr-n_0000000000)
[n:127.0.0.1:63065_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 32993 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6b7308da{/solr,null,UNAVAILABLE}
[junit4] 2> 32993 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 62993 WARN
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.u.t.QueuedThreadPool
QueuedThreadPool@qtp1682143640{STOPPING,8<=10<=10000,i=0,q=1} Couldn't stop
Thread[qtp1682143640-434,5,TGRP-LegacyCloudClusterPropTest]
[junit4] 2> 62996 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 62996 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 62996 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 62997 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 62997 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@17888f0b{/solr,null,AVAILABLE}
[junit4] 2> 62999 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@e8b84fb{SSL,[ssl,
http/1.1]}{127.0.0.1:63065}
[junit4] 2> 62999 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.Server Started @72919ms
[junit4] 2> 62999 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63065}
[junit4] 2> 63000 ERROR
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 63000 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 7.3.0
[junit4] 2> 63000 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 63000 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 63000 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T15:20:49.974125800Z
[junit4] 2> 63004 INFO (zkConnectionManagerCallback-102-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 63005 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 63017 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63061/solr
[junit4] 2> 63021 INFO (zkConnectionManagerCallback-106-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 63023 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100841948470007, likely client has closed socket
[junit4] 2> 63029 INFO
(zkConnectionManagerCallback-108-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 63048 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 63049 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:63065_solr
[junit4] 2> 63050 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.Overseer Overseer
(id=72202838159589384-127.0.0.1:63065_solr-n_0000000001) starting
[junit4] 2> 63063 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63065_solr
[junit4] 2> 63066 INFO
(OverseerStateUpdate-72202838159589384-127.0.0.1:63065_solr-n_0000000001)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 63066 INFO (zkCallback-99-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 63132 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 63152 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 63153 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 63157 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\.
[junit4] 2> 63159 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.CorePropertiesLocator Cores are:
[legacyTrue_shard1_replica_n1]
[junit4] 2> 63161 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 63183 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 63194 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1]
Schema name=minimal
[junit4] 2> 63205 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with
uniqueid field id
[junit4] 2> 63223 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 63232 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 63240 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 63240 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'legacyTrue_shard1_replica_n1' using
configuration from collection legacyTrue, trusted=true
[junit4] 2> 63241 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.core.legacyTrue.shard1.replica_n1' (registry
'solr.core.legacyTrue.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 63241 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 63241 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.c.SolrCore [[legacyTrue_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\legacyTrue_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\.\legacyTrue_shard1_replica_n1\data\]
[junit4] 2> 63316 INFO
(zkCallback-107-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyTrue/state.json] for collection [legacyTrue] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 63347 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 63347 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 63347 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 63347 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 63349 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@5f71ad9e[legacyTrue_shard1_replica_n1] main]
[junit4] 2> 63351 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 63352 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 63352 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 63352 INFO
(coreLoadExecutor-196-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1592480812578635776
[junit4] 2> 63355 INFO
(searcherExecutor-197-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore
[legacyTrue_shard1_replica_n1] Registered new searcher
Searcher@5f71ad9e[legacyTrue_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 63359 INFO
(coreZkRegister-187-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 63359 INFO
(coreZkRegister-187-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 63359 INFO
(coreZkRegister-187-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:63065/solr/legacyTrue_shard1_replica_n1/
[junit4] 2> 63359 INFO
(coreZkRegister-187-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 63359 INFO
(coreZkRegister-187-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1]
o.a.s.c.SyncStrategy https://127.0.0.1:63065/solr/legacyTrue_shard1_replica_n1/
has no replicas
[junit4] 2> 63359 INFO
(coreZkRegister-187-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 63361 INFO
(coreZkRegister-187-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:63065/solr/legacyTrue_shard1_replica_n1/ shard1
[junit4] 2> 63363 INFO
(coreZkRegister-187-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:63065_solr
c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 63471 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyTrue/state.json] for collection [legacyTrue] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 64379 INFO (qtp345002622-486) [n:127.0.0.1:63065_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params
val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 64380 INFO (qtp345002622-486) [n:127.0.0.1:63065_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 64384 INFO (qtp345002622-485) [n:127.0.0.1:63065_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=legacyFalse&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 64402 INFO
(OverseerThreadFactory-194-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
legacyFalse
[junit4] 2> 64618 INFO
(OverseerStateUpdate-72202838159589384-127.0.0.1:63065_solr-n_0000000001)
[n:127.0.0.1:63065_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"legacyFalse",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"legacyFalse_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:63065/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 64827 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=legacyFalse_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyFalse&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 64934 INFO
(zkCallback-107-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyFalse/state.json] for collection [legacyFalse] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 64934 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyFalse/state.json] for collection [legacyFalse] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 65842 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 65849 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.s.IndexSchema [legacyFalse_shard1_replica_n1] Schema name=minimal
[junit4] 2> 65859 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 65859 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'legacyFalse_shard1_replica_n1' using
configuration from collection legacyFalse, trusted=true
[junit4] 2> 65860 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.core.legacyFalse.shard1.replica_n1' (registry
'solr.core.legacyFalse.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 65860 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 65860 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.SolrCore [[legacyFalse_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\legacyFalse_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\.\legacyFalse_shard1_replica_n1\data\]
[junit4] 2> 65955 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 65955 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 65957 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 65957 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 65960 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@40a87ffb[legacyFalse_shard1_replica_n1] main]
[junit4] 2> 65961 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 65962 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 65962 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 65963 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1592480815316467712
[junit4] 2> 65964 INFO
(searcherExecutor-202-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyFalse_shard1_replica_n1 s:shard1 c:legacyFalse r:core_node2)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1 r:core_node2
x:legacyFalse_shard1_replica_n1] o.a.s.c.SolrCore
[legacyFalse_shard1_replica_n1] Registered new searcher
Searcher@40a87ffb[legacyFalse_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 65975 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 65975 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 65975 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:63065/solr/legacyFalse_shard1_replica_n1/
[junit4] 2> 65975 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 65975 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.SyncStrategy
https://127.0.0.1:63065/solr/legacyFalse_shard1_replica_n1/ has no replicas
[junit4] 2> 65975 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 65978 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:63065/solr/legacyFalse_shard1_replica_n1/ shard1
[junit4] 2> 66083 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyFalse/state.json] for collection [legacyFalse] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 66083 INFO
(zkCallback-107-thread-3-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyFalse/state.json] for collection [legacyFalse] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 66131 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 66136 INFO (qtp345002622-488) [n:127.0.0.1:63065_solr
c:legacyFalse s:shard1 r:core_node2 x:legacyFalse_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=legacyFalse_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyFalse&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1309
[junit4] 2> 66141 INFO (qtp345002622-485) [n:127.0.0.1:63065_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 66237 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyFalse/state.json] for collection [legacyFalse] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 66237 INFO
(zkCallback-107-thread-3-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/legacyFalse/state.json] for collection [legacyFalse] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 66406 INFO
(OverseerCollectionConfigSetProcessor-72202838159589384-127.0.0.1:63065_solr-n_0000000001)
[n:127.0.0.1:63065_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> 67142 INFO (qtp345002622-485) [n:127.0.0.1:63065_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=legacyFalse&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=2758
[junit4] 2> 67162 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@e8b84fb{SSL,[ssl,
http/1.1]}{127.0.0.1:63065}
[junit4] 2> 67162 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2029180137
[junit4] 2> 67162 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 67163 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4853c522: rootName =
solr_63065, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@6617332b
[junit4] 2> 67169 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 67169 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1a250976: rootName =
solr_63065, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@230a57fa
[junit4] 2> 67176 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 67176 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@73de48e2: rootName =
solr_63065, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@571a59e4
[junit4] 2> 67177 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:63065_solr
[junit4] 2> 67178 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 67179 INFO (zkCallback-99-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 67181 INFO
(zkCallback-107-thread-3-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 67181 INFO (coreCloseExecutor-207-thread-1)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1]
CLOSING SolrCore org.apache.solr.core.SolrCore@59ed0ad0
[junit4] 2> 67181 INFO (coreCloseExecutor-207-thread-2)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1 r:core_node2
x:legacyFalse_shard1_replica_n1] o.a.s.c.SolrCore
[legacyFalse_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@14920325
[junit4] 2> 67181 INFO (coreCloseExecutor-207-thread-2)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1 r:core_node2
x:legacyFalse_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.legacyFalse.shard1.replica_n1, tag=345113381
[junit4] 2> 67181 INFO (coreCloseExecutor-207-thread-2)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1 r:core_node2
x:legacyFalse_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@69616743: rootName =
solr_63065, domain = solr.core.legacyFalse.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.legacyFalse.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@5903cdca
[junit4] 2> 67189 INFO (coreCloseExecutor-207-thread-2)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1 r:core_node2
x:legacyFalse_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.legacyFalse.shard1.leader, tag=345113381
[junit4] 2> 67189 INFO (coreCloseExecutor-207-thread-1)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=1508707024
[junit4] 2> 67189 INFO (coreCloseExecutor-207-thread-1)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4a88c57c: rootName =
solr_63065, domain = solr.core.legacyTrue.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.legacyTrue.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@49fdba6d
[junit4] 2> 67201 INFO (coreCloseExecutor-207-thread-1)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.legacyTrue.shard1.leader, tag=1508707024
[junit4] 2> 67205 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.Overseer Overseer
(id=72202838159589384-127.0.0.1:63065_solr-n_0000000001) closing
[junit4] 2> 67207 INFO
(OverseerStateUpdate-72202838159589384-127.0.0.1:63065_solr-n_0000000001)
[n:127.0.0.1:63065_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:63065_solr
[junit4] 2> 67208 WARN
(OverseerAutoScalingTriggerThread-72202838159589384-127.0.0.1:63065_solr-n_0000000001)
[n:127.0.0.1:63065_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 67211 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100841948470008, likely client has closed socket
[junit4] 2> 67212 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@17888f0b{/solr,null,UNAVAILABLE}
[junit4] 2> 67212 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 67215 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 67215 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 67215 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 67215 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 67216 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@50bc299b{/solr,null,AVAILABLE}
[junit4] 2> 67218 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@94a9d73{SSL,[ssl,
http/1.1]}{127.0.0.1:63065}
[junit4] 2> 67218 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.e.j.s.Server Started @77137ms
[junit4] 2> 67218 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63065}
[junit4] 2> 67218 ERROR
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 67218 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 7.3.0
[junit4] 2> 67218 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 67219 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 67219 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T15:20:54.193072100Z
[junit4] 2> 67221 INFO (zkConnectionManagerCallback-112-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 67223 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 67235 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63061/solr
[junit4] 2> 67238 INFO (zkConnectionManagerCallback-116-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 67240 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10084194847000a, likely client has closed socket
[junit4] 2> 67245 INFO
(zkConnectionManagerCallback-118-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 67267 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 67272 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:63065_solr
[junit4] 2> 67274 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.Overseer Overseer
(id=72202838159589387-127.0.0.1:63065_solr-n_0000000002) starting
[junit4] 2> 67289 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63065_solr
[junit4] 2> 67293 INFO (zkCallback-99-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 67293 INFO
(OverseerStateUpdate-72202838159589387-127.0.0.1:63065_solr-n_0000000002)
[n:127.0.0.1:63065_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 67384 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 67396 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 67396 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 67400 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.CorePropertiesLocator Found 2 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\.
[junit4] 2> 67400 INFO
(TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[6FA3C0A887B255FB])
[n:127.0.0.1:63065_solr ] o.a.s.c.CorePropertiesLocator Cores are:
[legacyFalse_shard1_replica_n1, legacyTrue_shard1_replica_n1]
[junit4] 2> 67427 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 67470 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 67506 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 67519 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1]
Schema name=minimal
[junit4] 2> 67527 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with
uniqueid field id
[junit4] 2> 67531 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.s.IndexSchema
[legacyFalse_shard1_replica_n1] Schema name=minimal
[junit4] 2> 67538 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with
uniqueid field id
[junit4] 2> 67540 WARN
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.c.CoreContainer Core properties file for
node legacyFalse_shard1_replica_n1 found with no coreNodeName, attempting to
repair with value core_node2. See SOLR-11503. This message should only appear
if upgrading from collections created Solr 6.6.1 through 7.1.
[junit4] 2> 67545 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
7.3.0
[junit4] 2> 67563 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.s.IndexSchema
[legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 67565 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1
with uniqueid field id
[junit4] 2> 67565 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore
'legacyTrue_shard1_replica_n1' using configuration from collection legacyTrue,
trusted=true
[junit4] 2> 67565 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.core.legacyTrue.shard1.replica_n1' (registry
'solr.core.legacyTrue.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 67567 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 67567 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore
[[legacyTrue_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\legacyTrue_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\.\legacyTrue_shard1_replica_n1\data\]
[junit4] 2> 67571 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
7.3.0
[junit4] 2> 67600 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.s.IndexSchema
[legacyFalse_shard1_replica_n1] Schema name=minimal
[junit4] 2> 67603 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1
with uniqueid field id
[junit4] 2> 67603 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore
'legacyFalse_shard1_replica_n1' using configuration from collection
legacyFalse, trusted=true
[junit4] 2> 67603 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63065.solr.core.legacyFalse.shard1.replica_n1' (registry
'solr.core.legacyFalse.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7eb33c46
[junit4] 2> 67604 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 67604 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.c.SolrCore
[[legacyFalse_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\legacyFalse_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_6FA3C0A887B255FB-001\tempDir-001\node1\.\legacyFalse_shard1_replica_n1\data\]
[junit4] 2> 67682 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 67682 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 67683 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 67683 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 67684 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@39eb1077[legacyTrue_shard1_replica_n1] main]
[junit4] 2> 67687 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 67687 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 67687 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000ms.
[junit4] 2> 67688 INFO
(coreLoadExecutor-218-thread-2-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in
index or recent updates, using new clock 1592480817125261312
[junit4] 2> 67705 INFO
(searcherExecutor-219-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue r:core_node2)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1]
Registered new searcher Searcher@39eb1077[legacyTrue_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 67715 INFO
(coreZkRegister-209-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue r:core_node2)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 67715 INFO
(coreZkRegister-209-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue r:core_node2)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the
new leader - try and sync
[junit4] 2> 67715 INFO
(coreZkRegister-209-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue r:core_node2)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:63065/solr/legacyTrue_shard1_replica_n1/
[junit4] 2> 67715 INFO
(coreZkRegister-209-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue r:core_node2)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 67715 INFO
(coreZkRegister-209-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue r:core_node2)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy
https://127.0.0.1:63065/solr/legacyTrue_shard1_replica_n1/ has no replicas
[junit4] 2> 67715 INFO
(coreZkRegister-209-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue r:core_node2)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 67720 INFO
(coreZkRegister-209-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue r:core_node2)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: https://127.0.0.1:63065/solr/legacyTrue_shard1_replica_n1/ shard1
[junit4] 2> 67720 INFO
(coreZkRegister-209-thread-1-processing-n:127.0.0.1:63065_solr
x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue r:core_node2)
[n:127.0.0.1:63065_solr c:legacyTrue s:shard1 r:core_node2
x:legacyTrue_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 67734 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 67734 INFO
(coreLoadExecutor-218-thread-1-processing-n:127.0.0.1:63065_solr)
[n:127.0.0.1:63065_solr c:legacyFalse s:shard1
x:legacyFalse_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNum
[...truncated too long message...]
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native
Method)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192)
[junit4] 2> at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at
app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at
app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at
java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1700, maxMBSortInHeap=7.700293901481288,
sim=RandomSimilarity(queryNorm=false): {}, locale=fo-DK, timezone=Libya
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.1
(64-bit)/cpus=3,threads=2,free=53300320,total=129048576
[junit4] 2> NOTE: All tests run in this JVM: [SuggesterFSTTest,
FastVectorHighlighterTest, DeleteNodeTest,
OverseerCollectionConfigSetProcessorTest, TestPartialUpdateDeduplication,
TestSearcherReuse, TestClusterStateMutator, TestExecutePlanAction,
BadCopyFieldTest, LegacyCloudClusterPropTest]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=LegacyCloudClusterPropTest -Dtests.seed=6FA3C0A887B255FB
-Dtests.slow=true -Dtests.locale=fo-DK -Dtests.timezone=Libya
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J0 | LegacyCloudClusterPropTest (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.cloud.LegacyCloudClusterPropTest:
[junit4] > 1) Thread[id=434, name=qtp1682143640-434,
state=TIMED_WAITING, group=TGRP-LegacyCloudClusterPropTest]
[junit4] > at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
[junit4] > at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192)
[junit4] > at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at
app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at
app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at
java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] > at
__randomizedtesting.SeedInfo.seed([6FA3C0A887B255FB]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=434, name=qtp1682143640-434,
state=TIMED_WAITING, group=TGRP-LegacyCloudClusterPropTest]
[junit4] > at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
[junit4] > at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192)
[junit4] > at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at
app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at
app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at
app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at
java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] > at
__randomizedtesting.SeedInfo.seed([6FA3C0A887B255FB]:0)
[junit4] Completed [21/775 (1!)] on J0 in 62.29s, 1 test, 2 errors <<<
FAILURES!
[...truncated 4810 lines...]
[junit4] Suite:
org.apache.solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\init-core-data-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1992, maxMBSortInHeap=7.269562119964301,
sim=RandomSimilarity(queryNorm=true): {}, locale=hr-BA, timezone=Europe/Guernsey
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.1
(64-bit)/cpus=3,threads=1,free=54101648,total=97386496
[junit4] 2> NOTE: All tests run in this JVM:
[TestFoldingMultitermExtrasQuery,
TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory
-Dtests.seed=54DD56B006A4AC23 -Dtests.slow=true -Dtests.locale=hr-BA
-Dtests.timezone=Europe/Guernsey -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 |
TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the
following files (in the order of attempts):
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-sent.bin:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-sent.bin
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-tokenizer.bin:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-ner-person.bin:
java.nio.file.AccessDeniedException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001\tempDir-001\collection1\conf\en-test-ner-person.bin
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_54DD56B006A4AC23-001
[junit4] > at
__randomizedtesting.SeedInfo.seed([54DD56B006A4AC23]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] Completed [4/5 (1!)] on J1 in 1.18s, 8 tests, 1 error <<< FAILURES!
[...truncated 38829 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
