[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 583 - Still Unstable!

Policeman Jenkins Server Sat, 28 Apr 2018 15:39:07 -0700

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/583/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC


1 tests failed.
FAILED:  org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState

Error Message:
Did not expect the processor to fire on first run! event={   
"id":"c47a7febe31fTcjsq1vskqkvexonltctjxnw4q",   "source":"node_added_trigger", 
  "eventTime":216030411219743,   "eventType":"NODEADDED",   "properties":{     
"eventTimes":[       216030411219743,       216030411223891,       
216030411225545,       216030411227157],     "nodeNames":[       
"127.0.0.1:34711_solr",       "127.0.0.1:50223_solr",       
"127.0.0.1:58260_solr",       "127.0.0.1:53822_solr"]}}

Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run! 
event={
  "id":"c47a7febe31fTcjsq1vskqkvexonltctjxnw4q",
  "source":"node_added_trigger",
  "eventTime":216030411219743,
  "eventType":"NODEADDED",
  "properties":{
    "eventTimes":[
      216030411219743,
      216030411223891,
      216030411225545,
      216030411227157],
    "nodeNames":[
      "127.0.0.1:34711_solr",
      "127.0.0.1:50223_solr",
      "127.0.0.1:58260_solr",
      "127.0.0.1:53822_solr"]}}
        at 
__randomizedtesting.SeedInfo.seed([5DF46BD8703144C7:935ACF4B88083CD1]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
        at 
org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
        at 
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12408 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_5DF46BD8703144C7-001/init-core-data-001
   [junit4]   2> 129744 INFO  
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 129746 INFO  
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-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> 129746 INFO  
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 129746 INFO  
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_5DF46BD8703144C7-001/tempDir-001
   [junit4]   2> 129746 INFO  
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 129747 INFO  (Thread-165) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 129747 INFO  (Thread-165) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 129750 ERROR (Thread-165) [    ] 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> 129847 INFO  
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:58843
   [junit4]   2> 129850 INFO  (zkConnectionManagerCallback-252-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 129857 INFO  (jetty-launcher-249-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 129859 INFO  (jetty-launcher-249-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 129859 INFO  (jetty-launcher-249-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 129859 INFO  (jetty-launcher-249-thread-1) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 129860 INFO  (jetty-launcher-249-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@c063d3e{/solr,null,AVAILABLE}
   [junit4]   2> 129863 INFO  (jetty-launcher-249-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@332a3d46{HTTP/1.1,[http/1.1]}{127.0.0.1:53822}
   [junit4]   2> 129863 INFO  (jetty-launcher-249-thread-1) [    ] 
o.e.j.s.Server Started @129972ms
   [junit4]   2> 129863 INFO  (jetty-launcher-249-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=53822}
   [junit4]   2> 129864 ERROR (jetty-launcher-249-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 129865 INFO  (jetty-launcher-249-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 129865 INFO  (jetty-launcher-249-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.4.0
   [junit4]   2> 129865 INFO  (jetty-launcher-249-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 129865 INFO  (jetty-launcher-249-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 129865 INFO  (jetty-launcher-249-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-04-28T21:11:48.990Z
   [junit4]   2> 129868 INFO  (zkConnectionManagerCallback-254-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 129868 INFO  (jetty-launcher-249-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 129880 INFO  (jetty-launcher-249-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58843/solr
   [junit4]   2> 129884 INFO  (zkConnectionManagerCallback-258-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 129888 INFO  (zkConnectionManagerCallback-260-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 130046 INFO  (jetty-launcher-249-thread-1) 
[n:127.0.0.1:53822_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 130046 INFO  (jetty-launcher-249-thread-1) 
[n:127.0.0.1:53822_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:53822_solr
   [junit4]   2> 130048 INFO  (jetty-launcher-249-thread-1) 
[n:127.0.0.1:53822_solr    ] o.a.s.c.Overseer Overseer 
(id=72071751021166595-127.0.0.1:53822_solr-n_0000000000) starting
   [junit4]   2> 130060 INFO  (zkConnectionManagerCallback-267-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 130063 INFO  (jetty-launcher-249-thread-1) 
[n:127.0.0.1:53822_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:58843/solr ready
   [junit4]   2> 130065 INFO  (jetty-launcher-249-thread-1) 
[n:127.0.0.1:53822_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53822_solr
   [junit4]   2> 130066 INFO  
(OverseerStateUpdate-72071751021166595-127.0.0.1:53822_solr-n_0000000000) [    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 130083 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 130975 INFO  (jetty-launcher-249-thread-1) 
[n:127.0.0.1:53822_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53822.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 130980 INFO  (jetty-launcher-249-thread-1) 
[n:127.0.0.1:53822_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53822.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 130981 INFO  (jetty-launcher-249-thread-1) 
[n:127.0.0.1:53822_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53822.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 130981 INFO  (jetty-launcher-249-thread-1) 
[n:127.0.0.1:53822_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.NodeAddedTriggerTest_5DF46BD8703144C7-001/tempDir-001/node1/.
   [junit4]   2> 130998 INFO  (zkConnectionManagerCallback-269-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 131003 INFO  (zkConnectionManagerCallback-274-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 131004 INFO  
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 131005 INFO  
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58843/solr ready
   [junit4]   2> 131021 INFO  
(TEST-NodeAddedTriggerTest.testActionLifecycle-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testActionLifecycle
   [junit4]   2> 131040 INFO  
(TEST-NodeAddedTriggerTest.testActionLifecycle-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testActionLifecycle
   [junit4]   2> 131052 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testListenerAcceptance
   [junit4]   2> 131054 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 131055 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 131055 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 131055 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 131056 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@26e2455{/solr,null,AVAILABLE}
   [junit4]   2> 131056 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.e.j.s.AbstractConnector Started 
ServerConnector@47d58b90{HTTP/1.1,[http/1.1]}{127.0.0.1:58260}
   [junit4]   2> 131056 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.e.j.s.Server Started @131165ms
   [junit4]   2> 131056 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=58260}
   [junit4]   2> 131057 ERROR 
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 131057 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 131057 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.4.0
   [junit4]   2> 131057 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port 
null
   [junit4]   2> 131057 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 131057 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-04-28T21:11:50.182Z
   [junit4]   2> 131060 INFO  (zkConnectionManagerCallback-276-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 131061 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 131069 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58843/solr
   [junit4]   2> 131072 INFO  (zkConnectionManagerCallback-280-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 131078 INFO  (zkConnectionManagerCallback-282-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 131087 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 131090 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 131093 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 131094 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:58260_solr
   [junit4]   2> 131098 INFO  (zkCallback-259-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 131098 INFO  (zkCallback-273-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 131098 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 131099 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 132574 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_58260.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 132581 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_58260.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 132581 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_58260.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 132582 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_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.NodeAddedTriggerTest_5DF46BD8703144C7-001/tempDir-001/node2/.
   [junit4]   2> 132591 INFO  (zkConnectionManagerCallback-289-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 132593 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 132595 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:58260_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:58843/solr ready
   [junit4]   2> 132648 INFO  
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[5DF46BD8703144C7]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testListenerAcceptance
   [junit4]   2> 132698 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testTrigger
   [junit4]   2> 132701 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 132702 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 132702 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 132702 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 132703 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3cf31449{/solr,null,AVAILABLE}
   [junit4]   2> 132704 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@47d7c7dd{HTTP/1.1,[http/1.1]}{127.0.0.1:50223}
   [junit4]   2> 132704 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.Server Started @132813ms
   [junit4]   2> 132704 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50223}
   [junit4]   2> 132705 ERROR 
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 132705 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 132705 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.4.0
   [junit4]   2> 132705 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 132705 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 132705 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-04-28T21:11:51.830Z
   [junit4]   2> 132708 INFO  (zkConnectionManagerCallback-291-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 132709 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 132725 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58843/solr
   [junit4]   2> 132737 INFO  (zkConnectionManagerCallback-295-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 132740 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 
0x1000ce02df5000c, likely client has closed socket
   [junit4]   2> 132743 INFO  (zkConnectionManagerCallback-297-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 132759 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 132767 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 132775 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 132775 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50223_solr
   [junit4]   2> 132779 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 132779 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 132779 INFO  (zkCallback-273-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 132783 INFO  (zkCallback-259-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 132783 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 132785 INFO  (zkCallback-296-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 133353 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50223.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 133392 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50223.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 133392 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50223.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 133393 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_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.NodeAddedTriggerTest_5DF46BD8703144C7-001/tempDir-001/node3/.
   [junit4]   2> 133404 INFO  (zkConnectionManagerCallback-304-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 133407 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 133410 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:50223_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:58843/solr ready
   [junit4]   2> 133458 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 133459 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 133459 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 133459 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 133459 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@41f4d36e{/solr,null,AVAILABLE}
   [junit4]   2> 133460 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@371e849f{HTTP/1.1,[http/1.1]}{127.0.0.1:34711}
   [junit4]   2> 133460 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.Server Started @133569ms
   [junit4]   2> 133460 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=34711}
   [junit4]   2> 133460 ERROR 
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 133468 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 133468 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.4.0
   [junit4]   2> 133468 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 133468 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 133468 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-04-28T21:11:52.593Z
   [junit4]   2> 133485 INFO  (zkConnectionManagerCallback-306-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 133486 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 133614 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58843/solr
   [junit4]   2> 133620 INFO  (zkConnectionManagerCallback-310-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 133631 INFO  (zkConnectionManagerCallback-312-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 133653 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 133661 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 133667 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 133667 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34711_solr
   [junit4]   2> 133669 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 133669 INFO  (zkCallback-273-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 133669 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 133670 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 133670 INFO  (zkCallback-259-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 133670 INFO  (zkCallback-296-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 133673 INFO  (zkCallback-303-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 133674 INFO  (zkCallback-311-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 134127 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34711.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 134137 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34711.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 134137 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34711.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 134138 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_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.NodeAddedTriggerTest_5DF46BD8703144C7-001/tempDir-001/node4/.
   [junit4]   2> 134144 INFO  (zkConnectionManagerCallback-319-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 134146 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 134148 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:34711_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:58843/solr ready
   [junit4]   2> 136230 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 136232 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 136232 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 136232 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 136233 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@531f6fda{/solr,null,AVAILABLE}
   [junit4]   2> 136234 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6649cd13{HTTP/1.1,[http/1.1]}{127.0.0.1:37186}
   [junit4]   2> 136234 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.Server Started @136343ms
   [junit4]   2> 136234 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=37186}
   [junit4]   2> 136234 ERROR 
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 136234 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 136234 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.4.0
   [junit4]   2> 136234 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 136234 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 136234 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-04-28T21:11:55.359Z
   [junit4]   2> 136238 INFO  (zkConnectionManagerCallback-321-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 136239 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 136248 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58843/solr
   [junit4]   2> 136251 INFO  (zkConnectionManagerCallback-325-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 136256 INFO  (zkConnectionManagerCallback-327-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 136273 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 136277 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 136282 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 136282 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37186_solr
   [junit4]   2> 136287 INFO  (zkCallback-311-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136287 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136288 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136289 INFO  (zkCallback-259-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136289 INFO  (zkCallback-273-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136290 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136290 INFO  (zkCallback-303-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136290 INFO  (zkCallback-296-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136291 INFO  (zkCallback-318-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136292 INFO  (zkCallback-326-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 136537 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37186.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 136547 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37186.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 136547 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37186.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 136548 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_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.NodeAddedTriggerTest_5DF46BD8703144C7-001/tempDir-001/node5/.
   [junit4]   2> 136554 INFO  (zkConnectionManagerCallback-334-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 136556 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (5)
   [junit4]   2> 136561 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:37186_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:58843/solr ready
   [junit4]   2> 136622 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@6649cd13{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 136623 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=153549453
   [junit4]   2> 136623 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 136624 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@10485f74: rootName = 
solr_37186, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@662085de
   [junit4]   2> 136632 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 136632 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@180afdc9: rootName = 
solr_37186, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@9d7f0d3
   [junit4]   2> 136637 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 136638 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6e66ce12: rootName = 
solr_37186, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@576f7320
   [junit4]   2> 136641 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37186_solr
   [junit4]   2> 136642 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136642 INFO  (zkCallback-303-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136642 INFO  (zkCallback-259-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136642 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136642 INFO  (zkCallback-311-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136642 INFO  (zkCallback-296-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136642 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136642 INFO  (zkCallback-318-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136647 INFO  (zkCallback-273-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136648 INFO  (zkCallback-326-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136648 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 136650 INFO  (zkCallback-333-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 136654 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@531f6fda{/solr,null,UNAVAILABLE}
   [junit4]   2> 136654 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 141671 INFO  
(TEST-NodeAddedTriggerTest.testTrigger-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testTrigger
   [junit4]   2> 141735 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testRestoreState
   [junit4]   2> 141738 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 141738 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 141738 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 141738 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 141739 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@225a5454{/solr,null,AVAILABLE}
   [junit4]   2> 141739 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@61b6dfd1{HTTP/1.1,[http/1.1]}{127.0.0.1:47195}
   [junit4]   2> 141739 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.e.j.s.Server Started @141850ms
   [junit4]   2> 141739 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=47195}
   [junit4]   2> 141745 ERROR 
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 141745 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 141745 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.4.0
   [junit4]   2> 141745 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 141745 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 141745 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-04-28T21:12:00.870Z
   [junit4]   2> 141748 INFO  (zkConnectionManagerCallback-336-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 141749 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 141788 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58843/solr
   [junit4]   2> 141791 INFO  (zkConnectionManagerCallback-340-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 141799 INFO  (zkConnectionManagerCallback-342-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 141823 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 141828 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 141832 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 141832 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:47195_solr
   [junit4]   2> 141835 INFO  (zkCallback-259-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 141835 INFO  (zkCallback-273-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 141835 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 141836 INFO  (zkCallback-341-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 141836 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 141837 INFO  (zkCallback-318-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 141838 INFO  (zkCallback-303-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 141840 INFO  (zkCallback-311-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 141840 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 141841 INFO  (zkCallback-296-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 143685 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_47195.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 143691 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_47195.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 143691 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_47195.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@76b0eadc
   [junit4]   2> 143692 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_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.NodeAddedTriggerTest_5DF46BD8703144C7-001/tempDir-001/node6/.
   [junit4]   2> 143696 INFO  (zkConnectionManagerCallback-349-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 143698 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (5)
   [junit4]   2> 143699 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) 
[n:127.0.0.1:47195_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:58843/solr ready
   [junit4]   2> 143746 INFO  
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[5DF46BD8703144C7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testRestoreState
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=NodeAddedTriggerTest -Dtests.method=testRestoreState 
-Dtests.seed=5DF46BD8703144C7 -Dtests.slow=true -Dtests.locale=fr-LU 
-Dtests.timezone=America/Kentucky/Monticello -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 2.08s J0 | NodeAddedTriggerTest.testRestoreState <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Did not expect the 
processor to fire on first run! event={
   [junit4]    >   "id":"c47a7febe31fTcjsq1vskqkvexonltctjxnw4q",
   [junit4]    >   "source":"node_added_trigger",
   [junit4]    >   "eventTime":216030411219743,
   [junit4]    >   "eventType":"NODEADDED",
   [junit4]    >   "properties":{
   [junit4]    >     "eventTimes":[
   [junit4]    >       216030411219743,
   [junit4]    >       216030411223891,
   [junit4]    >       216030411225545,
   [junit4]    >       216030411227157],
   [junit4]    >     "nodeNames":[
   [junit4]    >       "127.0.0.1:34711_solr",
   [junit4]    >       "127.0.0.1:50223_solr",
   [junit4]    >       "127.0.0.1:58260_solr",
   [junit4]    >       "127.0.0.1:53822_solr"]}}
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([5DF46BD8703144C7:935ACF4B88083CD1]:0)
   [junit4]    >        at 
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
   [junit4]    >        at 
org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
   [junit4]    >        at 
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 143752 INFO  (jetty-closer-250-thread-3) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@47d7c7dd{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 143752 INFO  (jetty-closer-250-thread-2) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@47d58b90{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 143753 INFO  (jetty-closer-250-thread-3) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=153746612
   [junit4]   2> 143752 INFO  (jetty-closer-250-thread-1) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@332a3d46{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 143756 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=786055599
   [junit4]   2> 143756 INFO  (jetty-closer-250-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 143756 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 143755 INFO  (jetty-closer-250-thread-4) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@371e849f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 143757 INFO  (jetty-closer-250-thread-6) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@61b6dfd1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 143757 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7e5a25fc: rootName = 
solr_53822, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@2440aac9
   [junit4]   2> 143758 INFO  (jetty-closer-250-thread-2) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=339602696
   [junit4]   2> 143758 INFO  (jetty-closer-250-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 143759 INFO  (jetty-closer-250-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@495767a1: rootName = 
solr_50223, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@3eac1a4f
   [junit4]   2> 143760 INFO  (jetty-closer-250-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@18bfe129: rootName = 
solr_58260, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@30615fa4
   [junit4]   2> 143760 INFO  (jetty-closer-250-thread-4) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=299008169
   [junit4]   2> 143760 INFO  (jetty-closer-250-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 143760 INFO  (jetty-closer-250-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@54edfd99: rootName = 
solr_34711, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@662ac6a8
   [junit4]   2> 143786 INFO  (jetty-closer-250-thread-6) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=412495557
   [junit4]   2> 143786 INFO  (jetty-closer-250-thread-6) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 143786 INFO  (jetty-closer-250-thread-6) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@49b785d3: rootName = 
solr_47195, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@22bc28ab
   [junit4]   2> 143868 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 143868 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@455c3ce7: rootName = 
solr_53822, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@9d7f0d3
   [junit4]   2> 143900 INFO  (jetty-closer-250-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 143900 INFO  (jetty-closer-250-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@21441b5: rootName = 
solr_50223, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@9d7f0d3
   [junit4]   2> 143903 INFO  (jetty-closer-250-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 143904 INFO  (jetty-closer-250-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1b588947: rootName = 
solr_58260, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@9d7f0d3
   [junit4]   2> 143904 INFO  (jetty-closer-250-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 143904 INFO  (jetty-closer-250-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6db1cf0b: rootName = 
solr_34711, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@9d7f0d3
   [junit4]   2> 143929 INFO  (jetty-closer-250-thread-6) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 143929 INFO  (jetty-closer-250-thread-6) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7de2867c: rootName = 
solr_47195, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@9d7f0d3
   [junit4]   2> 143951 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 143951 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@57717f96: rootName = 
solr_53822, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@576f7320
   [junit4]   2> 143953 INFO  (jetty-closer-250-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 143953 INFO  (jetty-closer-250-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3176c64b: rootName = 
solr_34711, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@576f7320
   [junit4]   2> 143954 INFO  (jetty-closer-250-thread-6) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 143955 INFO  (jetty-closer-250-thread-6) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@54c95ee8: rootName = 
solr_47195, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@576f7320
   [junit4]   2> 143956 INFO  (jetty-closer-250-thread-4) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34711_solr
   [junit4]   2> 143958 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 143958 INFO  (zkCallback-341-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 143959 INFO  (jetty-closer-250-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 143960 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53822_solr
   [junit4]   2> 143960 INFO  (zkCallback-318-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 143960 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 143959 INFO  (jetty-closer-250-thread-6) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:47195_solr
   [junit4]   2> 143959 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 143961 INFO  (zkCallback-303-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 143959 INFO  (zkCallback-259-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 143959 INFO  (zkCallback-296-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 143959 INFO  (zkCallback-311-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 143962 INFO  (jetty-closer-250-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 143962 INFO  (jetty-closer-250-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@568214b3: rootName = 
solr_58260, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@576f7320
   [junit4]   2> 143963 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 143964 INFO  (zkCallback-303-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 143964 INFO  (zkCallback-259-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 143964 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 143964 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 143964 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 143963 INFO  (zkCallback-311-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 143963 INFO  (zkCallback-318-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 143965 INFO  (zkCallback-341-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 143963 INFO  (zkCallback-296-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 143968 INFO  (zkCallback-296-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 143968 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 143968 INFO  (zkCallback-303-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 143968 INFO  (zkCallback-318-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 143968 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 143968 INFO  (zkCallback-311-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 143968 INFO  (zkCallback-259-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 143969 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 143969 INFO  (jetty-closer-250-thread-6) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 143970 INFO  (jetty-closer-250-thread-2) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:58260_solr
   [junit4]   2> 143973 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 143973 INFO  (jetty-closer-250-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=72071751021166595-127.0.0.1:53822_solr-n_0000000000) closing
   [junit4]   2> 143973 INFO  
(OverseerStateUpdate-72071751021166595-127.0.0.1:53822_solr-n_0000000000) [    
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:53822_solr
   [junit4]   2> 143974 INFO  (jetty-closer-250-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 143974 INFO  (jetty-closer-250-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4ad71140: rootName = 
solr_50223, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@576f7320
   [junit4]   2> 143974 WARN  
(OverseerAutoScalingTriggerThread-72071751021166595-127.0.0.1:53822_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we 
are closed, exiting.
   [junit4]   2> 143976 INFO  (zkCallback-296-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 143976 INFO  (zkCallback-259-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 143976 INFO  (zkCallback-303-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 143976 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 143977 INFO  (zkCallback-311-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 143977 INFO  (zkCallback-318-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 143977 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 143979 INFO  (jetty-closer-250-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 143979 INFO  (zkCallback-341-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
   [junit4]   2> 143980 INFO  (zkCallback-348-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (1)
   [junit4]   2> 143980 INFO  (jetty-closer-250-thread-3) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50223_solr
   [junit4]   2> 143981 INFO  (zkCallback-281-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 143983 INFO  (zkCallback-259-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 143983 INFO  (zkCallback-296-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 143983 INFO  (zkCallback-311-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 143983 INFO  (zkCallback-318-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 143984 INFO  (zkCallback-266-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 143984 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 143984 INFO  (zkCallback-281-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 143984 INFO  (jetty-closer-250-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 143984 INFO  (zkCallback-303-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 143991 INFO  (zkCallback-341-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 144000 INFO  (jetty-closer-250-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@c063d3e{/solr,null,UNAVAILABLE}
   [junit4]   2> 144000 INFO  (jetty-closer-250-thread-1) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 144004 INFO  (zkCallback-281-thread-2) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58260_solr
   [junit4]   2> 144009 INFO  (zkCallback-296-thread-2) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50223_solr
   [junit4]   2> 144018 INFO  (jetty-closer-250-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@41f4d36e{/solr,null,UNAVAILABLE}
   [junit4]   2> 144019 INFO  (jetty-closer-250-thread-4) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 144021 INFO  (jetty-closer-250-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3cf31449{/solr,null,UNAVAILABLE}
   [junit4]   2> 144023 INFO  (jetty-closer-250-thread-3) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 144029 INFO  (zkCallback-341-thread-2) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:47195_solr
   [junit4]   2> 145490 WARN  (zkCallback-348-thread-1) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 145497 INFO  (jetty-closer-250-thread-6) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@225a5454{/solr,null,UNAVAILABLE}
   [junit4]   2> 145498 INFO  (jetty-closer-250-thread-6) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 145508 INFO  (jetty-closer-250-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@26e2455{/solr,null,UNAVAILABLE}
   [junit4]   2> 145508 INFO  (jetty-closer-250-thread-2) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 145510 ERROR 
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 145511 INFO  
(SUITE-NodeAddedTriggerTest-seed#[5DF46BD8703144C7]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:58843 58843
   [junit4]   2> 145646 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
   [junit4]   2> java.nio.channels.CancelledKeyException: null
   [junit4]   2>        at 
sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73) ~[?:1.8.0_162]
   [junit4]   2>        at 
sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87) ~[?:1.8.0_162]
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:205)
 [zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_162]
   [junit4]   2> 145647 INFO  (Thread-165) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:58843 58843
   [junit4]   2> 145649 WARN  (Thread-165) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        13      /solr/aliases.json
   [junit4]   2>        13      /solr/clusterprops.json
   [junit4]   2>        6       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        13      /solr/clusterstate.json
   [junit4]   2>        2       
/solr/overseer_elect/election/72071751021166609-127.0.0.1:34711_solr-n_0000000003
   [junit4]   2>        2       
/solr/overseer_elect/election/72071751021166605-127.0.0.1:50223_solr-n_0000000002
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        13      /solr/live_nodes
   [junit4]   2>        13      /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_5DF46BD8703144C7-001
   [junit4]   2> Apr 28, 2018 9:12:04 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: 
codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION,
 chunkSize=1, maxDocsPerChunk=8, blockSize=1), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION,
 chunkSize=1, blockSize=1)), sim=RandomSimilarity(queryNorm=false): {}, 
locale=fr-LU, timezone=America/Kentucky/Monticello
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_162 
(64-bit)/cpus=3,threads=1,free=243614128,total=402653184
   [junit4]   2> NOTE: All tests run in this JVM: 
[ShufflingReplicaListTransformerTest, TestFaceting, 
TestFieldCollectionResource, SolrInfoBeanTest, TestCollapseQParserPlugin, 
CreateCollectionCleanupTest, BlockDirectoryTest, TestStreamBody, 
TestInPlaceUpdatesStandalone, UUIDUpdateProcessorFallbackTest, 
TestCloudPivotFacet, TestRequestForwarding, CleanupOldIndexTest, TestSolrXml, 
HdfsRecoveryZkTest, TestCollectionAPIs, HdfsSyncSliceTest, NodeAddedTriggerTest]
   [junit4] Completed [28/803 (1!)] on J0 in 16.70s, 4 tests, 1 failure <<< 
FAILURES!

[...truncated 53038 lines...]
[asciidoctor:convert] asciidoctor: ERROR: about-this-guide.adoc: line 1: 
invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[asciidoctor:convert] asciidoctor: ERROR: solr-glossary.adoc: line 1: invalid 
part, must have at least one section (e.g., chapter, appendix, etc.)
     [java] Processed 2190 links (1747 relative) to 2947 anchors in 228 files
     [echo] Validated Links & Anchors via: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-ref-guide/bare-bones-html/

-documentation-lint:
    [jtidy] Checking for broken html (such as invalid tags)...
   [delete] Deleting directory 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/jtidy_tmp
     [echo] Checking for broken links...
     [exec] 
     [exec] Crawl/parse...
     [exec] 
     [exec] Verify...
     [echo] Checking for malformed docs...

jar-checksums:

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

common.resolve:

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

jar-checksums:
    [mkdir] Created dir: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/null1985104681
     [copy] Copying 40 files to 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/null1985104681
   [delete] Deleting directory 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/null1985104681

resolve-example:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

resolve-server:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fai

[...truncated too long message...]

ading settings :: file = 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml

install-junit4-taskdef:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

resolve-groovy:
[ivy:cachepath] :: resolving dependencies :: 
org.codehaus.groovy#groovy-all-caller;working
[ivy:cachepath]         confs: [default]
[ivy:cachepath]         found org.codehaus.groovy#groovy-all;2.4.15 in public
[ivy:cachepath] :: resolution report :: resolve 24ms :: artifacts dl 1ms
        ---------------------------------------------------------------------
        |                  |            modules            ||   artifacts   |
        |       conf       | number| search|dwnlded|evicted|| number|dwnlded|
        ---------------------------------------------------------------------
        |      default     |   1   |   0   |   0   |   0   ||   1   |   0   |
        ---------------------------------------------------------------------

-init-totals:

-test:
    [mkdir] Created dir: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test
[junit4:pickseed] Seed property 'tests.seed' already defined: 5DF46BD8703144C7
    [mkdir] Created dir: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/temp
   [junit4] <JUnit4> says aloha! Master seed: 5DF46BD8703144C7
   [junit4] Executing 5 suites with 2 JVMs.
   [junit4] 
   [junit4] Started J1 PID(8260@serv1-vm3).
   [junit4] Started J0 PID(8259@serv1-vm3).
   [junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
   [junit4] OK      0.07s J1 | NodeAddedTriggerTest.testActionLifecycle
   [junit4] OK      0.51s J1 | NodeAddedTriggerTest.testListenerAcceptance
   [junit4] OK      9.09s J1 | NodeAddedTriggerTest.testTrigger
   [junit4] OK      2.88s J1 | NodeAddedTriggerTest.testRestoreState
   [junit4] Completed [1/5] on J1 in 20.04s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
   [junit4] OK      0.06s J0 | NodeAddedTriggerTest.testActionLifecycle
   [junit4] OK      0.64s J0 | NodeAddedTriggerTest.testListenerAcceptance
   [junit4] OK      9.32s J0 | NodeAddedTriggerTest.testTrigger
   [junit4] OK      2.69s J0 | NodeAddedTriggerTest.testRestoreState
   [junit4] Completed [2/5] on J0 in 20.95s, 4 tests
   [junit4] 
   [junit4] Duplicate suite name used with XML reports: 
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest. This may confuse tools 
that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this 
message.
   [junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
   [junit4] OK      0.01s J1 | NodeAddedTriggerTest.testActionLifecycle
   [junit4] OK      0.57s J1 | NodeAddedTriggerTest.testListenerAcceptance
   [junit4] OK      10.4s J1 | NodeAddedTriggerTest.testTrigger
   [junit4] OK      2.96s J1 | NodeAddedTriggerTest.testRestoreState
   [junit4] Completed [3/5] on J1 in 16.54s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
   [junit4] OK      0.02s J0 | NodeAddedTriggerTest.testActionLifecycle
   [junit4] OK      1.44s J0 | NodeAddedTriggerTest.testListenerAcceptance
   [junit4] OK      9.91s J0 | NodeAddedTriggerTest.testTrigger
   [junit4] OK      2.57s J0 | NodeAddedTriggerTest.testRestoreState
   [junit4] Completed [4/5] on J0 in 16.98s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
   [junit4] OK      0.01s J1 | NodeAddedTriggerTest.testActionLifecycle
   [junit4] OK      0.57s J1 | NodeAddedTriggerTest.testListenerAcceptance
   [junit4] OK      9.13s J1 | NodeAddedTriggerTest.testTrigger
   [junit4] OK      2.83s J1 | NodeAddedTriggerTest.testRestoreState
   [junit4] Completed [5/5] on J1 in 16.15s, 4 tests
   [junit4] 
   [junit4] JVM J0:     0.85 ..    39.62 =    38.76s
   [junit4] JVM J1:     0.84 ..    54.69 =    53.85s
   [junit4] Execution time total: 54 seconds
   [junit4] Tests summary: 5 suites, 20 tests
   [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])
   [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])
     [echo] 5 slowest tests:
[junit4:tophints] 615.66s | org.apache.solr.cloud.BasicDistributedZkTest
[junit4:tophints] 216.61s | org.apache.solr.update.TestInPlaceUpdatesDistrib
[junit4:tophints] 192.37s | 
org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest
[junit4:tophints] 141.89s | 
org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4:tophints] 118.79s | org.apache.solr.cloud.ZkFailoverTest

-check-totals:

test-nocompile:

BUILD SUCCESSFUL
Total time: 58 seconds
[repro] Failures:
[repro]   0/5 failed: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
[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$34.hasMatch(FilePath.java:2662)
        at hudson.FilePath$34.invoke(FilePath.java:2541)
        at hudson.FilePath$34.invoke(FilePath.java:2531)
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2902)
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:1021)
                at hudson.FilePath.act(FilePath.java:1010)
                at hudson.FilePath.validateAntFileMask(FilePath.java:2531)
                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:1819)
                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:2904)
        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:1023)
        at hudson.FilePath.act(FilePath.java:1010)
        at hudson.FilePath.validateAntFileMask(FilePath.java:2531)
        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:1819)
        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=56998e6de3d1c71ad19a7400a99cd7dd2925de05, 
workspace=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris
[WARNINGS] Computing warning deltas based on reference build #582
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]

[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 583 - Still Unstable!

Reply via email to