Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/924/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.TestPolicyCloud.testDataProviderPerReplicaDetails
Error Message:
Could not find collection : perReplicaDataColl
Stack Trace:
org.apache.solr.common.SolrException: Could not find collection :
perReplicaDataColl
at
__randomizedtesting.SeedInfo.seed([EC058FBC118CA421:10521A757FA0D0BE]:0)
at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:118)
at
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:258)
at
org.apache.solr.cloud.autoscaling.TestPolicyCloud.testDataProviderPerReplicaDetails(TestPolicyCloud.java:131)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
at
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12960 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TestPolicyCloud
[junit4] 2> 572802 INFO
(SUITE-TestPolicyCloud-seed#[EC058FBC118CA421]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/init-core-data-001
[junit4] 2> 572803 WARN
(SUITE-TestPolicyCloud-seed#[EC058FBC118CA421]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 572803 INFO
(SUITE-TestPolicyCloud-seed#[EC058FBC118CA421]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 572804 INFO
(SUITE-TestPolicyCloud-seed#[EC058FBC118CA421]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 572804 INFO
(SUITE-TestPolicyCloud-seed#[EC058FBC118CA421]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001
[junit4] 2> 572804 INFO
(SUITE-TestPolicyCloud-seed#[EC058FBC118CA421]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 572804 INFO (Thread-1157) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 572804 INFO (Thread-1157) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 572806 ERROR (Thread-1157) [ ] 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> 572904 INFO
(SUITE-TestPolicyCloud-seed#[EC058FBC118CA421]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:48690
[junit4] 2> 572906 INFO (zkConnectionManagerCallback-1743-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 572910 INFO (jetty-launcher-1740-thread-2) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_191-b12
[junit4] 2> 572911 INFO (jetty-launcher-1740-thread-3) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_191-b12
[junit4] 2> 572911 INFO (jetty-launcher-1740-thread-5) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_191-b12
[junit4] 2> 572911 INFO (jetty-launcher-1740-thread-1) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_191-b12
[junit4] 2> 572912 INFO (jetty-launcher-1740-thread-4) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_191-b12
[junit4] 2> 572935 INFO (jetty-launcher-1740-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 572935 INFO (jetty-launcher-1740-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 572935 INFO (jetty-launcher-1740-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 572936 INFO (jetty-launcher-1740-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 572936 INFO (jetty-launcher-1740-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 572936 INFO (jetty-launcher-1740-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 572936 INFO (jetty-launcher-1740-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 572936 INFO (jetty-launcher-1740-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 572936 INFO (jetty-launcher-1740-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 572937 INFO (jetty-launcher-1740-thread-5) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 572937 INFO (jetty-launcher-1740-thread-5) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 572937 INFO (jetty-launcher-1740-thread-5) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 572938 INFO (jetty-launcher-1740-thread-5) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@771e066a{/solr,null,AVAILABLE}
[junit4] 2> 572939 INFO (jetty-launcher-1740-thread-5) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@30d2f03f{HTTP/1.1,[http/1.1]}{127.0.0.1:48366}
[junit4] 2> 572939 INFO (jetty-launcher-1740-thread-5) [ ]
o.e.j.s.Server Started @573494ms
[junit4] 2> 572939 INFO (jetty-launcher-1740-thread-5) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=48366}
[junit4] 2> 572939 ERROR (jetty-launcher-1740-thread-5) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 572939 INFO (jetty-launcher-1740-thread-5) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 572939 INFO (jetty-launcher-1740-thread-5) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.7.0
[junit4] 2> 572939 INFO (jetty-launcher-1740-thread-5) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 572939 INFO (jetty-launcher-1740-thread-5) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 572939 INFO (jetty-launcher-1740-thread-5) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-23T16:01:32.718Z
[junit4] 2> 572943 INFO (jetty-launcher-1740-thread-4) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 572943 INFO (jetty-launcher-1740-thread-4) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 572943 INFO (jetty-launcher-1740-thread-4) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 572943 INFO (jetty-launcher-1740-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@36a8c1f7{/solr,null,AVAILABLE}
[junit4] 2> 572944 INFO (jetty-launcher-1740-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1b0198ab{HTTP/1.1,[http/1.1]}{127.0.0.1:33919}
[junit4] 2> 572944 INFO (jetty-launcher-1740-thread-4) [ ]
o.e.j.s.Server Started @573499ms
[junit4] 2> 572944 INFO (jetty-launcher-1740-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33919}
[junit4] 2> 572944 ERROR (jetty-launcher-1740-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 572944 INFO (jetty-launcher-1740-thread-4) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 572944 INFO (jetty-launcher-1740-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.7.0
[junit4] 2> 572944 INFO (jetty-launcher-1740-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 572945 INFO (jetty-launcher-1740-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4463b23b{/solr,null,AVAILABLE}
[junit4] 2> 572945 INFO (jetty-launcher-1740-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2b5fd265{/solr,null,AVAILABLE}
[junit4] 2> 572945 INFO (jetty-launcher-1740-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@26c6b06b{HTTP/1.1,[http/1.1]}{127.0.0.1:58114}
[junit4] 2> 572945 INFO (jetty-launcher-1740-thread-2) [ ]
o.e.j.s.Server Started @573501ms
[junit4] 2> 572945 INFO (jetty-launcher-1740-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58114}
[junit4] 2> 572946 ERROR (jetty-launcher-1740-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 572946 INFO (jetty-launcher-1740-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 572946 INFO (jetty-launcher-1740-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.7.0
[junit4] 2> 572946 INFO (jetty-launcher-1740-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 572946 INFO (jetty-launcher-1740-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 572946 INFO (jetty-launcher-1740-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-23T16:01:32.725Z
[junit4] 2> 572948 INFO (jetty-launcher-1740-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@c7ecdc9{HTTP/1.1,[http/1.1]}{127.0.0.1:63621}
[junit4] 2> 572948 INFO (jetty-launcher-1740-thread-1) [ ]
o.e.j.s.Server Started @573503ms
[junit4] 2> 572948 INFO (jetty-launcher-1740-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63621}
[junit4] 2> 572948 INFO (zkConnectionManagerCallback-1747-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 572949 ERROR (jetty-launcher-1740-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 572949 INFO (jetty-launcher-1740-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 572949 INFO (jetty-launcher-1740-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 572949 INFO (jetty-launcher-1740-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.7.0
[junit4] 2> 572949 INFO (jetty-launcher-1740-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 572949 INFO (jetty-launcher-1740-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 572949 INFO (jetty-launcher-1740-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-23T16:01:32.728Z
[junit4] 2> 572950 INFO (jetty-launcher-1740-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 572950 INFO (jetty-launcher-1740-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-23T16:01:32.729Z
[junit4] 2> 572951 INFO (jetty-launcher-1740-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@18af0c4d{/solr,null,AVAILABLE}
[junit4] 2> 572951 INFO (jetty-launcher-1740-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@577d06bc{HTTP/1.1,[http/1.1]}{127.0.0.1:47965}
[junit4] 2> 572951 INFO (jetty-launcher-1740-thread-3) [ ]
o.e.j.s.Server Started @573506ms
[junit4] 2> 572951 INFO (jetty-launcher-1740-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=47965}
[junit4] 2> 572951 ERROR (jetty-launcher-1740-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 572951 INFO (jetty-launcher-1740-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 572951 INFO (jetty-launcher-1740-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.7.0
[junit4] 2> 572951 INFO (jetty-launcher-1740-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 572951 INFO (jetty-launcher-1740-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 572951 INFO (jetty-launcher-1740-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-23T16:01:32.730Z
[junit4] 2> 572956 INFO (zkConnectionManagerCallback-1751-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 572957 INFO (zkConnectionManagerCallback-1749-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 572957 INFO (zkConnectionManagerCallback-1753-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 572960 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
0x1002f8d88640001, likely client has closed socket
[junit4] 2> 572961 INFO (jetty-launcher-1740-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 572961 INFO (jetty-launcher-1740-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 572965 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
0x1002f8d88640003, likely client has closed socket
[junit4] 2> 572965 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
0x1002f8d88640004, likely client has closed socket
[junit4] 2> 572965 INFO (jetty-launcher-1740-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 572971 INFO (zkConnectionManagerCallback-1745-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 572971 INFO (jetty-launcher-1740-thread-5) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 573036 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
0x1002f8d88640002, likely client has closed socket
[junit4] 2> 573800 INFO (jetty-launcher-1740-thread-5) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48690/solr
[junit4] 2> 573911 INFO (zkConnectionManagerCallback-1757-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 573943 INFO (zkConnectionManagerCallback-1759-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574142 INFO (jetty-launcher-1740-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48690/solr
[junit4] 2> 574144 INFO (zkConnectionManagerCallback-1766-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574153 INFO (jetty-launcher-1740-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48690/solr
[junit4] 2> 574222 INFO (zkConnectionManagerCallback-1769-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574225 INFO (zkConnectionManagerCallback-1774-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574245 INFO (jetty-launcher-1740-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48690/solr
[junit4] 2> 574247 INFO (zkConnectionManagerCallback-1771-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574253 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:48366_solr
[junit4] 2> 574255 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.c.Overseer Overseer
(id=72109878963077127-127.0.0.1:48366_solr-n_0000000000) starting
[junit4] 2> 574263 INFO (jetty-launcher-1740-thread-1)
[n:127.0.0.1:63621_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63621_solr
[junit4] 2> 574265 INFO (zkConnectionManagerCallback-1786-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574281 INFO (zkConnectionManagerCallback-1791-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574292 INFO (zkCallback-1758-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 574292 INFO (zkConnectionManagerCallback-1777-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574294 INFO (zkCallback-1770-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 574294 INFO (jetty-launcher-1740-thread-1)
[n:127.0.0.1:63621_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 574295 INFO (zkConnectionManagerCallback-1793-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574295 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 574296 INFO (jetty-launcher-1740-thread-1)
[n:127.0.0.1:63621_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:48690/solr ready
[junit4] 2> 574297 INFO (jetty-launcher-1740-thread-1)
[n:127.0.0.1:63621_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 574298 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:48690/solr ready
[junit4] 2> 574300 INFO
(OverseerStateUpdate-72109878963077127-127.0.0.1:48366_solr-n_0000000000)
[n:127.0.0.1:48366_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:48366_solr
[junit4] 2> 574305 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 574305 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 574305 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:48366_solr
[junit4] 2> 574308 INFO (zkCallback-1792-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 574310 INFO (zkCallback-1785-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 574312 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 574312 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58114_solr
[junit4] 2> 574314 INFO (zkCallback-1785-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 574314 INFO (zkCallback-1758-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 574315 INFO (zkCallback-1790-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 574318 INFO (zkCallback-1770-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 574321 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 574331 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 574333 INFO (zkCallback-1792-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 574333 INFO (zkConnectionManagerCallback-1802-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574334 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 574335 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:48690/solr ready
[junit4] 2> 574336 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 574344 INFO (jetty-launcher-1740-thread-1)
[n:127.0.0.1:63621_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63621.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574349 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_48366.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574351 INFO (jetty-launcher-1740-thread-1)
[n:127.0.0.1:63621_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63621.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574351 INFO (jetty-launcher-1740-thread-1)
[n:127.0.0.1:63621_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_63621.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574352 INFO (jetty-launcher-1740-thread-1)
[n:127.0.0.1:63621_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001/node1/.
[junit4] 2> 574354 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_48366.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574354 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_48366.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574355 INFO (jetty-launcher-1740-thread-5)
[n:127.0.0.1:48366_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001/node4/.
[junit4] 2> 574361 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 574361 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33919_solr
[junit4] 2> 574362 INFO (zkCallback-1758-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 574362 INFO (zkCallback-1785-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 574362 INFO (zkCallback-1770-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 574362 INFO (zkCallback-1790-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 574369 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58114.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574373 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58114.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574374 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58114.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574374 INFO (jetty-launcher-1740-thread-2)
[n:127.0.0.1:58114_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001/node2/.
[junit4] 2> 574381 INFO (zkCallback-1773-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 574382 INFO (zkConnectionManagerCallback-1808-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574384 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 574392 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:48690/solr ready
[junit4] 2> 574392 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 574403 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33919.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574408 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33919.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574408 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33919.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574409 INFO (jetty-launcher-1740-thread-4)
[n:127.0.0.1:33919_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001/node5/.
[junit4] 2> 574420 INFO (zkCallback-1792-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 574439 INFO (zkCallback-1801-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 574836 INFO (jetty-launcher-1740-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48690/solr
[junit4] 2> 574838 INFO (zkConnectionManagerCallback-1813-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574841 INFO (zkConnectionManagerCallback-1815-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574852 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 574861 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 574861 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47965_solr
[junit4] 2> 574863 INFO (zkCallback-1790-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 574863 INFO (zkCallback-1758-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 574863 INFO (zkCallback-1770-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 574863 INFO (zkCallback-1785-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 574864 INFO (zkCallback-1807-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 574864 INFO (zkCallback-1773-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 574864 INFO (zkCallback-1801-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 574864 INFO (zkCallback-1792-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 574865 INFO (zkCallback-1814-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 574883 INFO (zkConnectionManagerCallback-1822-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574885 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 574886 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:48690/solr ready
[junit4] 2> 574887 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 574898 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47965.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574903 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47965.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574903 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47965.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 574904 INFO (jetty-launcher-1740-thread-3)
[n:127.0.0.1:47965_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001/node3/.
[junit4] 2> 574936 INFO (zkConnectionManagerCallback-1825-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574940 INFO (zkConnectionManagerCallback-1830-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 574942 INFO
(SUITE-TestPolicyCloud-seed#[EC058FBC118CA421]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 574944 INFO
(SUITE-TestPolicyCloud-seed#[EC058FBC118CA421]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:48690/solr ready
[junit4] 2> 574998 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[EC058FBC118CA421]) [
] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddReplica
[junit4] 2> 575006 INFO (qtp1723643405-5810) [n:127.0.0.1:33919_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=2
[junit4] 2> 575008 INFO (qtp1455903308-5797) [n:127.0.0.1:47965_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 575010 INFO (qtp37422563-5806) [n:127.0.0.1:48366_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 575013 INFO (qtp1393689083-5784) [n:127.0.0.1:58114_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 575015 INFO (qtp483368660-5788) [n:127.0.0.1:63621_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 575025 INFO (qtp1723643405-5818) [n:127.0.0.1:33919_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 575027 INFO (qtp1723643405-5816) [n:127.0.0.1:33919_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf&name=testCreateCollectionAddReplica&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=c1
and sendToOCPQueue=true
[junit4] 2> 575033 INFO
(OverseerThreadFactory-2574-thread-1-processing-n:127.0.0.1:48366_solr)
[n:127.0.0.1:48366_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testCreateCollectionAddReplica
[junit4] 2> 575144 INFO (qtp1723643405-5820) [n:127.0.0.1:33919_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 575148 INFO (qtp1455903308-5801) [n:127.0.0.1:47965_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=2
[junit4] 2> 575152 INFO (qtp37422563-5802) [n:127.0.0.1:48366_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 575155 INFO (qtp483368660-5785) [n:127.0.0.1:63621_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=2
[junit4] 2> 575160 INFO (qtp1393689083-5782) [n:127.0.0.1:58114_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=3
[junit4] 2> 575168 INFO
(OverseerStateUpdate-72109878963077127-127.0.0.1:48366_solr-n_0000000000)
[n:127.0.0.1:48366_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testCreateCollectionAddReplica",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testCreateCollectionAddReplica_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:48366/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 575373 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testCreateCollectionAddReplica_shard1_replica_n1&action=CREATE&numShards=1&collection=testCreateCollectionAddReplica&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 576389 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.7.0
[junit4] 2> 576394 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.IndexSchema
[testCreateCollectionAddReplica_shard1_replica_n1] Schema name=minimal
[junit4] 2> 576395 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 576395 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.CoreContainer
Creating SolrCore 'testCreateCollectionAddReplica_shard1_replica_n1' using
configuration from collection testCreateCollectionAddReplica, trusted=true
[junit4] 2> 576396 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter
JMX monitoring for
'solr_48366.solr.core.testCreateCollectionAddReplica.shard1.replica_n1'
(registry 'solr.core.testCreateCollectionAddReplica.shard1.replica_n1') enabled
at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 576396 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SolrCore
[[testCreateCollectionAddReplica_shard1_replica_n1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001/node4/testCreateCollectionAddReplica_shard1_replica_n1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001/node4/./testCreateCollectionAddReplica_shard1_replica_n1/data/]
[junit4] 2> 576434 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 576434 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 576435 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 576435 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 576435 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@308d4241[testCreateCollectionAddReplica_shard1_replica_n1]
main]
[junit4] 2> 576437 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 576438 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 576438 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000ms.
[junit4] 2> 576438 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1617941124839636992
[junit4] 2> 576439 INFO
(searcherExecutor-2595-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1
c:testCreateCollectionAddReplica s:shard1 r:core_node2) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SolrCore
[testCreateCollectionAddReplica_shard1_replica_n1] Registered new searcher
Searcher@308d4241[testCreateCollectionAddReplica_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 576446 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.ZkShardTerms
Successful update of terms at
/collections/testCreateCollectionAddReplica/terms/shard1 to
Terms{values={core_node2=0}, version=0}
[junit4] 2> 576451 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 576451 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 576451 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SyncStrategy Sync
replicas to
http://127.0.0.1:48366/solr/testCreateCollectionAddReplica_shard1_replica_n1/
[junit4] 2> 576452 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SyncStrategy Sync
Success - now sync replicas to me
[junit4] 2> 576452 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:48366/solr/testCreateCollectionAddReplica_shard1_replica_n1/
has no replicas
[junit4] 2> 576452 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 576456 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:48366/solr/testCreateCollectionAddReplica_shard1_replica_n1/
shard1
[junit4] 2> 576560 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.ZkController I am
the leader, no recovery necessary
[junit4] 2> 576563 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_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=testCreateCollectionAddReplica_shard1_replica_n1&action=CREATE&numShards=1&collection=testCreateCollectionAddReplica&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1191
[junit4] 2> 576566 INFO (qtp1723643405-5816) [n:127.0.0.1:33919_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> 576664 INFO (zkCallback-1758-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testCreateCollectionAddReplica/state.json] for collection
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size:
[5])
[junit4] 2> 577035 INFO
(OverseerCollectionConfigSetProcessor-72109878963077127-127.0.0.1:48366_solr-n_0000000000)
[n:127.0.0.1:48366_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> 577568 INFO (qtp1723643405-5816) [n:127.0.0.1:33919_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf&name=testCreateCollectionAddReplica&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=c1}
status=0 QTime=2543
[junit4] 2> 577570 INFO (qtp1723643405-5821) [n:127.0.0.1:33919_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
action=ADDREPLICA&collection=testCreateCollectionAddReplica&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 577581 INFO (qtp1723643405-5816) [n:127.0.0.1:33919_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 577582 INFO (qtp1455903308-5796) [n:127.0.0.1:47965_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 577585 INFO (qtp37422563-5808) [n:127.0.0.1:48366_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.core.testCreateCollectionAddReplica.shard1.replica_n1:INDEX.sizeInBytes}
status=0 QTime=1
[junit4] 2> 577586 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 577588 INFO (qtp483368660-5790) [n:127.0.0.1:63621_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 577591 INFO (qtp1393689083-5778) [n:127.0.0.1:58114_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 577593 INFO (qtp37422563-5808) [n:127.0.0.1:48366_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.core.testCreateCollectionAddReplica.shard1.replica_n1:INDEX.sizeInBytes}
status=0 QTime=0
[junit4] 2> 577595 INFO
(OverseerThreadFactory-2574-thread-2-processing-n:127.0.0.1:48366_solr)
[n:127.0.0.1:48366_solr c:testCreateCollectionAddReplica s:shard1 ]
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:48366_solr for creating new
replica of shard shard1 for collection testCreateCollectionAddReplica
[junit4] 2> 577598 INFO
(OverseerStateUpdate-72109878963077127-127.0.0.1:48366_solr-n_0000000000)
[n:127.0.0.1:48366_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"testCreateCollectionAddReplica",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testCreateCollectionAddReplica_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:48366/solr";,
[junit4] 2> "node_name":"127.0.0.1:48366_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 577702 INFO (zkCallback-1758-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testCreateCollectionAddReplica/state.json] for collection
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size:
[5])
[junit4] 2> 577800 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&name=testCreateCollectionAddReplica_shard1_replica_n3&action=CREATE&collection=testCreateCollectionAddReplica&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 577813 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.7.0
[junit4] 2> 577818 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.s.IndexSchema
[testCreateCollectionAddReplica_shard1_replica_n3] Schema name=minimal
[junit4] 2> 577820 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 577821 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.CoreContainer
Creating SolrCore 'testCreateCollectionAddReplica_shard1_replica_n3' using
configuration from collection testCreateCollectionAddReplica, trusted=true
[junit4] 2> 577821 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter
JMX monitoring for
'solr_48366.solr.core.testCreateCollectionAddReplica.shard1.replica_n3'
(registry 'solr.core.testCreateCollectionAddReplica.shard1.replica_n3') enabled
at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7105bef0
[junit4] 2> 577821 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore
[[testCreateCollectionAddReplica_shard1_replica_n3] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001/node4/testCreateCollectionAddReplica_shard1_replica_n3],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_EC058FBC118CA421-001/tempDir-001/node4/./testCreateCollectionAddReplica_shard1_replica_n3/data/]
[junit4] 2> 577885 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 577885 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 577887 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 577887 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 577888 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.s.SolrIndexSearcher
Opening [Searcher@1cf1ec17[testCreateCollectionAddReplica_shard1_replica_n3]
main]
[junit4] 2> 577890 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 577890 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 577891 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000ms.
[junit4] 2> 577891 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1617941126363217920
[junit4] 2> 577891 INFO
(searcherExecutor-2600-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore
[testCreateCollectionAddReplica_shard1_replica_n3] Registered new searcher
Searcher@1cf1ec17[testCreateCollectionAddReplica_shard1_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 577893 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.ZkShardTerms
Successful update of terms at
/collections/testCreateCollectionAddReplica/terms/shard1 to
Terms{values={core_node2=0, core_node4=0}, version=1}
[junit4] 2> 577895 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.ZkController Core
needs to recover:testCreateCollectionAddReplica_shard1_replica_n3
[junit4] 2> 577897 INFO
(updateExecutor-1754-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 577898 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&name=testCreateCollectionAddReplica_shard1_replica_n3&action=CREATE&collection=testCreateCollectionAddReplica&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=97
[junit4] 2> 577898 INFO
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy
Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 577899 INFO
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy
startupVersions is empty
[junit4] 2> 577901 INFO (qtp1723643405-5821) [n:127.0.0.1:33919_solr
c:testCreateCollectionAddReplica ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/collections
params={action=ADDREPLICA&collection=testCreateCollectionAddReplica&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=330
[junit4] 2> 577902 INFO (qtp37422563-5808) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.S.Request
[testCreateCollectionAddReplica_shard1_replica_n1] webapp=/solr
path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 577902 INFO (qtp37422563-5808) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node2
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.S.Request
[testCreateCollectionAddReplica_shard1_replica_n1] webapp=/solr
path=/admin/ping params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 577903 INFO
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy
Begin buffering updates. core=[testCreateCollectionAddReplica_shard1_replica_n3]
[junit4] 2> 577903 INFO
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.UpdateLog Starting
to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 577903 INFO
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy
Publishing state of core [testCreateCollectionAddReplica_shard1_replica_n3] as
recovering, leader is
[http://127.0.0.1:48366/solr/testCreateCollectionAddReplica_shard1_replica_n1/]
and I am
[http://127.0.0.1:48366/solr/testCreateCollectionAddReplica_shard1_replica_n3/]
[junit4] 2> 577905 INFO (zkCallback-1758-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testCreateCollectionAddReplica/state.json] for collection
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size:
[5])
[junit4] 2> 577905 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[EC058FBC118CA421]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 577906 INFO
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.ZkShardTerms
Successful update of terms at
/collections/testCreateCollectionAddReplica/terms/shard1 to
Terms{values={core_node2=0, core_node4_recovering=0, core_node4=0}, version=2}
[junit4] 2> 577909 INFO (qtp1723643405-5820) [n:127.0.0.1:33919_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=testCreateCollectionAddReplica&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 577911 INFO
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy
Sending prep recovery command to [http://127.0.0.1:48366/solr]; [WaitForState:
action=PREPRECOVERY&core=testCreateCollectionAddReplica_shard1_replica_n1&nodeName=127.0.0.1:48366_solr&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 577915 INFO
(OverseerCollectionConfigSetProcessor-72109878963077127-127.0.0.1:48366_solr-n_0000000000)
[n:127.0.0.1:48366_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> 577915 INFO
(OverseerThreadFactory-2574-thread-3-processing-n:127.0.0.1:48366_solr)
[n:127.0.0.1:48366_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Executing Collection
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true,
asyncId=null
[junit4] 2> 577917 INFO (qtp37422563-5808) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.h.a.PrepRecoveryOp
Going to wait for coreNodeName: core_node4, state: recovering, checkLive: true,
onlyIfLeader: true, onlyIfLeaderActive: true, maxTime: 183 s
[junit4] 2> 577918 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.m.SolrMetricManager
Closing metric reporters for
registry=solr.core.testCreateCollectionAddReplica.shard1.replica_n1, tag=null
[junit4] 2> 577918 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter
Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@52425b4d:
rootName = solr_48366, domain =
solr.core.testCreateCollectionAddReplica.shard1.replica_n1, service url = null,
agent id = null] for registry
solr.core.testCreateCollectionAddReplica.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@226a10ba
[junit4] 2> 577919 ERROR (qtp37422563-5808) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.h.RequestHandlerBase
org.apache.solr.common.SolrException: Error handling 'PREPRECOVERY' action
[junit4] 2> at
org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:365)
[junit4] 2> at
org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:395)
[junit4] 2> at
org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:180)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:734)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:715)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:531)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.NullPointerException
[junit4] 2> at
org.apache.solr.handler.admin.PrepRecoveryOp.execute(PrepRecoveryOp.java:140)
[junit4] 2> at
org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:360)
[junit4] 2> ... 38 more
[junit4] 2>
[junit4] 2> 577919 INFO (qtp37422563-5808) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:48366_solr&onlyIfLeaderActive=true&core=testCreateCollectionAddReplica_shard1_replica_n1&coreNodeName=core_node4&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=500 QTime=1
[junit4] 2> 577919 ERROR (qtp37422563-5808) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.HttpSolrCall
null:org.apache.solr.common.SolrException: Error handling 'PREPRECOVERY' action
[junit4] 2> at
org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:365)
[junit4] 2> at
org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:395)
[junit4] 2> at
org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:180)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:734)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:715)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:531)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.NullPointerException
[junit4] 2> at
org.apache.solr.handler.admin.PrepRecoveryOp.execute(PrepRecoveryOp.java:140)
[junit4] 2> at
org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:360)
[junit4] 2> ... 38 more
[junit4] 2>
[junit4] 2> 577921 ERROR
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy
Error while trying to recover.
core=testCreateCollectionAddReplica_shard1_replica_n3:java.util.concurrent.ExecutionException:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:48366/solr: Error handling 'PREPRECOVERY' action
[junit4] 2> at
java.util.concurrent.FutureTask.report(FutureTask.java:122)
[junit4] 2> at
java.util.concurrent.FutureTask.get(FutureTask.java:192)
[junit4] 2> at
org.apache.solr.cloud.RecoveryStrategy.sendPrepRecoveryCmd(RecoveryStrategy.java:870)
[junit4] 2> at
org.apache.solr.cloud.RecoveryStrategy.doSyncOrReplicateRecovery(RecoveryStrategy.java:570)
[junit4] 2> at
org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:310)
[junit4] 2> at
org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:294)
[junit4] 2> at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:48366/solr: Error handling 'PREPRECOVERY' action
[junit4] 2> at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
[junit4] 2> at
org.apache.solr.client.solrj.impl.HttpSolrClient.lambda$httpUriRequest$0(HttpSolrClient.java:299)
[junit4] 2> ... 5 more
[junit4] 2>
[junit4] 2> 577922 ERROR
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy
Recovery failed - trying again... (0)
[junit4] 2> 577922 INFO
(recoveryExecutor-1755-thread-1-processing-n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3
c:testCreateCollectionAddReplica s:shard1 r:core_node4) [n:127.0.0.1:48366_solr
c:testCreateCollectionAddReplica s:shard1 r:core_node4
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy
Wait [2.0] seconds before trying to recover again (attempt=1)
[junit4] 2> 577931 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.m.SolrMetricManager
Closing metric reporters for
registry=solr.core.testCreateCollectionAddReplica.shard1.replica_n3, tag=null
[junit4] 2> 577931 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SolrCore
[testCreateCollectionAddReplica_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@75c2dd23
[junit4] 2> 577931 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter
Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5d6ca69c:
rootName = solr_48366, domain =
solr.core.testCreateCollectionAddReplica.shard1.replica_n3, service url = null,
agent id = null] for registry
solr.core.testCreateCollectionAddReplica.shard1.replica_n3 /
com.codahale.metrics.MetricRegistry@18d950de
[junit4] 2> 577941 WARN (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy
Stopping recovery for core=[testCreateCollectionAddReplica_shard1_replica_n3]
coreNodeName=[core_node4]
[junit4] 2> 577941 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 577941 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.m.SolrMetricManager
Closing metric reporters for
registry=solr.core.testCreateCollectionAddReplica.shard1.replica_n1,
tag=75c2dd23
[junit4] 2> 577941 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.m.SolrMetricManager
Closing metric reporters for
registry=solr.collection.testCreateCollectionAddReplica.shard1.leader,
tag=75c2dd23
[junit4] 2> 577944 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.ZkShardTerms
Successful update of terms at
/collections/testCreateCollectionAddReplica/terms/shard1 to
Terms{values={core_node4_recovering=0, core_node4=0}, version=3}
[junit4] 2> 577946 WARN (zkCallback-1758-thread-2) [ ]
o.a.s.c.LeaderElector
[junit4] 2> org.apache.solr.common.SolrException: SolrCore not
found:testCreateCollectionAddReplica_shard1_replica_n3 in []
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:312)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56) ~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:270)
~[java/:?]
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
~[?:1.8.0_191]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_191]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[?:1.8.0_191]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[?:1.8.0_191]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_191]
[junit4] 2> 577948 INFO (qtp37422563-5805) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&deleteMetricsHistory=true&core=testCreateCollectionAddReplica_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=31
[junit4] 2> 578041 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 578145 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 578245 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 578345 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 578445 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 578545 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 578646 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 578747 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 578847 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 578948 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579048 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579148 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579249 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579349 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579449 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579549 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579650 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579750 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579850 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 579950 INFO (qtp37422563-5804) [n:127.0.0.1:48366_solr
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore Core
testCreateCollectionAddReplica_shard1_replica_n3 is not yet closed, waiting 100
ms before checking again.
[junit4] 2> 580051 IN
[...truncated too long message...]
ctionSplitShard
[junit4] OK 5.46s J0 | TestPolicyCloud.testDataProvider
[junit4] OK 3.59s J0 | TestPolicyCloud.testMetricsTag
[junit4] OK 5.12s J0 |
TestPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 4.61s J0 | TestPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 6.63s J0 |
TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] OK 5.19s J0 | TestPolicyCloud.testCreateCollection
[junit4] Completed [1/5] on J0 in 55.99s, 8 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TestPolicyCloud
[junit4] OK 6.27s J1 | TestPolicyCloud.testCreateCollectionAddReplica
[junit4] OK 9.35s J1 | TestPolicyCloud.testCreateCollectionSplitShard
[junit4] OK 5.47s J1 | TestPolicyCloud.testDataProvider
[junit4] OK 3.50s J1 | TestPolicyCloud.testMetricsTag
[junit4] OK 5.14s J1 |
TestPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 4.72s J1 | TestPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 11.5s J1 |
TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] OK 5.22s J1 | TestPolicyCloud.testCreateCollection
[junit4] Completed [2/5] on J1 in 62.07s, 8 tests
[junit4]
[junit4] Duplicate suite name used with XML reports:
org.apache.solr.cloud.autoscaling.TestPolicyCloud. This may confuse tools that
process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message.
[junit4] Suite: org.apache.solr.cloud.autoscaling.TestPolicyCloud
[junit4] OK 3.90s J0 | TestPolicyCloud.testCreateCollectionAddReplica
[junit4] OK 8.97s J0 | TestPolicyCloud.testCreateCollectionSplitShard
[junit4] OK 5.65s J0 | TestPolicyCloud.testDataProvider
[junit4] OK 3.57s J0 | TestPolicyCloud.testMetricsTag
[junit4] OK 5.21s J0 |
TestPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 4.94s J0 | TestPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 11.4s J0 |
TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] OK 5.22s J0 | TestPolicyCloud.testCreateCollection
[junit4] Completed [3/5] on J0 in 53.04s, 8 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TestPolicyCloud
[junit4] OK 3.85s J1 | TestPolicyCloud.testCreateCollectionAddReplica
[junit4] OK 9.07s J1 | TestPolicyCloud.testCreateCollectionSplitShard
[junit4] OK 5.43s J1 | TestPolicyCloud.testDataProvider
[junit4] OK 3.57s J1 | TestPolicyCloud.testMetricsTag
[junit4] OK 5.40s J1 |
TestPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 4.83s J1 | TestPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 11.4s J1 |
TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] OK 5.23s J1 | TestPolicyCloud.testCreateCollection
[junit4] Completed [4/5] on J1 in 52.76s, 8 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TestPolicyCloud
[junit4] OK 3.91s J0 | TestPolicyCloud.testCreateCollectionAddReplica
[junit4] OK 9.19s J0 | TestPolicyCloud.testCreateCollectionSplitShard
[junit4] OK 5.51s J0 | TestPolicyCloud.testDataProvider
[junit4] OK 3.56s J0 | TestPolicyCloud.testMetricsTag
[junit4] OK 5.15s J0 |
TestPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 4.90s J0 | TestPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 6.61s J0 |
TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] OK 5.27s J0 | TestPolicyCloud.testCreateCollection
[junit4] Completed [5/5] on J0 in 47.86s, 8 tests
[junit4]
[junit4] JVM J0: 0.69 .. 158.26 = 157.57s
[junit4] JVM J1: 0.70 .. 116.47 = 115.77s
[junit4] Execution time total: 2 minutes 38 seconds
[junit4] Tests summary: 5 suites, 40 tests
[junit4] Could not remove temporary path:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1
(java.nio.file.DirectoryNotEmptyException: Remaining files:
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp])
[junit4] Could not remove temporary path:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0
(java.nio.file.DirectoryNotEmptyException: Remaining files:
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 372.52s | org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4:tophints] 176.10s |
org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4:tophints] 147.17s | org.apache.solr.cloud.cdcr.CdcrOpsAndBoundariesTest
[junit4:tophints] 122.45s | org.apache.solr.cloud.cdcr.CdcrWithNodesRestartsTest
[junit4:tophints] 118.26s | org.apache.solr.update.SoftAutoCommitTest
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 2 minutes 41 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.TestPolicyCloud
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2845)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2724)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2705)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3084)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to Solaris VBOX
at
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1070)
at hudson.FilePath.act(FilePath.java:1059)
at hudson.FilePath.validateAntFileMask(FilePath.java:2703)
at
hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at
hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1835)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at
hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at
hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no
matches found within 10000
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1059)
at hudson.FilePath.validateAntFileMask(FilePath.java:2703)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1835)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern
"**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=b0b3afca0aa64ad67d374651b1a9e81ea03af5fb,
workspace=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris
[WARNINGS] Computing warning deltas based on reference build #923
Recording test results
Setting
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
