Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/210/
No tests ran.
Build Log:
[...truncated 11695 lines...]
[junit4] JVM J0: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171101_225113_066886789534836535979.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # There is insufficient memory for the Java Runtime Environment to
continue.
[junit4] # Native memory allocation (mmap) failed to map 44040192 bytes for
committing reserved memory.
[junit4] # An error report file with more information is saved as:
[junit4] #
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/hs_err_pid29289.log
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J0: stderr was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171101_225113_066829234350085015639.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: INFO:
os::commit_memory(0x00000000ea980000, 44040192, 0) failed; error='Cannot
allocate memory' (errno=12)
[junit4] <<< JVM J0: EOF ----
[...truncated 153 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
[junit4] 2> 1041213 INFO
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/init-core-data-001
[junit4] 2> 1041213 INFO
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1041214 INFO
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-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> 1041214 INFO
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001
[junit4] 2> 1041214 INFO
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1041215 INFO (Thread-422) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1041215 INFO (Thread-422) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1041331 ERROR (Thread-422) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 1041331 INFO
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:57542
[junit4] 2> 1041437 INFO (jetty-launcher-479-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1041437 INFO (jetty-launcher-479-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1041438 INFO (jetty-launcher-479-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5c00d5b5{/solr,null,AVAILABLE}
[junit4] 2> 1041451 INFO (jetty-launcher-479-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@517afa5c{HTTP/1.1,[http/1.1]}{127.0.0.1:47504}
[junit4] 2> 1041451 INFO (jetty-launcher-479-thread-1) [ ]
o.e.j.s.Server Started @1064698ms
[junit4] 2> 1041451 INFO (jetty-launcher-479-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=47504}
[junit4] 2> 1041451 ERROR (jetty-launcher-479-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1041451 INFO (jetty-launcher-479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.2.0
[junit4] 2> 1041451 INFO (jetty-launcher-479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1041451 INFO (jetty-launcher-479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1041451 INFO (jetty-launcher-479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-01T23:08:57.977Z
[junit4] 2> 1042715 INFO (jetty-launcher-479-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5d22bd95{/solr,null,AVAILABLE}
[junit4] 2> 1042716 INFO (jetty-launcher-479-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@b104c48{HTTP/1.1,[http/1.1]}{127.0.0.1:59076}
[junit4] 2> 1042716 INFO (jetty-launcher-479-thread-2) [ ]
o.e.j.s.Server Started @1065963ms
[junit4] 2> 1042716 INFO (jetty-launcher-479-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=59076}
[junit4] 2> 1042717 ERROR (jetty-launcher-479-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1042717 INFO (jetty-launcher-479-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.2.0
[junit4] 2> 1042717 INFO (jetty-launcher-479-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1042717 INFO (jetty-launcher-479-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1042717 INFO (jetty-launcher-479-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-01T23:08:59.243Z
[junit4] 2> 1042834 INFO (jetty-launcher-479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1042876 INFO (jetty-launcher-479-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1042901 INFO (jetty-launcher-479-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57542/solr
[junit4] 2> 1042915 INFO (jetty-launcher-479-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57542/solr
[junit4] 2> 1043309 INFO (jetty-launcher-479-thread-2)
[n:127.0.0.1:59076_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1043309 INFO (jetty-launcher-479-thread-2)
[n:127.0.0.1:59076_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:59076_solr
[junit4] 2> 1043310 INFO (jetty-launcher-479-thread-2)
[n:127.0.0.1:59076_solr ] o.a.s.c.Overseer Overseer
(id=98931686625443846-127.0.0.1:59076_solr-n_0000000000) starting
[junit4] 2> 1043444 INFO (jetty-launcher-479-thread-2)
[n:127.0.0.1:59076_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:59076_solr
[junit4] 2> 1043565 INFO
(zkCallback-492-thread-1-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1043905 INFO (jetty-launcher-479-thread-1)
[n:127.0.0.1:47504_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1043906 INFO (jetty-launcher-479-thread-1)
[n:127.0.0.1:47504_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1043908 INFO (jetty-launcher-479-thread-1)
[n:127.0.0.1:47504_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47504_solr
[junit4] 2> 1043915 INFO
(zkCallback-492-thread-1-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1044217 INFO
(zkCallback-490-thread-1-processing-n:127.0.0.1:47504_solr)
[n:127.0.0.1:47504_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1044586 INFO (jetty-launcher-479-thread-2)
[n:127.0.0.1:59076_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59076.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1044715 INFO (jetty-launcher-479-thread-2)
[n:127.0.0.1:59076_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59076.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1044715 INFO (jetty-launcher-479-thread-2)
[n:127.0.0.1:59076_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59076.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1044732 INFO (jetty-launcher-479-thread-2)
[n:127.0.0.1:59076_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node2/.
[junit4] 2> 1044794 INFO (jetty-launcher-479-thread-1)
[n:127.0.0.1:47504_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47504.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1044880 INFO (jetty-launcher-479-thread-1)
[n:127.0.0.1:47504_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47504.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1044915 INFO (jetty-launcher-479-thread-1)
[n:127.0.0.1:47504_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47504.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1044916 INFO (jetty-launcher-479-thread-1)
[n:127.0.0.1:47504_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node1/.
[junit4] 2> 1046272 INFO
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1046273 INFO
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1046311 INFO
(TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[1C436BE69425E52E]) [
] o.a.s.SolrTestCaseJ4 ###Starting testDeleteUsedPolicy
[junit4] 2> 1047967 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1047968 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1047989 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1048170 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1048186 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=424
[junit4] 2> 1048186 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=COLL1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=nodelete
and sendToOCPQueue=true
[junit4] 2> 1048214 INFO
(OverseerThreadFactory-919-thread-1-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.CreateCollectionCmd Create collection COLL1
[junit4] 2> 1048345 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1048417 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1049768 INFO
(OverseerStateUpdate-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"COLL1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"COLL1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59076/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1050042 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=COLL1_shard1_replica_n1&action=CREATE&numShards=1&collection=COLL1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1050043 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1050162 INFO
(zkCallback-492-thread-1-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 1050180 INFO
(zkCallback-492-thread-2-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 1051150 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.2.0
[junit4] 2> 1051260 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.s.IndexSchema
[COLL1_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1051363 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1051363 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.CoreContainer
Creating SolrCore 'COLL1_shard1_replica_n1' using configuration from collection
COLL1, trusted=true
[junit4] 2> 1051363 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59076.solr.core.COLL1.shard1.replica_n1' (registry
'solr.core.COLL1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1051364 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1051364 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SolrCore
[[COLL1_shard1_replica_n1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node2/COLL1_shard1_replica_n1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node2/./COLL1_shard1_replica_n1/data/]
[junit4] 2> 1053973 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1053973 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1053974 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1053974 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1053997 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@537c362d[COLL1_shard1_replica_n1]
main]
[junit4] 2> 1054017 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1054018 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1054018 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1054019 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1582906999355473920
[junit4] 2> 1054116 INFO
(searcherExecutor-924-thread-1-processing-n:127.0.0.1:59076_solr
x:COLL1_shard1_replica_n1 s:shard1 c:COLL1 r:core_node2)
[n:127.0.0.1:59076_solr c:COLL1 s:shard1 r:core_node2
x:COLL1_shard1_replica_n1] o.a.s.c.SolrCore [COLL1_shard1_replica_n1]
Registered new searcher Searcher@537c362d[COLL1_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1054121 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1054121 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1054122 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:59076/solr/COLL1_shard1_replica_n1/
[junit4] 2> 1054122 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1054122 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:59076/solr/COLL1_shard1_replica_n1/ has no replicas
[junit4] 2> 1054122 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1054126 INFO
(zkCallback-492-thread-3-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 1054126 INFO
(zkCallback-492-thread-2-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 1054127 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:59076/solr/COLL1_shard1_replica_n1/ shard1
[junit4] 2> 1054128 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 1054129 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=COLL1_shard1_replica_n1&action=CREATE&numShards=1&collection=COLL1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=4087
[junit4] 2> 1054137 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 1054240 INFO
(zkCallback-492-thread-2-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 1054240 INFO
(zkCallback-492-thread-3-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 1054254 INFO
(OverseerCollectionConfigSetProcessor-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_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> 1055145 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=COLL1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=nodelete}
status=0 QTime=6958
[junit4] 2> 1055213 ERROR (qtp351588003-2389) [n:127.0.0.1:59076_solr ]
o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject:
Error in command payload, errors: [{remove-policy=nodelete,
errorMessages=[policy : nodelete is being used by collection COLL1]}],
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:211)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
[junit4] 2> at
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:308)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1055213 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=400 QTime=4
[junit4] 2> 1055222 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=COLL1&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1055281 INFO
(OverseerThreadFactory-919-thread-2-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1055282 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.COLL1.shard1.replica_n1, tag=null
[junit4] 2> 1055282 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@88cefab: rootName =
solr_59076, domain = solr.core.COLL1.shard1.replica_n1, service url = null,
agent id = null] for registry solr.core.COLL1.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@75ca32d6
[junit4] 2> 1055326 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.c.SolrCore [COLL1_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@10e816e1
[junit4] 2> 1055326 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.COLL1.shard1.replica_n1, tag=283645665
[junit4] 2> 1055326 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.COLL1.shard1.leader, tag=283645665
[junit4] 2> 1055423 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=COLL1_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=141
[junit4] 2> 1056185 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=COLL1&action=DELETE&wt=javabin&version=2} status=0 QTime=963
[junit4] 2> 1056186 INFO
(TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[1C436BE69425E52E]) [
] o.a.s.SolrTestCaseJ4 ###Ending testDeleteUsedPolicy
[junit4] 2> 1056316 INFO
(TEST-AutoScalingHandlerTest.testSuspendTrigger-seed#[1C436BE69425E52E]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testSuspendTrigger
[junit4] 2> 1056324 INFO (qtp46097853-2373) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 1056341 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1056765 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1056766 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1056773 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1057307 INFO
(OverseerCollectionConfigSetProcessor-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1057429 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1057434 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1085
[junit4] 2> 1058406 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1058406 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1058414 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1058415 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1058534 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1099
[junit4] 2> 1059954 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1059954 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1059956 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1059957 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1059997 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1462
[junit4] 2> 1060364 INFO (qtp46097853-2373) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1060365 INFO (qtp46097853-2373) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1060369 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060369 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060434 INFO (qtp46097853-2373) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=436
[junit4] 2> 1060489 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1060490 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1060490 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060491 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060514 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=69
[junit4] 2> 1060598 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1060599 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1060599 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060615 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060626 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=111
[junit4] 2> 1060676 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1060677 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1060677 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060677 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060696 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=68
[junit4] 2> 1060705 INFO
(TEST-AutoScalingHandlerTest.testSuspendTrigger-seed#[1C436BE69425E52E]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testSuspendTrigger
[junit4] 2> 1060796 INFO
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[1C436BE69425E52E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testPolicyAndPreferences
[junit4] 2> 1060810 ERROR (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject:
Error in command payload, errors: [{set-policy={xyz=[{replica=<2, shard=#EACH,
node=#ANY}, {nodeRole=!overseer, replica=0}], policy1=[{cores=<2, node=#ANY},
{replica=<2, shard=#EACH, node=#ANY}]}, errorMessages=[cores is only allowed in
'cluster-policy']}],
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:211)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
[junit4] 2> at
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:308)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1060812 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=400 QTime=3
[junit4] 2> 1060850 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1060851 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1060851 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060865 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060876 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=63
[junit4] 2> 1060914 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1060915 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1060915 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060915 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1060917 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=40
[junit4] 2> 1060981 INFO (qtp351588003-2388) [n:127.0.0.1:59076_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1060981 INFO (qtp351588003-2388) [n:127.0.0.1:59076_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1060982 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1061005 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1061018 INFO (qtp351588003-2388) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=81
[junit4] 2> 1061062 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1061062 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1061402 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=337
[junit4] 2> 1061403 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1061417 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=398
[junit4] 2> 1061533 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1061537 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1061545 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm}
status=0 QTime=0
[junit4] 2> 1061546 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm}
status=0 QTime=0
[junit4] 2> 1061560 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=63
[junit4] 2> 1061647 INFO (qtp46097853-2373) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1061648 INFO (qtp46097853-2373) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1061665 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core}
status=0 QTime=0
[junit4] 2> 1061685 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core}
status=0 QTime=0
[junit4] 2> 1061747 INFO (qtp46097853-2373) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=186
[junit4] 2> 1061764 INFO
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[1C436BE69425E52E])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testPolicyAndPreferences
[junit4] 2> 1061867 INFO
(TEST-AutoScalingHandlerTest.testReadApi-seed#[1C436BE69425E52E]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testReadApi
[junit4] 2> 1061941 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1061944 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1061945 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1061965 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1062015 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=146
[junit4] 2> 1062434 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1062435 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1063977 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1063978 INFO (qtp351588003-2388) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1063990 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=1962
[junit4] 2> 1064346 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1064346 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1064354 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=7
[junit4] 2> 1064365 INFO (qtp351588003-2388) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1064390 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=400
[junit4] 2> 1064425 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1064444 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1064445 INFO (qtp46097853-2373) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1064446 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1064493 INFO (qtp46097853-2377) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=101
[junit4] 2> 1064497 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 1064589 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1064593 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1064626 INFO (qtp46097853-2376) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1064665 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1064705 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling/diagnostics
params={wt=javabin&version=2} status=0 QTime=200
[junit4] 2> 1064705 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=6&collection.configName=conf&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1064707 INFO
(OverseerThreadFactory-919-thread-3-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.CreateCollectionCmd Create collection
readApiTestViolations
[junit4] 2> 1064708 ERROR
(OverseerThreadFactory-919-thread-3-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: readApiTestViolations operation: create
failed:org.apache.solr.common.SolrException: 'maxShardsPerNode>0' is not
supported when autoScaling policies are used
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:136)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:232)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1064716 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=6&collection.configName=conf&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2}
status=400 QTime=11
[junit4] 2> 1064761 INFO
(OverseerCollectionConfigSetProcessor-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1064867 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1064867 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57542/solr ready
[junit4] 2> 1064868 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1064886 INFO (qtp351588003-2386) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1064946 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f79d84657001c, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1064952 INFO (qtp351588003-2385) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=231
[junit4] 2> 1064957 INFO (qtp46097853-2380) [n:127.0.0.1:47504_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=6&collection.configName=conf&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1064989 INFO
(OverseerThreadFactory-919-thread-4-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.CreateCollectionCmd Create collection
readApiTestViolations
[junit4] 2> 1064990 WARN
(OverseerThreadFactory-919-thread-4-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.CreateCollectionCmd Specified number of
replicas of 6 on collection readApiTestViolations is higher than the number of
Solr instances currently live or live and part of your createNodeSet(2). It's
unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1065097 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1065114 INFO (qtp351588003-2383) [n:127.0.0.1:59076_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1066334 INFO
(OverseerStateUpdate-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:47504/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1066336 INFO
(OverseerStateUpdate-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59076/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1066353 INFO
(OverseerStateUpdate-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:47504/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1066419 INFO
(OverseerStateUpdate-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n7",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59076/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1066513 INFO
(OverseerStateUpdate-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n9",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:47504/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1067046 INFO
(OverseerStateUpdate-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n11",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59076/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1067245 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n1&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1067245 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1067248 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n4&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1067252 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n9&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1067254 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n7&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1067269 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n2&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1067269 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n11&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1067381 INFO
(zkCallback-492-thread-3-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/readApiTestViolations/state.json] for collection
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1067384 INFO
(zkCallback-492-thread-2-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/readApiTestViolations/state.json] for collection
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1067384 INFO
(zkCallback-490-thread-1-processing-n:127.0.0.1:47504_solr)
[n:127.0.0.1:47504_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/readApiTestViolations/state.json] for collection
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1068274 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 1068286 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 1068301 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node6
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 1068320 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 1068414 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n7] Schema name=minimal
[junit4] 2> 1068434 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n9] Schema name=minimal
[junit4] 2> 1068435 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 1068438 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1068438 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.c.CoreContainer Creating
SolrCore 'readApiTestViolations_shard1_replica_n7' using configuration from
collection readApiTestViolations, trusted=true
[junit4] 2> 1068439 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_59076.solr.core.readApiTestViolations.shard1.replica_n7'
(registry 'solr.core.readApiTestViolations.shard1.replica_n7') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1068439 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1068439 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n7] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n7],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n7/data/]
[junit4] 2> 1068485 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 1068507 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n2] Schema name=minimal
[junit4] 2> 1068509 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1068509 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.c.CoreContainer Creating
SolrCore 'readApiTestViolations_shard1_replica_n9' using configuration from
collection readApiTestViolations, trusted=true
[junit4] 2> 1068509 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_47504.solr.core.readApiTestViolations.shard1.replica_n9'
(registry 'solr.core.readApiTestViolations.shard1.replica_n9') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1068509 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1068509 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n9] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n9],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n9/data/]
[junit4] 2> 1068526 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n11] Schema name=minimal
[junit4] 2> 1068528 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1068528 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.c.CoreContainer Creating
SolrCore 'readApiTestViolations_shard1_replica_n11' using configuration from
collection readApiTestViolations, trusted=true
[junit4] 2> 1068540 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1068540 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.CoreContainer Creating
SolrCore 'readApiTestViolations_shard1_replica_n2' using configuration from
collection readApiTestViolations, trusted=true
[junit4] 2> 1068541 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_59076.solr.core.readApiTestViolations.shard1.replica_n2'
(registry 'solr.core.readApiTestViolations.shard1.replica_n2') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1068541 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1068541 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n2] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n2],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n2/data/]
[junit4] 2> 1068562 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1068564 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1068564 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'readApiTestViolations_shard1_replica_n1' using configuration from
collection readApiTestViolations, trusted=true
[junit4] 2> 1068578 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node6
x:readApiTestViolations_shard1_replica_n4] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n4] Schema name=minimal
[junit4] 2> 1068580 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node6
x:readApiTestViolations_shard1_replica_n4] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1068580 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node6
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.CoreContainer Creating
SolrCore 'readApiTestViolations_shard1_replica_n4' using configuration from
collection readApiTestViolations, trusted=true
[junit4] 2> 1068580 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node6
x:readApiTestViolations_shard1_replica_n4] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_47504.solr.core.readApiTestViolations.shard1.replica_n4'
(registry 'solr.core.readApiTestViolations.shard1.replica_n4') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1068580 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node6
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1068589 INFO (qtp46097853-2379) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node6
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n4] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n4],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n4/data/]
[junit4] 2> 1068812 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_47504.solr.core.readApiTestViolations.shard1.replica_n1'
(registry 'solr.core.readApiTestViolations.shard1.replica_n1') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1068812 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1068813 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n1/data/]
[junit4] 2> 1068589 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_59076.solr.core.readApiTestViolations.shard1.replica_n11'
(registry 'solr.core.readApiTestViolations.shard1.replica_n11') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@68d6f828
[junit4] 2> 1069492 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1069493 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1069493 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1069493 INFO (qtp351588003-2389) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n11] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n11],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n11/data/]
[junit4] 2> 1069493 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1069494 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1069543 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1069543 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1069544 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1069544 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1069572 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.s.SolrIndexSearcher Opening
[Searcher@58223732[readApiTestViolations_shard1_replica_n7] main]
[junit4] 2> 1069572 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.s.SolrIndexSearcher Opening
[Searcher@5680995e[readApiTestViolations_shard1_replica_n9] main]
[junit4] 2> 1069573 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1069574 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1069574 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 1069574 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1069575 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1069575 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 1069575 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1582907015667122176
[junit4] 2> 1069605 INFO
(searcherExecutor-929-thread-1-processing-n:127.0.0.1:59076_solr
x:readApiTestViolations_shard1_replica_n7 s:shard1 c:readApiTestViolations
r:core_node8) [n:127.0.0.1:59076_solr c:readApiTestViolations s:shard1
r:core_node8 x:readApiTestViolations_shard1_replica_n7] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n7] Registered new searcher
Searcher@58223732[readApiTestViolations_shard1_replica_n7]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1069605 INFO (qtp351588003-2382) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1582907015698579456
[junit4] 2> 1069609 INFO
(searcherExecutor-930-thread-1-processing-n:127.0.0.1:47504_solr
x:readApiTestViolations_shard1_replica_n9 s:shard1 c:readApiTestViolations
r:core_node10) [n:127.0.0.1:47504_solr c:readApiTestViolations s:shard1
r:core_node10 x:readApiTestViolations_shard1_replica_n9] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n9] Registered new searcher
Searcher@5680995e[readApiTestViolations_shard1_replica_n9]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1069609 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1069609 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1069613 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1069614 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1069629 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@491d1cf8[readApiTestViolations_shard1_replica_n1] main]
[junit4] 2> 1070413 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1070413 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1070414 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 1070414 INFO (qtp46097853-2374) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1582907016546877440
[junit4] 2> 1070498 INFO
(zkCallback-490-thread-1-processing-n:127.0.0.1:47504_solr)
[n:127.0.0.1:47504_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/readApiTestViolations/state.json] for collection
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1070499 INFO
(zkCallback-492-thread-3-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/readApiTestViolations/state.json] for collection
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1070499 INFO
(zkCallback-492-thread-2-processing-n:127.0.0.1:59076_solr)
[n:127.0.0.1:59076_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/readApiTestViolations/state.json] for collection
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1070524 INFO (qtp46097853-2378) [n:127.0.0.1:47504_solr
c:readApiTestViolations s:shard1 r:core_node10
x:readApiTestViolations_shard1_replica_n9] o.a.s.c.ShardLeaderElectionContext
Waiting until we see more replicas up for shard shard1: total=6 found=2
timeoutin=9993ms
[junit4] 2> 1070610 INFO
(searcherExecutor-933-thread-1-processing-n:127.0.0.1:47504_solr
x:readApiTestViolations_shard1_replica_n1 s:shard1 c:readApiTestViolations
r:core_node3) [n:127.0.0.1:47504_solr c:readApiTestViolations s:shard1
r:core_node3 x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n1] Registered new searcher
Searcher@491d1cf8[readApiTestViolations_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1070879 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1070879 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1070885 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1070885 INFO (qtp351588003-2387) [n:127.0.0.1:59076_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1070889 INFO (qtp35158800
[...truncated too long message...]
metrics.reporters.SolrJmxReporter@5238089c: rootName = solr_57308, domain =
solr.core.readApiTestViolations.shard1.replica_n13, service url = null, agent
id = null] for registry solr.core.readApiTestViolations.shard1.replica_n13 /
com.codahale.metrics.MetricRegistry@486073d2
[junit4] 2> 1103132 INFO
(zkCallback-569-thread-3-processing-n:127.0.0.1:57308_solr)
[n:127.0.0.1:57308_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (0)
[junit4] 2> 1103133 INFO (coreCloseExecutor-970-thread-2)
[n:127.0.0.1:47504_solr c:readApiTestViolations s:shard1 r:core_node6
x:readApiTestViolations_shard1_replica_n4] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@64a667d2: rootName
= solr_47504, domain = solr.core.readApiTestViolations.shard1.replica_n4,
service url = null, agent id = null] for registry
solr.core.readApiTestViolations.shard1.replica_n4 /
com.codahale.metrics.MetricRegistry@2088140e
[junit4] 2> 1103178 INFO (coreCloseExecutor-972-thread-1)
[n:127.0.0.1:57308_solr c:readApiTestViolations s:shard1 r:core_node14
x:readApiTestViolations_shard1_replica_n13] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1604109779
[junit4] 2> 1103191 INFO (coreCloseExecutor-970-thread-2)
[n:127.0.0.1:47504_solr c:readApiTestViolations s:shard1 r:core_node6
x:readApiTestViolations_shard1_replica_n4] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1127557188
[junit4] 2> 1103236 INFO (coreCloseExecutor-971-thread-1)
[n:127.0.0.1:59076_solr c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n7, tag=592287867
[junit4] 2> 1103236 INFO (coreCloseExecutor-971-thread-1)
[n:127.0.0.1:59076_solr c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ada1d64: rootName
= solr_59076, domain = solr.core.readApiTestViolations.shard1.replica_n7,
service url = null, agent id = null] for registry
solr.core.readApiTestViolations.shard1.replica_n7 /
com.codahale.metrics.MetricRegistry@522db41d
[junit4] 2> 1103284 INFO (coreCloseExecutor-971-thread-3)
[n:127.0.0.1:59076_solr c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n11, tag=1230924101
[junit4] 2> 1103285 INFO (coreCloseExecutor-971-thread-3)
[n:127.0.0.1:59076_solr c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3e5c6163: rootName
= solr_59076, domain = solr.core.readApiTestViolations.shard1.replica_n11,
service url = null, agent id = null] for registry
solr.core.readApiTestViolations.shard1.replica_n11 /
com.codahale.metrics.MetricRegistry@b2ad14f
[junit4] 2> 1105289 INFO (coreCloseExecutor-971-thread-1)
[n:127.0.0.1:59076_solr c:readApiTestViolations s:shard1 r:core_node8
x:readApiTestViolations_shard1_replica_n7] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=592287867
[junit4] 2> 1105290 INFO (coreCloseExecutor-971-thread-2)
[n:127.0.0.1:59076_solr c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1485196105
[junit4] 2> 1105290 INFO (coreCloseExecutor-971-thread-3)
[n:127.0.0.1:59076_solr c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n11] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1230924101
[junit4] 2> 1105597 INFO (jetty-closer-480-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6595d48e{/solr,null,UNAVAILABLE}
[junit4] 2> 1105625 INFO (jetty-closer-480-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98931686625443846-127.0.0.1:59076_solr-n_0000000000) closing
[junit4] 2> 1105625 INFO
(OverseerStateUpdate-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:59076_solr
[junit4] 2> 1105626 WARN
(OverseerAutoScalingTriggerThread-98931686625443846-127.0.0.1:59076_solr-n_0000000000)
[n:127.0.0.1:59076_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1106364 INFO (jetty-closer-480-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5c00d5b5{/solr,null,UNAVAILABLE}
[junit4] 2> 1106364 INFO (jetty-closer-480-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5d22bd95{/solr,null,UNAVAILABLE}
[junit4] 2> 1106414 ERROR
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1106414 INFO
(SUITE-AutoScalingHandlerTest-seed#[1C436BE69425E52E]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:57542 57542
[junit4] 2> 1106565 INFO (Thread-422) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:57542 57542
[junit4] 2> 1106566 WARN (Thread-422) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 200 /solr/aliases.json
[junit4] 2> 200 /solr/clusterprops.json
[junit4] 2> 4 /solr/configs/conf
[junit4] 2> 3 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 200 /solr/clusterstate.json
[junit4] 2> 24
/solr/collections/readApiTestViolations/state.json
[junit4] 2> 5 /solr/collections/COLL1/state.json
[junit4] 2> 2
/solr/overseer_elect/election/98931686625443846-127.0.0.1:59076_solr-n_0000000000
[junit4] 2> 2
/solr/collections/readApiTestViolations/leader_elect/shard1/election/98931686625443846-core_node8-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 200 /solr/live_nodes
[junit4] 2> 200 /solr/collections
[junit4] 2> 2 /solr/autoscaling/events/node_lost_trigger
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_1C436BE69425E52E-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70),
sim=RandomSimilarity(queryNorm=false): {}, locale=es-CR, timezone=Africa/Lusaka
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=4,threads=1,free=143056976,total=393740288
[junit4] 2> NOTE: All tests run in this JVM:
[TestLMDirichletSimilarityFactory, TestFuzzyAnalyzedSuggestions,
XmlUpdateRequestHandlerTest, TestConfigSetsAPI,
CoreMergeIndexesAdminHandlerTest, TestRetrieveFieldsOptimizer,
TestCloudNestedDocsSort, TestDelegationWithHadoopAuth, ScriptEngineTest,
TestPartialUpdateDeduplication, ChaosMonkeyNothingIsSafeTest,
TestFieldCacheSort, TestPushWriter, TestShardHandlerFactory,
CloneFieldUpdateProcessorFactoryTest, TestStressCloudBlindAtomicUpdates,
TestMacros, TestFunctionQuery, RegexBytesRefFilterTest,
TestShortCircuitedRequests, TestUseDocValuesAsStored, ConnectionReuseTest,
CollectionReloadTest, TestCoreContainer, AsyncCallRequestStatusResponseTest,
CollectionsAPIAsyncDistributedZkTest, TestFieldTypeCollectionResource,
TestSolrXml, ConjunctionSolrSpellCheckerTest, TestStandardQParsers,
AutoScalingHandlerTest]
[junit4] Completed [70/746 (1!)] on J2 in 66.35s, 7 tests, 1 failure <<<
FAILURES!
[...truncated 315 lines...]
[junit4] JVM J1: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J1-20171101_225113_0705016330672934723713.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # There is insufficient memory for the Java Runtime Environment to
continue.
[junit4] # Native memory allocation (mmap) failed to map 30408704 bytes for
committing reserved memory.
[junit4] # An error report file with more information is saved as:
[junit4] #
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/hs_err_pid29282.log
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J1: stderr was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J1-20171101_225113_07082876860693113745.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: INFO:
os::commit_memory(0x00000000f2480000, 30408704, 0) failed; error='Cannot
allocate memory' (errno=12)
[junit4] <<< JVM J1: EOF ----
[...truncated 1272 lines...]
ERROR: command execution failed.
ERROR: Step ‘Archive the artifacts’ failed: no workspace for
Lucene-Solr-Tests-7.x #210
ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for
Lucene-Solr-Tests-7.x #210
ERROR: lucene is offline; cannot locate JDK 1.8 (latest)
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
ERROR: lucene is offline; cannot locate JDK 1.8 (latest)
ERROR: lucene is offline; cannot locate JDK 1.8 (latest)
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
