Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/3086/
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest: 1) Thread[id=37817, name=zkConnectionManagerCallback-12994-thread-1, state=WAITING, group=TGRP-AutoscalingHistoryHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest: 1) Thread[id=37817, name=zkConnectionManagerCallback-12994-thread-1, state=WAITING, group=TGRP-AutoscalingHistoryHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([F3CE16358484368]:0) FAILED: junit.framework.TestSuite.org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=37817, name=zkConnectionManagerCallback-12994-thread-1, state=WAITING, group=TGRP-AutoscalingHistoryHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=37817, name=zkConnectionManagerCallback-12994-thread-1, state=WAITING, group=TGRP-AutoscalingHistoryHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([F3CE16358484368]:0) Build Log: [...truncated 14821 lines...] [junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest [junit4] 2> 3743111 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_F3CE16358484368-001/init-core-data-001 [junit4] 2> 3743120 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6 [junit4] 2> 3743120 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 3743122 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 3743122 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_F3CE16358484368-001/tempDir-001 [junit4] 2> 3743122 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 3743123 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3743123 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 3743223 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:33540 [junit4] 2> 3743223 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:33540 [junit4] 2> 3743223 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 33540 [junit4] 2> 3743226 INFO (zkConnectionManagerCallback-12870-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3743228 INFO (zkConnectionManagerCallback-12872-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3743229 INFO (zkConnectionManagerCallback-12874-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3743239 WARN (jetty-launcher-12875-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 3743239 WARN (jetty-launcher-12875-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 3743240 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 3743240 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 3743240 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 3743240 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 3743240 INFO (jetty-launcher-12875-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 3743240 INFO (jetty-launcher-12875-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 3743241 INFO (jetty-launcher-12875-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3743241 INFO (jetty-launcher-12875-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3743241 INFO (jetty-launcher-12875-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 3743241 INFO (jetty-launcher-12875-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3743241 INFO (jetty-launcher-12875-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3743241 INFO (jetty-launcher-12875-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 3743241 INFO (jetty-launcher-12875-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@67baff5a{/solr,null,AVAILABLE} [junit4] 2> 3743241 INFO (jetty-launcher-12875-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2bc1f7f7{/solr,null,AVAILABLE} [junit4] 2> 3743242 INFO (jetty-launcher-12875-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6a5942f3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42314} [junit4] 2> 3743250 INFO (jetty-launcher-12875-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7104a5a4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42151} [junit4] 2> 3743250 INFO (jetty-launcher-12875-thread-2) [ ] o.e.j.s.Server Started @3743292ms [junit4] 2> 3743250 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42151} [junit4] 2> 3743250 ERROR (jetty-launcher-12875-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3743250 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 3743250 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 3743250 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3743250 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3743250 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-25T05:13:17.629Z [junit4] 2> 3743254 INFO (jetty-launcher-12875-thread-1) [ ] o.e.j.s.Server Started @3743296ms [junit4] 2> 3743254 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42314} [junit4] 2> 3743254 ERROR (jetty-launcher-12875-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3743254 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 3743254 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 3743254 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3743254 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3743254 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-25T05:13:17.633Z [junit4] 2> 3743255 INFO (zkConnectionManagerCallback-12879-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3743256 INFO (zkConnectionManagerCallback-12877-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3743256 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3743270 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3743489 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 0x106880a94280004, likely client has closed socket [junit4] 2> 3743862 WARN (jetty-launcher-12875-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7912b524[provider=null,keyStore=null,trustStore=null] [junit4] 2> 3743956 WARN (jetty-launcher-12875-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7cbadc88[provider=null,keyStore=null,trustStore=null] [junit4] 2> 3743957 INFO (jetty-launcher-12875-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33540/solr [junit4] 2> 3743972 INFO (zkConnectionManagerCallback-12885-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3744044 INFO (zkConnectionManagerCallback-12887-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3744543 INFO (jetty-launcher-12875-thread-1) [n:127.0.0.1:42314_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42314_solr [junit4] 2> 3744543 INFO (jetty-launcher-12875-thread-1) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer Overseer (id=73896022914891782-127.0.0.1:42314_solr-n_0000000000) starting [junit4] 2> 3744559 INFO (zkConnectionManagerCallback-12894-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3744561 INFO (jetty-launcher-12875-thread-1) [n:127.0.0.1:42314_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33540/solr ready [junit4] 2> 3744562 INFO (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42314_solr [junit4] 2> 3744611 DEBUG (OverseerAutoScalingTriggerThread-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 3744627 INFO (jetty-launcher-12875-thread-1) [n:127.0.0.1:42314_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42314_solr [junit4] 2> 3744688 DEBUG (OverseerAutoScalingTriggerThread-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 3744689 DEBUG (OverseerAutoScalingTriggerThread-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0 [junit4] 2> 3744689 DEBUG (OverseerAutoScalingTriggerThread-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 3744733 INFO (zkCallback-12893-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3744769 INFO (zkCallback-12886-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3744992 DEBUG (OverseerAutoScalingTriggerThread-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:42314_solr] [junit4] 2> 3744992 INFO (jetty-launcher-12875-thread-1) [n:127.0.0.1:42314_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 3745275 DEBUG (OverseerAutoScalingTriggerThread-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers [junit4] 2> 3745276 DEBUG (OverseerAutoScalingTriggerThread-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 3745293 WARN (jetty-launcher-12875-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2c1c8d05[provider=null,keyStore=null,trustStore=null] [junit4] 2> 3745294 DEBUG (ScheduledTrigger-9709-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1 [junit4] 2> 3745366 WARN (jetty-launcher-12875-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@62d46a46[provider=null,keyStore=null,trustStore=null] [junit4] 2> 3745367 INFO (jetty-launcher-12875-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33540/solr [junit4] 2> 3745385 INFO (zkConnectionManagerCallback-12901-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3745393 INFO (jetty-launcher-12875-thread-1) [n:127.0.0.1:42314_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42314.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ccf519 [junit4] 2> 3745425 INFO (zkConnectionManagerCallback-12903-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3745463 INFO (jetty-launcher-12875-thread-1) [n:127.0.0.1:42314_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42314.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ccf519 [junit4] 2> 3745463 INFO (jetty-launcher-12875-thread-1) [n:127.0.0.1:42314_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42314.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ccf519 [junit4] 2> 3745464 INFO (jetty-launcher-12875-thread-1) [n:127.0.0.1:42314_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_F3CE16358484368-001/tempDir-001/node1/. [junit4] 2> 3745553 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3745609 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42151_solr as DOWN [junit4] 2> 3745609 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3745609 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42151_solr [junit4] 2> 3745610 INFO (zkCallback-12886-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3745656 INFO (zkCallback-12902-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3745656 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:42151_solr"} current state version: 0 [junit4] 2> 3745656 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:42151_solr [junit4] 2> 3745657 INFO (zkCallback-12893-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3745843 INFO (zkConnectionManagerCallback-12910-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3745846 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3745864 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33540/solr ready [junit4] 2> 3745864 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 3746076 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42151.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ccf519 [junit4] 2> 3746291 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42151.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ccf519 [junit4] 2> 3746291 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42151.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ccf519 [junit4] 2> 3746345 DEBUG (ScheduledTrigger-9709-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1 [junit4] 2> 3746365 INFO (jetty-launcher-12875-thread-2) [n:127.0.0.1:42151_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_F3CE16358484368-001/tempDir-001/node2/. [junit4] 2> 3746646 INFO (zkConnectionManagerCallback-12916-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3746647 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3746648 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[F3CE16358484368]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33540/solr ready [junit4] 2> 3746690 INFO (qtp1524981486-37561) [n:127.0.0.1:42314_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42314_solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 3746737 INFO (OverseerThreadFactory-9711-thread-1-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system [junit4] 2> 3746761 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "name":".system", [junit4] 2> "fromApi":"true", [junit4] 2> "numShards":1, [junit4] 2> "createNodeSet":"127.0.0.1:42314_solr", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "stateFormat":"2", [junit4] 2> "replicationFactor":"1", [junit4] 2> "operation":"create"} current state version: 0 [junit4] 2> 3746761 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system [junit4] 2> 3746762 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json [junit4] 2> 3746945 DEBUG (OverseerThreadFactory-9711-thread-1-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session [junit4] 2> 3746966 INFO (qtp1524981486-37565) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3746967 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3746968 INFO (qtp1524981486-37559) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3746968 INFO (qtp1524981486-37563) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3746984 INFO (qtp1524981486-37565) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=15 [junit4] 2> 3746997 INFO (qtp1460245449-37562) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=12 [junit4] 2> 3746998 INFO (qtp1460245449-37562) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3746999 INFO (qtp1460245449-37562) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3746999 INFO (qtp1460245449-37562) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3747000 INFO (qtp1460245449-37562) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3747000 DEBUG (OverseerThreadFactory-9711-thread-1-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.s.c.a.PolicyHelper New session created [junit4] 2> 3747018 DEBUG (OverseerThreadFactory-9711-thread-1-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 28052202334 sessionWrapper.createTime 28052202316495453, this.sessionWrapper.createTime 28052202316495453 [junit4] 2> 3747020 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":".system", [junit4] 2> "shard":"shard1", [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 3747020 INFO (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":".system", [junit4] 2> "shard":"shard1", [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 3747021 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 3747021 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 3747171 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0 [junit4] 2> 3747296 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr x:.system_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 3747297 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr x:.system_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3747355 DEBUG (ScheduledTrigger-9709-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2 [junit4] 2> 3747399 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "node_name":"127.0.0.1:42314_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 3747399 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "node_name":"127.0.0.1:42314_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 3747399 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":".system_shard1_replica_n1","base_url":"http://127.0.0.1:42314/solr","node_name":"127.0.0.1:42314_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 3747399 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"1", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"1", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "node_name":"127.0.0.1:42314_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 3747508 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1 [junit4] 2> 3748356 DEBUG (ScheduledTrigger-9709-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2 [junit4] 2> 3748581 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 3748622 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead [junit4] 2> 3748679 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core [junit4] 2> 3748737 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id [junit4] 2> 3748881 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema [junit4] 2> 3748883 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak [junit4] 2> 3748883 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from collection .system, trusted=true [junit4] 2> 3748884 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42314.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ccf519 [junit4] 2> 3748884 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_F3CE16358484368-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_F3CE16358484368-001/tempDir-001/node1/./.system_shard1_replica_n1/data/] [junit4] 2> 3749372 DEBUG (ScheduledTrigger-9709-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2 [junit4] 2> 3749416 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 3749416 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 3749419 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs; [junit4] 2> 3749419 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3749420 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bad8f23[.system_shard1_replica_n1] main] [junit4] 2> 3749490 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system [junit4] 2> 3749490 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system [junit4] 2> 3749490 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema [junit4] 2> 3749491 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 3749491 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 3749491 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1620799446182789120 [junit4] 2> 3749506 INFO (searcherExecutor-9723-thread-1-processing-n:127.0.0.1:42314_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@bad8f23[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3749524 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 3749525 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1 [junit4] 2> 3749543 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3749543 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 3749543 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42314/solr/.system_shard1_replica_n1/ [junit4] 2> 3749543 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3749543 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:42314/solr/.system_shard1_replica_n1/ has no replicas [junit4] 2> 3749544 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/73896022914891782-core_node2-n_0000000000 [junit4] 2> 3749545 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42314/solr/.system_shard1_replica_n1/ shard1 [junit4] 2> 3749545 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "state":"active"} current state version: 0 [junit4] 2> 3749576 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2 [junit4] 2> 3749615 INFO (zkCallback-12886-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 3749632 INFO (zkCallback-12886-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 3749667 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3749669 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "node_name":"127.0.0.1:42314_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 3749669 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "node_name":"127.0.0.1:42314_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 3749669 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":".system_shard1_replica_n1","base_url":"http://127.0.0.1:42314/solr","node_name":"127.0.0.1:42314_solr","state":"active","type":"NRT","force_set_state":"false","leader":"true"} [junit4] 2> 3749669 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/3)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"1", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"1", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42314/solr", [junit4] 2> "node_name":"127.0.0.1:42314_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "leader":"true"}}}}} [junit4] 2> 3749670 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2373 [junit4] 2> 3749670 DEBUG (OverseerThreadFactory-9711-thread-1-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL [junit4] 2> 3749712 INFO (qtp1524981486-37561) [n:127.0.0.1:42314_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 3749781 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3 [junit4] 2> 3749781 INFO (zkCallback-12886-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 3749782 INFO (zkCallback-12886-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 3749782 INFO (qtp1524981486-37561) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42314_solr&wt=javabin&version=2} status=0 QTime=3091 [junit4] 2> 3749801 INFO (zkCallback-12886-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 3749866 INFO (qtp1524981486-37559) [n:127.0.0.1:42314_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params maxShardsPerNode=3&name=AutoscalingHistoryHandlerTest_collection&nrtReplicas=3&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42151_solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 3750018 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection [junit4] 2> 3750088 INFO (OverseerCollectionConfigSetProcessor-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 3750125 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt [junit4] 2> 3750142 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema [junit4] 2> 3750143 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml [junit4] 2> 3750144 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt [junit4] 2> 3750162 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt [junit4] 2> 3750163 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/params.json [junit4] 2> 3750164 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_it.txt [junit4] 2> 3750165 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_no.txt [junit4] 2> 3750180 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hy.txt [junit4] 2> 3750207 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stemdict_nl.txt [junit4] 2> 3750209 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ca.txt [junit4] 2> 3750230 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_id.txt [junit4] 2> 3750232 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ga.txt [junit4] 2> 3750233 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_da.txt [junit4] 2> 3750267 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hi.txt [junit4] 2> 3750269 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ja.txt [junit4] 2> 3750270 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_pt.txt [junit4] 2> 3750288 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hu.txt [junit4] 2> 3750289 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_el.txt [junit4] 2> 3750290 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ru.txt [junit4] 2> 3750307 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_tr.txt [junit4] 2> 3750342 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ar.txt [junit4] 2> 3750343 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/userdict_ja.txt [junit4] 2> 3750372 DEBUG (ScheduledTrigger-9709-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2 [junit4] 2> 3750373 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_it.txt [junit4] 2> 3750375 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_eu.txt [junit4] 2> 3750405 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_cz.txt [junit4] 2> 3750407 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stoptags_ja.txt [junit4] 2> 3750408 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_fr.txt [junit4] 2> 3750434 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fa.txt [junit4] 2> 3750436 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_de.txt [junit4] 2> 3750474 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ga.txt [junit4] 2> 3750476 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ca.txt [junit4] 2> 3750477 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_nl.txt [junit4] 2> 3750492 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_bg.txt [junit4] 2> 3750493 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_es.txt [junit4] 2> 3750506 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_en.txt [junit4] 2> 3750507 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_lv.txt [junit4] 2> 3750508 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_th.txt [junit4] 2> 3750509 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ro.txt [junit4] 2> 3750527 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fr.txt [junit4] 2> 3750529 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_sv.txt [junit4] 2> 3750575 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fi.txt [junit4] 2> 3750576 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/hyphenations_ga.txt [junit4] 2> 3750577 INFO (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_gl.txt [junit4] 2> 3750614 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "name":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "fromApi":"true", [junit4] 2> "numShards":"1", [junit4] 2> "maxShardsPerNode":"3", [junit4] 2> "createNodeSet":"127.0.0.1:42151_solr", [junit4] 2> "nrtReplicas":"3", [junit4] 2> "stateFormat":"2", [junit4] 2> "replicationFactor":"3", [junit4] 2> "operation":"create"} current state version: 0 [junit4] 2> 3750614 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection [junit4] 2> 3750746 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json [junit4] 2> 3750891 WARN (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection AutoscalingHistoryHandlerTest_collection is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance. [junit4] 2> 3750891 DEBUG (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session [junit4] 2> 3750896 INFO (qtp1524981486-37563) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core..system.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 3750907 INFO (qtp1524981486-37565) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core..system.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 3750907 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core..system.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 3750909 INFO (qtp1524981486-37561) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3750909 INFO (qtp1524981486-37563) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3750910 INFO (qtp1524981486-37565) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3750919 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=8 [junit4] 2> 3750920 INFO (qtp1524981486-37567) [n:127.0.0.1:42314_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3750927 INFO (qtp1460245449-37564) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3750928 INFO (qtp1460245449-37566) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3750929 INFO (qtp1460245449-37568) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3750929 INFO (qtp1460245449-37560) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3750933 INFO (qtp1460245449-37562) [n:127.0.0.1:42151_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3750934 DEBUG (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.s.c.a.PolicyHelper New session created [junit4] 2> 3750965 DEBUG (OverseerThreadFactory-9711-thread-2-processing-n:127.0.0.1:42314_solr) [n:127.0.0.1:42314_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 28052206281 sessionWrapper.createTime 28052206250453733, this.sessionWrapper.createTime 28052206250453733 [junit4] 2> 3751117 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 3751117 INFO (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 3751166 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 3751166 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node4":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 3751251 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 3751251 INFO (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 3751272 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node4":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 3751272 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 3751272 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 3751272 INFO (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 3751273 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 3751273 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node6":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 3751385 DEBUG (ScheduledTrigger-9709-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2 [junit4] 2> 3751395 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0 [junit4] 2> 3751474 INFO (qtp1460245449-37564) [n:127.0.0.1:42151_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 3751483 INFO (qtp1460245449-37568) [n:127.0.0.1:42151_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n2&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 3751541 INFO (qtp1460245449-37562) [n:127.0.0.1:42151_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n3&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 3751644 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3", [junit4] 2> "core_node_name":"core_node6", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 3751644 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3", [junit4] 2> "core_node_name":"core_node6", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 3751644 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node6:{"core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3","base_url":"http://127.0.0.1:42151/solr","node_name":"127.0.0.1:42151_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 3751644 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"3", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"3", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"3", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node6":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 3751645 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node4", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 3751645 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node4", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 3751645 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{"core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1","base_url":"http://127.0.0.1:42151/solr","node_name":"127.0.0.1:42151_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 3751645 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"3", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"3", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"3", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node6":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 3751645 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "core_node_name":"core_node5", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 3751645 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "core_node_name":"core_node5", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 3751645 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node5:{"core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2","base_url":"http://127.0.0.1:42151/solr","node_name":"127.0.0.1:42151_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 3751645 DEBUG (OverseerStateUpdate-73896022914891782-127.0.0.1:42314_solr-n_0000000000) [n:127.0.0.1:42314_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"3", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"3", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"3", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:42151/solr", [junit4] 2> "node_name":"127.0.0.1:42151_solr", [junit4] [...truncated too long message...] -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1739892273 [copy] Copying 256 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1739892273 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1739892273 check-working-copy: [ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working [ivy:cachepath] confs: [default] [ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public [ivy:cachepath] found com.jcraft#jsch;0.1.53 in public [ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public [ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public [ivy:cachepath] found commons-codec#commons-codec;1.6 in public [ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public [ivy:cachepath] :: resolution report :: resolve 19ms :: artifacts dl 1ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 8 | 0 | 0 | 0 || 8 | 0 | --------------------------------------------------------------------- [wc-checker] Initializing working copy... [wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". [wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation [wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. [wc-checker] Checking working copy status... -jenkins-base: BUILD SUCCESSFUL Total time: 193 minutes 6 seconds Archiving artifacts java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$34.hasMatch(FilePath.java:2678) at hudson.FilePath$34.invoke(FilePath.java:2557) at hudson.FilePath$34.invoke(FilePath.java:2547) at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2 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:1036) at hudson.FilePath.act(FilePath.java:1025) at hudson.FilePath.validateAntFileMask(FilePath.java:2547) 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:2920) 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:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 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:1038) at hudson.FilePath.act(FilePath.java:1025) at hudson.FilePath.validateAntFileMask(FilePath.java:2547) 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/**,**/hs_err_pid*". Configuration error? Recording test results 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)
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org