You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/12/27 13:19:37 UTC
[JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build #
2226 - Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/2226/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
All tests passed
Build Log:
[...truncated 15748 lines...]
[junit4] Suite: org.apache.solr.cloud.MultiThreadedOCPTest
[junit4] 2> 2038665 INFO (SUITE-MultiThreadedOCPTest-seed#[886D3004CABE64E8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/init-core-data-001
[junit4] 2> 2038666 WARN (SUITE-MultiThreadedOCPTest-seed#[886D3004CABE64E8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=19 numCloses=19
[junit4] 2> 2038666 INFO (SUITE-MultiThreadedOCPTest-seed#[886D3004CABE64E8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2038667 INFO (SUITE-MultiThreadedOCPTest-seed#[886D3004CABE64E8]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2038668 INFO (SUITE-MultiThreadedOCPTest-seed#[886D3004CABE64E8]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 2038671 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2038672 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2038672 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2038773 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer start zk server on port:63122
[junit4] 2> 2038773 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63122
[junit4] 2> 2038773 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63122
[junit4] 2> 2038785 INFO (zkConnectionManagerCallback-15371-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2038788 INFO (zkConnectionManagerCallback-15373-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2038789 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2038790 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2038791 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2038792 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2038794 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2038795 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2038796 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2038797 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2038798 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2038799 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2038800 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2038803 INFO (zkConnectionManagerCallback-15377-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2038806 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 2038954 WARN (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2038955 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2038955 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2038955 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 2038956 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2038956 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2038956 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2038956 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@772385fc{/,null,AVAILABLE}
[junit4] 2> 2038956 WARN (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@28f82a0a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2038957 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@9e4ca7{SSL,[ssl, http/1.1]}{127.0.0.1:52642}
[junit4] 2> 2038957 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.s.Server Started @2039769ms
[junit4] 2> 2038957 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/tempDir-001/control/data, replicaType=NRT, hostContext=/, hostPort=52642, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/control-001/cores}
[junit4] 2> 2038957 ERROR (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2038957 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2038957 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 2038957 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2038957 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2038957 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-27T11:07:16.030Z
[junit4] 2> 2038958 INFO (zkConnectionManagerCallback-15379-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2038963 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2038963 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/control-001/solr.xml
[junit4] 2> 2038966 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2038966 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2038966 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2039662 WARN (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2039663 WARN (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6641b8e8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2039666 WARN (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2039667 WARN (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@440f8927[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2039668 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63122/solr
[junit4] 2> 2039669 INFO (zkConnectionManagerCallback-15385-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2039672 INFO (zkConnectionManagerCallback-15387-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2039790 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [n:127.0.0.1:52642_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52642_
[junit4] 2> 2039791 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [n:127.0.0.1:52642_ ] o.a.s.c.Overseer Overseer (id=72079948525600773-127.0.0.1:52642_-n_0000000000) starting
[junit4] 2> 2039799 INFO (zkConnectionManagerCallback-15394-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2039802 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [n:127.0.0.1:52642_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63122/solr ready
[junit4] 2> 2039804 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [n:127.0.0.1:52642_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52642_
[junit4] 2> 2039804 INFO (OverseerStateUpdate-72079948525600773-127.0.0.1:52642_-n_0000000000) [n:127.0.0.1:52642_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:52642_
[junit4] 2> 2039806 INFO (zkCallback-15393-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2039807 INFO (OverseerStateUpdate-72079948525600773-127.0.0.1:52642_-n_0000000000) [n:127.0.0.1:52642_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2039835 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [n:127.0.0.1:52642_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2039856 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [n:127.0.0.1:52642_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2039865 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [n:127.0.0.1:52642_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2039865 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [n:127.0.0.1:52642_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2039866 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [n:127.0.0.1:52642_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/control-001/cores
[junit4] 2> 2039886 INFO (zkConnectionManagerCallback-15400-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2039887 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2039889 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63122/solr ready
[junit4] 2> 2039910 INFO (qtp2118161881-39537) [n:127.0.0.1:52642_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:52642_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2039915 INFO (OverseerThreadFactory-10743-thread-1-processing-n:127.0.0.1:52642_) [n:127.0.0.1:52642_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 2040026 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2040027 INFO (qtp2118161881-39536) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2040028 INFO (qtp2118161881-39533) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2040029 INFO (qtp2118161881-39538) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2040030 INFO (qtp2118161881-39539) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2040035 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ x:control_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2040035 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2041055 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2041065 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 2041166 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2041181 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 2041181 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2041181 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 2041182 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=79.8310546875, floorSegmentMB=0.3056640625, forceMergeDeletesPctAllowed=15.450775643445215, segmentsPerTier=22.0, maxCFSSegmentSizeMB=0.5927734375, noCFSRatio=1.0, deletesPctAllowed=22.019363645852813
[junit4] 2> 2041184 WARN (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2041222 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2041222 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2041224 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2041224 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2041224 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1492561815, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2041225 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a5ffd55[control_collection_shard1_replica_n1] main]
[junit4] 2> 2041225 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2041226 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2041226 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2041226 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1621002906028212224
[junit4] 2> 2041229 INFO (searcherExecutor-10748-thread-1-processing-n:127.0.0.1:52642_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@6a5ffd55[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2041231 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2041231 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 2041234 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2041234 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2041234 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:52642/control_collection_shard1_replica_n1/
[junit4] 2> 2041234 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2041235 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:52642/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 2041235 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72079948525600773-core_node2-n_0000000000
[junit4] 2> 2041236 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:52642/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 2041339 INFO (zkCallback-15386-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2041339 INFO (zkCallback-15386-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2041340 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2041343 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1308
[junit4] 2> 2041346 INFO (qtp2118161881-39537) [n:127.0.0.1:52642_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2041443 INFO (zkCallback-15386-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2041443 INFO (zkCallback-15386-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2041443 INFO (zkCallback-15386-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2041444 INFO (qtp2118161881-39537) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:52642_&wt=javabin&version=2} status=0 QTime=1534
[junit4] 2> 2041449 INFO (zkConnectionManagerCallback-15405-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2041451 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2041452 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63122/solr ready
[junit4] 2> 2041452 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2041455 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2041459 INFO (OverseerThreadFactory-10743-thread-2-processing-n:127.0.0.1:52642_) [n:127.0.0.1:52642_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 2041460 INFO (OverseerCollectionConfigSetProcessor-72079948525600773-127.0.0.1:52642_-n_0000000000) [n:127.0.0.1:52642_ ] 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> 2041667 WARN (OverseerThreadFactory-10743-thread-2-processing-n:127.0.0.1:52642_) [n:127.0.0.1:52642_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 2041671 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2041672 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=216
[junit4] 2> 2041673 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=3
[junit4] 2> 2041816 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-1-001 of type NRT
[junit4] 2> 2041818 WARN (closeThreadPool-15406-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2041818 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2041818 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2041818 INFO (closeThreadPool-15406-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 2041819 INFO (closeThreadPool-15406-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2041819 INFO (closeThreadPool-15406-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2041819 INFO (closeThreadPool-15406-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2041819 INFO (closeThreadPool-15406-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f01c394{/,null,AVAILABLE}
[junit4] 2> 2041820 WARN (closeThreadPool-15406-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2fab9325[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2041820 INFO (closeThreadPool-15406-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@528bde4e{SSL,[ssl, http/1.1]}{127.0.0.1:50534}
[junit4] 2> 2041820 INFO (closeThreadPool-15406-thread-1) [ ] o.e.j.s.Server Started @2042634ms
[junit4] 2> 2041820 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/tempDir-001/jetty1, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=50534, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-1-001/cores}
[junit4] 2> 2041820 ERROR (closeThreadPool-15406-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2041820 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2041820 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 2041820 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2041820 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2041820 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-27T11:07:18.893Z
[junit4] 2> 2041822 INFO (zkConnectionManagerCallback-15408-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2041823 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2041823 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-1-001/solr.xml
[junit4] 2> 2041826 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2041826 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2041827 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2041973 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-2-001 of type NRT
[junit4] 2> 2041976 WARN (closeThreadPool-15406-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2041980 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2041980 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2041980 INFO (closeThreadPool-15406-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 2041981 INFO (closeThreadPool-15406-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2041981 INFO (closeThreadPool-15406-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2041981 INFO (closeThreadPool-15406-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2041982 INFO (closeThreadPool-15406-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28cfa707{/,null,AVAILABLE}
[junit4] 2> 2041982 WARN (closeThreadPool-15406-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4aeea89f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2041982 INFO (closeThreadPool-15406-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@64f3cdc1{SSL,[ssl, http/1.1]}{127.0.0.1:58039}
[junit4] 2> 2041982 INFO (closeThreadPool-15406-thread-2) [ ] o.e.j.s.Server Started @2042796ms
[junit4] 2> 2041982 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/tempDir-001/jetty2, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=58039, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-2-001/cores}
[junit4] 2> 2041982 ERROR (closeThreadPool-15406-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2041982 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2041982 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 2041982 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2041983 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2041983 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-27T11:07:19.056Z
[junit4] 2> 2041984 INFO (zkConnectionManagerCallback-15410-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2041985 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2041985 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-2-001/solr.xml
[junit4] 2> 2041988 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2041988 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2041989 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2042124 WARN (closeThreadPool-15406-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2042125 WARN (closeThreadPool-15406-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5a1e494c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2042127 WARN (closeThreadPool-15406-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2042128 WARN (closeThreadPool-15406-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@c89443d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2042132 INFO (TEST-MultiThreadedOCPTest.test-seed#[886D3004CABE64E8]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-3-001 of type NRT
[junit4] 2> 2042133 INFO (closeThreadPool-15406-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63122/solr
[junit4] 2> 2042133 WARN (closeThreadPool-15406-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2042134 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2042134 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2042134 INFO (closeThreadPool-15406-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 2042134 INFO (closeThreadPool-15406-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2042134 INFO (closeThreadPool-15406-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2042134 INFO (closeThreadPool-15406-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2042135 INFO (closeThreadPool-15406-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@53c75d7b{/,null,AVAILABLE}
[junit4] 2> 2042135 WARN (closeThreadPool-15406-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1339d977[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2042135 INFO (closeThreadPool-15406-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2cb081de{SSL,[ssl, http/1.1]}{127.0.0.1:56700}
[junit4] 2> 2042135 INFO (closeThreadPool-15406-thread-3) [ ] o.e.j.s.Server Started @2042949ms
[junit4] 2> 2042135 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/tempDir-001/jetty3, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=56700, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-3-001/cores}
[junit4] 2> 2042136 ERROR (closeThreadPool-15406-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2042136 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2042136 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 2042136 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2042136 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2042136 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-27T11:07:19.209Z
[junit4] 2> 2042138 INFO (zkConnectionManagerCallback-15418-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042139 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2042139 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-3-001/solr.xml
[junit4] 2> 2042142 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2042142 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2042142 INFO (zkConnectionManagerCallback-15416-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042143 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2042144 INFO (zkConnectionManagerCallback-15420-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042149 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2042153 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.c.ZkController Publish node=127.0.0.1:50534_ as DOWN
[junit4] 2> 2042154 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2042154 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50534_
[junit4] 2> 2042155 INFO (zkCallback-15386-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2042155 INFO (zkCallback-15404-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2042155 INFO (zkCallback-15393-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2042175 INFO (zkCallback-15419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2042176 INFO (zkConnectionManagerCallback-15427-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042178 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2042179 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63122/solr ready
[junit4] 2> 2042180 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2042203 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2042214 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2042214 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2042215 INFO (closeThreadPool-15406-thread-1) [n:127.0.0.1:50534_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-1-001/cores
[junit4] 2> 2042377 WARN (closeThreadPool-15406-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2042378 WARN (closeThreadPool-15406-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@e5bd44f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2042380 WARN (closeThreadPool-15406-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2042380 WARN (closeThreadPool-15406-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@564cbfe1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2042381 INFO (closeThreadPool-15406-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63122/solr
[junit4] 2> 2042383 INFO (zkConnectionManagerCallback-15434-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042385 INFO (zkConnectionManagerCallback-15436-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042390 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2042394 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.c.ZkController Publish node=127.0.0.1:56700_ as DOWN
[junit4] 2> 2042395 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2042395 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56700_
[junit4] 2> 2042396 INFO (zkCallback-15393-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2042396 INFO (zkCallback-15386-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2042396 INFO (zkCallback-15404-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2042397 INFO (zkCallback-15419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2042397 INFO (zkCallback-15426-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2042398 INFO (zkCallback-15435-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2042415 INFO (zkConnectionManagerCallback-15443-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042417 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2042418 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63122/solr ready
[junit4] 2> 2042419 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2042432 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2042438 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2042438 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2042439 INFO (closeThreadPool-15406-thread-3) [n:127.0.0.1:56700_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-3-001/cores
[junit4] 2> 2042764 WARN (closeThreadPool-15406-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2042765 WARN (closeThreadPool-15406-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@44e52a01[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2042767 WARN (closeThreadPool-15406-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2042768 WARN (closeThreadPool-15406-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1e9d49[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2042769 INFO (closeThreadPool-15406-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63122/solr
[junit4] 2> 2042771 INFO (zkConnectionManagerCallback-15450-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042774 INFO (zkConnectionManagerCallback-15452-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042780 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2042785 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.c.ZkController Publish node=127.0.0.1:58039_ as DOWN
[junit4] 2> 2042786 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2042786 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58039_
[junit4] 2> 2042787 INFO (zkCallback-15393-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2042787 INFO (zkCallback-15386-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2042787 INFO (zkCallback-15419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2042787 INFO (zkCallback-15404-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2042787 INFO (zkCallback-15435-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2042788 INFO (zkCallback-15426-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2042788 INFO (zkCallback-15442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2042789 INFO (zkCallback-15451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2042805 INFO (zkConnectionManagerCallback-15459-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2042807 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2042808 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63122/solr ready
[junit4] 2> 2042809 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2042822 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2042828 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2042828 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2042828 INFO (closeThreadPool-15406-thread-2) [n:127.0.0.1:58039_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-2-001/cores
[junit4] 2> 2042875 INFO (qtp223933464-39601) [n:127.0.0.1:50534_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:50534_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2042876 INFO (qtp223933464-39602) [n:127.0.0.1:50534_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:58039_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2042878 INFO (OverseerCollectionConfigSetProcessor-72079948525600773-127.0.0.1:52642_-n_0000000000) [n:127.0.0.1:52642_ ] 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> 2042881 INFO (qtp223933464-39599) [n:127.0.0.1:50534_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:56700_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2042883 INFO (qtp223933464-39598) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042884 INFO (qtp223933464-39600) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042884 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042885 INFO (qtp223933464-39598) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042886 INFO (qtp223933464-39600) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042887 INFO (qtp2118161881-39537) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2042888 INFO (qtp2118161881-39536) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2042888 INFO (qtp2118161881-39533) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2042889 INFO (qtp2118161881-39538) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042890 INFO (qtp2118161881-39539) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042891 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042892 INFO (qtp2118161881-39537) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042893 INFO (qtp2118161881-39536) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042906 INFO (qtp889781786-39640) [n:127.0.0.1:56700_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042907 INFO (qtp889781786-39643) [n:127.0.0.1:56700_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042908 INFO (qtp889781786-39633) [n:127.0.0.1:56700_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042909 INFO (qtp889781786-39635) [n:127.0.0.1:56700_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042910 INFO (qtp889781786-39637) [n:127.0.0.1:56700_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042927 INFO (qtp1503766577-39616) [n:127.0.0.1:58039_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042928 INFO (qtp1503766577-39613) [n:127.0.0.1:58039_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042929 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042930 INFO (qtp1503766577-39612) [n:127.0.0.1:58039_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042930 INFO (qtp1503766577-39614) [n:127.0.0.1:58039_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2042930 INFO (OverseerThreadFactory-10743-thread-4-processing-n:127.0.0.1:52642_) [n:127.0.0.1:52642_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:58039_ for creating new replica of shard shard1 for collection collection1
[junit4] 2> 2042930 INFO (OverseerThreadFactory-10743-thread-3-processing-n:127.0.0.1:52642_) [n:127.0.0.1:52642_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:50534_ for creating new replica of shard shard2 for collection collection1
[junit4] 2> 2042936 INFO (OverseerThreadFactory-10743-thread-4-processing-n:127.0.0.1:52642_) [n:127.0.0.1:52642_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2042938 INFO (OverseerThreadFactory-10743-thread-3-processing-n:127.0.0.1:52642_) [n:127.0.0.1:52642_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2042964 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ x:collection1_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2042968 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ x:collection1_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2043984 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2043985 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2043995 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 2043995 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 2044111 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2044114 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2044138 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 2044139 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2044139 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 2044139 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-1-001/cores/collection1_shard2_replica_n2], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-1-001/cores/collection1_shard2_replica_n2/data/]
[junit4] 2> 2044139 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47ba84f5
[junit4] 2> 2044139 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-2-001/cores/collection1_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_886D3004CABE64E8-001/shard-2-001/cores/collection1_shard1_replica_n1/data/]
[junit4] 2> 2044149 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=79.8310546875, floorSegmentMB=0.3056640625, forceMergeDeletesPctAllowed=15.450775643445215, segmentsPerTier=22.0, maxCFSSegmentSizeMB=0.5927734375, noCFSRatio=1.0, deletesPctAllowed=22.019363645852813
[junit4] 2> 2044149 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=79.8310546875, floorSegmentMB=0.3056640625, forceMergeDeletesPctAllowed=15.450775643445215, segmentsPerTier=22.0, maxCFSSegmentSizeMB=0.5927734375, noCFSRatio=1.0, deletesPctAllowed=22.019363645852813
[junit4] 2> 2044150 WARN (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2044150 WARN (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2044211 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2044211 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2044215 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2044215 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2044216 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1492561815, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2044216 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@10695a2e[collection1_shard1_replica_n1] main]
[junit4] 2> 2044218 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2044219 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2044220 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2044220 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1621002909167648768
[junit4] 2> 2044225 INFO (searcherExecutor-10781-thread-1-processing-n:127.0.0.1:58039_ x:collection1_shard1_replica_n1 c:collection1 s:shard1) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@10695a2e[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2044227 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2044227 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2044228 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 2044228 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 2044230 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2044230 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2044230 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1492561815, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2044231 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@53990193[collection1_shard2_replica_n2] main]
[junit4] 2> 2044233 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2044233 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2044233 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2044233 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:58039/collection1_shard1_replica_n1/
[junit4] 2> 2044233 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2044233 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:58039/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 2044233 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72079948525600787-core_node4-n_0000000000
[junit4] 2> 2044233 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2044234 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2044234 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1621002909182328832
[junit4] 2> 2044235 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:58039/collection1_shard1_replica_n1/ shard1
[junit4] 2> 2044237 INFO (searcherExecutor-10780-thread-1-processing-n:127.0.0.1:50534_ x:collection1_shard2_replica_n2 c:collection1 s:shard2) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@53990193[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2044246 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 2044247 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 2044251 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2044252 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2044252 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:50534/collection1_shard2_replica_n2/
[junit4] 2> 2044252 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2044252 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy https://127.0.0.1:50534/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 2044252 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72079948525600781-core_node3-n_0000000000
[junit4] 2> 2044254 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:50534/collection1_shard2_replica_n2/ shard2
[junit4] 2> 2044357 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2044358 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2044361 INFO (qtp1503766577-39617) [n:127.0.0.1:58039_ c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1393
[junit4] 2> 2044363 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ c:collection1 s:shard2 x:collection1_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1399
[junit4] 2> 2044365 INFO (qtp223933464-39602) [n:127.0.0.1:50534_ c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:58039_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=1489
[junit4] 2> 2044365 WARN (OverseerCollectionConfigSetProcessor-72079948525600773-127.0.0.1:52642_-n_0000000000) [n:127.0.0.1:52642_ ] o.a.s.c.LockTree lock_is_leaked at[collection1, shard2]
[junit4] 2> 2044366 INFO (OverseerThreadFactory-10743-thread-3-processing-n:127.0.0.1:52642_) [n:127.0.0.1:52642_ c:collection1 s:shard2 ] o.a.s.c.LockTree Unlocked multiple times : collection1/shard2
[junit4] 2> 2044367 INFO (OverseerCollectionConfigSetProcessor-72079948525600773-127.0.0.1:52642_-n_0000000000) [n:127.0.0.1:52642_ ] 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> 2044367 INFO (qtp223933464-39601) [n:127.0.0.1:50534_ c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:50534_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2} status=0 QTime=1492
[junit4] 2> 2044368 INFO (OverseerCollectionConfigSetProcessor-72079948525600773-127.0.0.1:52642_-n_0000000000) [n:127.0.0.1:52642_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2044369 INFO (qtp223933464-39598) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard2.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2044370 INFO (qtp223933464-39600) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard2.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2044370 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard2.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2044372 INFO (qtp223933464-39602) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2044373 INFO (qtp223933464-39601) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2044374 INFO (qtp223933464-39598) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2044375 INFO (qtp223933464-39600) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2044376 INFO (qtp223933464-39603) [n:127.0.0.1:50534_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2044377 INFO (qtp2118161881-39533) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2044377 INFO (qtp2118161881-39538) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2044378 INFO (qtp2118161881-39539) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2044379 INFO (qtp2118161881-39535) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 2044380 INFO (qtp2118161881-39537) [n:127.0.0.1:52642_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.tota
[...truncated too long message...]
.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "OverseerThreadFactory-10743-thread-5"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp2118161881-39535"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from
[junit4] the UncaughtExceptionHandler in thread "Scheduler-1533325557"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Scheduler-966289134"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp1503766577-39612"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp2118161881-39538"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp2118161881-39533"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp223933464-39602"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Scheduler-1139757460"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "MetricsHistoryHandler-10764-thread-1"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "SolrRrdBackendFactory-10763-thread-2"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "OverseerCollectionConfigSetProcessor-72079948525600773-127.0.0.1:52642_-n_0000000000"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Scheduler-931201595"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp2118161881-39537"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp2118161881-39539"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "HttpClient@7cff3eb8-scheduler"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp889781786-39633"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp1503766577-39613"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExcept
[junit4] ionHandler in thread "Scheduler-928086646"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp889781786-39640"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp1503766577-39616"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp1503766577-39615"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp1503766577-39617"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "SolrRrdBackendFactory-10777-thread-2"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "MetricsHistoryHandler-10771-thread-1"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp223933464-39603"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Scheduler-2049550826"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp889781786-39637"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp889781786-39636"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp1503766577-39614"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "MetricsHistoryHandler-10778-thread-1"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "HttpClient@647a656-scheduler"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "CloudSolrClient ThreadPool-15401-thread-1-processing-https:////127.0.0.1:50534//collection1_shard2_replica_n2//"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown fro
[junit4] m the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "HttpClient@24fbefeb-scheduler"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Thread-5217"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "SolrRrdBackendFactory-10770-thread-1"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "ScheduledTrigger-10741-thread-3"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp889781786-39643"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp889781786-39638"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp223933464-39601"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "qtp223933464-39599"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "updateExecutor-15412-thread-2"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "SolrRrdBackendFactory-10770-thread-2"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "h2sc-15413-thread-27"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "SolrRrdBackendFactory-10745-thread-1
[junit4] "
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "SolrRrdBackendFactory-10777-thread-1"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Scheduler-693033278"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "SolrRrdBackendFactory-10745-thread-2"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "SolrRrdBackendFactory-10763-thread-1"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the UncaughtExceptionHandler in thread "ScheduledTrigger-10741-thread-1"
[junit4] <<< JVM J1: EOF ----
[...truncated 9012 lines...]
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/build.xml:633: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/build.xml:585: Some of the tests produced a heap dump, but did not fail. Maybe a suppressed OutOfMemoryError? Dumps created:
* java_pid21215.hprof
Total time: 184 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build #
2227 - Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/2227/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream
Error Message:
expected:<5> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<5> but was:<3>
at __randomizedtesting.SeedInfo.seed([3C15698AAFEB775D:1CFF0B8A33AA9A11]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream(StreamDecoratorTest.java:3034)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream
Error Message:
expected:<5> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<5> but was:<3>
at __randomizedtesting.SeedInfo.seed([3C15698AAFEB775D:1CFF0B8A33AA9A11]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream(StreamDecoratorTest.java:3034)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 16553 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4] 2> 310449 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/init-core-data-001
[junit4] 2> 310449 WARN (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=95 numCloses=95
[junit4] 2> 310449 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 310451 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 310452 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/tempDir-001
[junit4] 2> 310452 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 310453 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 310453 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 310553 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:54074
[junit4] 2> 310553 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54074
[junit4] 2> 310553 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54074
[junit4] 2> 310559 INFO (zkConnectionManagerCallback-3019-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 310562 INFO (zkConnectionManagerCallback-3021-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 310564 INFO (zkConnectionManagerCallback-3023-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 310567 WARN (jetty-launcher-3024-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 310567 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 310567 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 310567 INFO (jetty-launcher-3024-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 310568 INFO (jetty-launcher-3024-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 310568 INFO (jetty-launcher-3024-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 310568 INFO (jetty-launcher-3024-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 310568 INFO (jetty-launcher-3024-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ffea0a4{/solr,null,AVAILABLE}
[junit4] 2> 310569 WARN (jetty-launcher-3024-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 310570 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 310570 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 310570 INFO (jetty-launcher-3024-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 310570 INFO (jetty-launcher-3024-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 310570 INFO (jetty-launcher-3024-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 310570 INFO (jetty-launcher-3024-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 310571 INFO (jetty-launcher-3024-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7d84d5f0{/solr,null,AVAILABLE}
[junit4] 2> 310571 WARN (jetty-launcher-3024-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 310571 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 310571 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 310571 INFO (jetty-launcher-3024-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 310572 WARN (jetty-launcher-3024-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16057e47{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:45377}
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-2) [ ] o.e.j.s.Server Started @310747ms
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45377}
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 310572 ERROR (jetty-launcher-3024-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 310572 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-27T23:07:44.469Z
[junit4] 2> 310575 INFO (jetty-launcher-3024-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 310575 INFO (jetty-launcher-3024-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 310575 INFO (jetty-launcher-3024-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 310575 INFO (jetty-launcher-3024-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@77479078{/solr,null,AVAILABLE}
[junit4] 2> 310576 INFO (jetty-launcher-3024-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 310576 INFO (jetty-launcher-3024-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 310576 INFO (jetty-launcher-3024-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 310576 INFO (jetty-launcher-3024-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ecd13d{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:47848}
[junit4] 2> 310576 INFO (jetty-launcher-3024-thread-4) [ ] o.e.j.s.Server Started @310751ms
[junit4] 2> 310576 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=47848}
[junit4] 2> 310577 ERROR (jetty-launcher-3024-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-27T23:07:44.474Z
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@587fdd3b{/solr,null,AVAILABLE}
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5865783c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:32805}
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-3) [ ] o.e.j.s.Server Started @310752ms
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=32805}
[junit4] 2> 310577 ERROR (jetty-launcher-3024-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 310577 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 310578 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 310578 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 310578 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 310578 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-27T23:07:44.475Z
[junit4] 2> 310581 INFO (jetty-launcher-3024-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6d3350a2{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:48845}
[junit4] 2> 310581 INFO (jetty-launcher-3024-thread-1) [ ] o.e.j.s.Server Started @310756ms
[junit4] 2> 310581 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=48845}
[junit4] 2> 310581 ERROR (jetty-launcher-3024-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 310581 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 310581 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 310581 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 310581 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 310581 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-27T23:07:44.478Z
[junit4] 2> 310583 INFO (zkConnectionManagerCallback-3026-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 310584 INFO (zkConnectionManagerCallback-3030-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 310584 INFO (zkConnectionManagerCallback-3028-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 310584 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 310584 INFO (zkConnectionManagerCallback-3032-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 310585 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 310585 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 310592 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 310593 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10016e8a51f0003, likely client has closed socket
[junit4] 2> 312206 WARN (jetty-launcher-3024-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1a70defa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312206 WARN (jetty-launcher-3024-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@16a0d1ff[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312210 WARN (jetty-launcher-3024-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2f5e83a1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312211 INFO (jetty-launcher-3024-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54074/solr
[junit4] 2> 312212 INFO (zkConnectionManagerCallback-3041-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312216 INFO (zkConnectionManagerCallback-3043-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312220 WARN (jetty-launcher-3024-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4bc9f451[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312220 INFO (jetty-launcher-3024-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54074/solr
[junit4] 2> 312226 INFO (zkConnectionManagerCallback-3046-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312229 INFO (zkConnectionManagerCallback-3048-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312242 WARN (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 312253 INFO (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:48845_solr
[junit4] 2> 312254 INFO (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.c.Overseer Overseer (id=72082782496423946-127.0.0.1:48845_solr-n_0000000000) starting
[junit4] 2> 312263 INFO (zkConnectionManagerCallback-3057-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312266 INFO (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54074/solr ready
[junit4] 2> 312267 INFO (OverseerStateUpdate-72082782496423946-127.0.0.1:48845_solr-n_0000000000) [n:127.0.0.1:48845_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:48845_solr
[junit4] 2> 312268 INFO (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48845_solr
[junit4] 2> 312269 INFO (OverseerStateUpdate-72082782496423946-127.0.0.1:48845_solr-n_0000000000) [n:127.0.0.1:48845_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 312272 INFO (zkCallback-3056-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 312296 WARN (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 312298 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 312302 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:32805_solr as DOWN
[junit4] 2> 312303 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 312303 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32805_solr
[junit4] 2> 312306 INFO (zkCallback-3056-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 312306 INFO (zkCallback-3042-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 312366 INFO (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 312384 INFO (zkCallback-3047-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 312411 INFO (zkConnectionManagerCallback-3063-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312411 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 312418 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54074/solr ready
[junit4] 2> 312419 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 312456 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32805.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 312466 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32805.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 312466 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32805.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 312467 INFO (jetty-launcher-3024-thread-3) [n:127.0.0.1:32805_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/tempDir-001/node4/.
[junit4] 2> 312473 INFO (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48845.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 312480 INFO (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48845.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 312481 INFO (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48845.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 312482 INFO (jetty-launcher-3024-thread-1) [n:127.0.0.1:48845_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/tempDir-001/node1/.
[junit4] 2> 312698 WARN (jetty-launcher-3024-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@99ec5ea[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312702 WARN (jetty-launcher-3024-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6585b04d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312703 INFO (jetty-launcher-3024-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54074/solr
[junit4] 2> 312705 INFO (zkConnectionManagerCallback-3070-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312805 INFO (zkConnectionManagerCallback-3072-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312811 WARN (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 312812 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 312819 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:45377_solr as DOWN
[junit4] 2> 312819 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 312819 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45377_solr
[junit4] 2> 312825 INFO (zkCallback-3056-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 312825 INFO (zkCallback-3042-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 312825 INFO (zkCallback-3062-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 312825 INFO (zkCallback-3047-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 312826 INFO (zkCallback-3071-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 312844 INFO (zkConnectionManagerCallback-3079-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312846 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 312847 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54074/solr ready
[junit4] 2> 312848 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 312860 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45377.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 312867 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45377.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 312867 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45377.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 312867 INFO (jetty-launcher-3024-thread-2) [n:127.0.0.1:45377_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/tempDir-001/node2/.
[junit4] 2> 313450 WARN (jetty-launcher-3024-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2a591d9b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 313453 WARN (jetty-launcher-3024-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@55a37875[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 313454 INFO (jetty-launcher-3024-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54074/solr
[junit4] 2> 313456 INFO (zkConnectionManagerCallback-3086-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 313459 INFO (zkConnectionManagerCallback-3088-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 313464 WARN (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 313465 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 313470 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:47848_solr as DOWN
[junit4] 2> 313471 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 313471 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47848_solr
[junit4] 2> 313472 INFO (zkCallback-3047-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 313472 INFO (zkCallback-3042-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 313473 INFO (zkCallback-3056-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 313473 INFO (zkCallback-3062-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 313473 INFO (zkCallback-3071-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 313473 INFO (zkCallback-3078-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 313474 INFO (zkCallback-3087-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 313492 INFO (zkConnectionManagerCallback-3095-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 313493 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 313494 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54074/solr ready
[junit4] 2> 313494 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 313507 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47848.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 313513 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47848.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 313513 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47848.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 313514 INFO (jetty-launcher-3024-thread-4) [n:127.0.0.1:47848_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/tempDir-001/node3/.
[junit4] 2> 313557 INFO (zkConnectionManagerCallback-3101-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 313559 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 313560 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54074/solr ready
[junit4] 2> 313571 INFO (qtp472960589-7355) [n:127.0.0.1:47848_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 313575 INFO (OverseerThreadFactory-1995-thread-1-processing-n:127.0.0.1:48845_solr) [n:127.0.0.1:48845_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1_collection
[junit4] 2> 313683 INFO (qtp472960589-7359) [n:127.0.0.1:47848_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313684 INFO (qtp472960589-7357) [n:127.0.0.1:47848_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313684 INFO (qtp472960589-7360) [n:127.0.0.1:47848_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313685 INFO (qtp472960589-7381) [n:127.0.0.1:47848_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313686 INFO (qtp472960589-7354) [n:127.0.0.1:47848_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313688 INFO (qtp1844329985-7377) [n:127.0.0.1:48845_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313688 INFO (qtp1844329985-7373) [n:127.0.0.1:48845_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313689 INFO (qtp1844329985-7378) [n:127.0.0.1:48845_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313690 INFO (qtp1844329985-7375) [n:127.0.0.1:48845_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313690 INFO (qtp1844329985-7379) [n:127.0.0.1:48845_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313693 INFO (qtp328196147-7366) [n:127.0.0.1:32805_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313694 INFO (qtp328196147-7370) [n:127.0.0.1:32805_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313694 INFO (qtp328196147-7364) [n:127.0.0.1:32805_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313695 INFO (qtp328196147-7369) [n:127.0.0.1:32805_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313696 INFO (qtp328196147-7382) [n:127.0.0.1:32805_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313698 INFO (qtp829956677-7352) [n:127.0.0.1:45377_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313699 INFO (qtp829956677-7347) [n:127.0.0.1:45377_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313704 INFO (qtp829956677-7345) [n:127.0.0.1:45377_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313705 INFO (qtp829956677-7349) [n:127.0.0.1:45377_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313706 INFO (qtp829956677-7363) [n:127.0.0.1:45377_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 313710 INFO (OverseerStateUpdate-72082782496423946-127.0.0.1:48845_solr-n_0000000000) [n:127.0.0.1:48845_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:45377/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 313713 INFO (OverseerStateUpdate-72082782496423946-127.0.0.1:48845_solr-n_0000000000) [n:127.0.0.1:48845_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_collection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:32805/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 313916 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr x:collection1_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 313916 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr x:collection1_collection_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 314931 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 314937 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 314945 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.s.IndexSchema [collection1_collection_shard2_replica_n2] Schema name=test
[junit4] 2> 314951 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema [collection1_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 315032 WARN (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 315033 WARN (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 315033 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 315033 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard2_replica_n2' using configuration from collection collection1_collection, trusted=true
[junit4] 2> 315034 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32805.solr.core.collection1_collection.shard2.replica_n2' (registry 'solr.core.collection1_collection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 315034 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrCore [[collection1_collection_shard2_replica_n2] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/tempDir-001/node4/collection1_collection_shard2_replica_n2], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/tempDir-001/node4/./collection1_collection_shard2_replica_n2/data/]
[junit4] 2> 315038 WARN (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 315038 WARN (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 315038 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 315038 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard1_replica_n1' using configuration from collection collection1_collection, trusted=true
[junit4] 2> 315038 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45377.solr.core.collection1_collection.shard1.replica_n1' (registry 'solr.core.collection1_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65c192f2
[junit4] 2> 315039 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore [[collection1_collection_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/tempDir-001/node2/collection1_collection_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_3C15698AAFEB775D-001/tempDir-001/node2/./collection1_collection_shard1_replica_n1/data/]
[junit4] 2> 315090 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 315090 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 315091 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 315092 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 315094 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 315094 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 315095 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 315095 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 315095 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@25e95741[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 315096 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@3116d85b[collection1_collection_shard2_replica_n2] main]
[junit4] 2> 315097 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 315098 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 315098 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 315099 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1621048236699549696
[junit4] 2> 315103 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 315103 INFO (searcherExecutor-2019-thread-1-processing-n:127.0.0.1:45377_solr x:collection1_collection_shard1_replica_n1 c:collection1_collection s:shard1 r:core_node3) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@25e95741[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 315103 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 315103 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 315104 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1621048236704792576
[junit4] 2> 315108 INFO (searcherExecutor-2018-thread-1-processing-n:127.0.0.1:32805_solr x:collection1_collection_shard2_replica_n2 c:collection1_collection s:shard2 r:core_node4) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new searcher Searcher@3116d85b[collection1_collection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 315111 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_collection/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 315111 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1_collection/leaders/shard1
[junit4] 2> 315114 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_collection/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 315114 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1_collection/leaders/shard2
[junit4] 2> 315118 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 315118 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 315118 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:45377/solr/collection1_collection_shard1_replica_n1/
[junit4] 2> 315119 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 315120 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 315120 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 315120 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:45377/solr/collection1_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 315120 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:32805/solr/collection1_collection_shard2_replica_n2/
[junit4] 2> 315120 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1_collection/leaders/shard1/leader after winning as /collections/collection1_collection/leader_elect/shard1/election/72082782496423950-core_node3-n_0000000000
[junit4] 2> 315121 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 315121 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:32805/solr/collection1_collection_shard2_replica_n2/ has no replicas
[junit4] 2> 315121 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1_collection/leaders/shard2/leader after winning as /collections/collection1_collection/leader_elect/shard2/election/72082782496423944-core_node4-n_0000000000
[junit4] 2> 315124 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:45377/solr/collection1_collection_shard1_replica_n1/ shard1
[junit4] 2> 315124 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:32805/solr/collection1_collection_shard2_replica_n2/ shard2
[junit4] 2> 315227 INFO (zkCallback-3042-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315227 INFO (zkCallback-3071-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315227 INFO (zkCallback-3042-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315227 INFO (zkCallback-3071-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315231 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 315232 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 315236 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1320
[junit4] 2> 315237 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1321
[junit4] 2> 315242 INFO (qtp472960589-7355) [n:127.0.0.1:47848_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 315340 INFO (zkCallback-3071-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315340 INFO (zkCallback-3042-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315344 INFO (zkCallback-3071-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315344 INFO (zkCallback-3042-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315344 INFO (zkCallback-3087-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315344 INFO (zkCallback-3071-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315344 INFO (zkCallback-3042-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 315346 INFO (qtp472960589-7355) [n:127.0.0.1:47848_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1775
[junit4] 2> 315347 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1_collection failOnTimeout:true timeout (sec):45
[junit4] 2> 315348 INFO (SUITE-StreamDecoratorTest-seed#[3C15698AAFEB775D]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1_collection
[junit4] 2> 315350 INFO (qtp472960589-7358) [n:127.0.0.1:47848_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 315357 INFO (OverseerCollectionConfigSetProcessor-72082782496423946-127.0.0.1:48845_solr-n_0000000000) [n:127.0.0.1:48845_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> 315464 INFO (qtp472960589-7358) [n:127.0.0.1:47848_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2} status=0 QTime=114
[junit4] 2> 315525 INFO (TEST-StreamDecoratorTest.testInnerJoinStream-seed#[3C15698AAFEB775D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInnerJoinStream
[junit4] 2> 315548 INFO (qtp829956677-7352) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_collection/terms/shard1 to Terms{values={core_node3=1}, version=1}
[junit4] 2> 315548 INFO (qtp829956677-7352) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:32805/solr/collection1_collection_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1621048237160923136)} 0 9
[junit4] 2> 315551 INFO (qtp829956677-7347) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1621048237173506048,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 315551 INFO (qtp829956677-7347) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 315552 INFO (qtp829956677-7347) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 315552 INFO (qtp829956677-7347) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:32805/solr/collection1_collection_shard2_replica_n2/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 315553 INFO (qtp328196147-7370) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1621048237175603200,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 315553 INFO (qtp328196147-7370) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 315553 INFO (qtp328196147-7370) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 315554 INFO (qtp328196147-7370) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_collection/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 315554 INFO (qtp328196147-7370) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1621048237148340224),commit=} 0 27
[junit4] 2> 315558 INFO (qtp328196147-7364) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[2 (1621048237178748928), 3 (1621048237179797504), 5 (1621048237179797505), 6 (1621048237179797506), 7 (1621048237179797507), 9 (1621048237179797508)]} 0 1
[junit4] 2> 315558 INFO (qtp829956677-7349) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[1 (1621048237178748928), 15 (1621048237179797504), 4 (1621048237179797505), 8 (1621048237179797506), 10 (1621048237180846080), 11 (1621048237180846081), 12 (1621048237180846082), 13 (1621048237180846083), 14 (1621048237180846084)]} 0 1
[junit4] 2> 315560 INFO (qtp829956677-7363) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1621048237182943232,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 315561 INFO (qtp829956677-7363) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7f10d60d commitCommandVersion:1621048237182943232
[junit4] 2> 315588 INFO (qtp829956677-7363) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bc2d1e3[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 315588 INFO (qtp829956677-7363) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 315589 INFO (searcherExecutor-2019-thread-1-processing-n:127.0.0.1:45377_solr x:collection1_collection_shard1_replica_n1 c:collection1_collection s:shard1 r:core_node3) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@bc2d1e3[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(8.0.0):c9:[diagnostics={os=SunOS, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=5.11, timestamp=1545952069473}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]))}
[junit4] 2> 315589 INFO (qtp829956677-7363) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:32805/solr/collection1_collection_shard2_replica_n2/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 30
[junit4] 2> 315590 INFO (qtp328196147-7369) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1621048237214400512,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 315591 INFO (qtp328196147-7369) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22c41972 commitCommandVersion:1621048237214400512
[junit4] 2> 315603 INFO (qtp328196147-7369) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@150fe643[collection1_collection_shard2_replica_n2] main]
[junit4] 2> 315603 INFO (qtp328196147-7369) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 315604 INFO (searcherExecutor-2018-thread-1-processing-n:127.0.0.1:32805_solr x:collection1_collection_shard2_replica_n2 c:collection1_collection s:shard2 r:core_node4) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new searcher Searcher@150fe643[collection1_collection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(8.0.0):c6:[diagnostics={os=SunOS, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=5.11, timestamp=1545952069492}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]))}
[junit4] 2> 315604 INFO (qtp328196147-7369) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{commit=} 0 45
[junit4] 2> 315608 INFO (zkConnectionManagerCallback-3106-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 315610 INFO (TEST-StreamDecoratorTest.testInnerJoinStream-seed#[3C15698AAFEB775D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 315611 INFO (TEST-StreamDecoratorTest.testInnerJoinStream-seed#[3C15698AAFEB775D]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54074/solr ready
[junit4] 2> 315621 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=side_s:left&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=join1_i+asc,+join2_s+asc,+id+asc&wt=json&version=2.2} hits=5 status=0 QTime=5
[junit4] 2> 315621 INFO (qtp829956677-7371) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=side_s:left&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=join1_i+asc,+join2_s+asc,+id+asc&wt=json&version=2.2} hits=3 status=0 QTime=4
[junit4] 2> 315624 INFO (qtp328196147-7370) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=side_s:right&distrib=false&fl=join1_i,join2_s,ident_s&sort=join1_i+asc,+join2_s+asc&wt=json&version=2.2} hits=1 status=0 QTime=1
[junit4] 2> 315624 INFO (qtp829956677-7352) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=side_s:right&distrib=false&fl=join1_i,join2_s,ident_s&sort=join1_i+asc,+join2_s+asc&wt=json&version=2.2} hits=6 status=0 QTime=1
[junit4] 2> 315629 INFO (qtp328196147-7370) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=side_s:left&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=join1_i+desc,+join2_s+asc&wt=json&version=2.2} hits=5 status=0 QTime=0
[junit4] 2> 315632 INFO (qtp829956677-7371) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=side_s:left&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=join1_i+desc,+join2_s+asc&wt=json&version=2.2} hits=3 status=0 QTime=0
[junit4] 2> 315635 INFO (qtp328196147-7369) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=side_s:right&distrib=false&fl=join1_i,join2_s,ident_s&sort=join1_i+desc,+join2_s+asc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 315635 INFO (qtp829956677-7352) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=side_s:right&distrib=false&fl=join1_i,join2_s,ident_s&sort=join1_i+desc,+join2_s+asc&wt=json&version=2.2} hits=6 status=0 QTime=0
[junit4] 2> 315645 INFO (qtp328196147-7364) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=side_s:left&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=ident_s+asc&wt=json&version=2.2} hits=5 status=0 QTime=0
[junit4] 2> 315646 INFO (qtp829956677-7371) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=side_s:left&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=ident_s+asc&wt=json&version=2.2} hits=3 status=0 QTime=0
[junit4] 2> 315649 INFO (qtp328196147-7367) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=side_s:right&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=ident_s+asc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 315649 INFO (qtp829956677-7352) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=side_s:right&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=ident_s+asc&wt=json&version=2.2} hits=6 status=0 QTime=0
[junit4] 2> 315653 INFO (qtp328196147-7382) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=side_s:left&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=join1_i+asc,+join2_s+asc,+id+asc&wt=json&version=2.2} hits=5 status=0 QTime=0
[junit4] 2> 315656 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=side_s:left&distrib=false&fl=id,join1_i,join2_s,ident_s&sort=join1_i+asc,+join2_s+asc,+id+asc&wt=json&version=2.2} hits=3 status=0 QTime=0
[junit4] 2> 315658 INFO (qtp328196147-7369) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=side_s:right&distrib=false&fl=join3_i,join2_s,ident_s&sort=join3_i+asc,+join2_s+asc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 315666 INFO (qtp829956677-7352) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=side_s:right&distrib=false&fl=join3_i,join2_s,ident_s&sort=join3_i+asc,+join2_s+asc&wt=json&version=2.2} hits=6 status=0 QTime=0
[junit4] 2> 315669 INFO (TEST-StreamDecoratorTest.testInnerJoinStream-seed#[3C15698AAFEB775D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testInnerJoinStream
[junit4] 2> 315748 INFO (TEST-StreamDecoratorTest.testLetStream-seed#[3C15698AAFEB775D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLetStream
[junit4] 2> 315750 INFO (qtp328196147-7366) [n:127.0.0.1:32805_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@221d686e[collection1_collection_shard2_replica_n2] realtime]
[junit4] 2> 315751 INFO (qtp829956677-7371) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@527a37e0[collection1_collection_shard1_replica_n1] realtime]
[junit4] 2> 315751 INFO (qtp829956677-7371) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:32805/solr/collection1_collection_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1621048237381124096)} 0 2
[junit4] 2> 315753 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1621048237385318400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 315753 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7f10d60d commitCommandVersion:1621048237385318400
[junit4] 2> 315753 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d731fbd[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 315754 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 315755 INFO (searcherExecutor-2019-thread-1-processing-n:127.0.0.1:45377_solr x:collection1_collection_shard1_replica_n1 c:collection1_collection s:shard1 r:core_node3) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@7d731fbd[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 315755 INFO (qtp829956677-7351) [n:127.0.0.1:45377_solr c:collection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEA
[...truncated too long message...]
n #1: got: <false>, expected: is <true>
[junit4] OK 0.15s J1 | StreamDecoratorTest.testRankStream
[junit4] OK 0.19s J1 | StreamDecoratorTest.testStream
[junit4] OK 4.37s J1 | StreamDecoratorTest.testParallelDaemonCommitStream
[junit4] IGNOR/A 0.08s J1 | StreamDecoratorTest.testClassifyStream
[junit4] > Assumption #1: got: <false>, expected: is <true>
[junit4] OK 0.12s J1 | StreamDecoratorTest.testGetStreamForEOFTuple
[junit4] OK 0.14s J1 | StreamDecoratorTest.testRollupStream
[junit4] OK 0.22s J1 | StreamDecoratorTest.testHashJoinStream
[junit4] OK 11.1s J1 | StreamDecoratorTest.testParallelExecutorStream
[junit4] OK 0.20s J1 | StreamDecoratorTest.testSortStream
[junit4] IGNOR/A 0.18s J1 | StreamDecoratorTest.testParallelPriorityStream
[junit4] > Assumption #1: got: <false>, expected: is <true>
[junit4] OK 0.17s J1 | StreamDecoratorTest.testOuterHashJoinStreamWithKnownConflict
[junit4] OK 2.95s J1 | StreamDecoratorTest.testUpdateStream
[junit4] OK 0.19s J1 | StreamDecoratorTest.testHavingStream
[junit4] OK 0.22s J1 | StreamDecoratorTest.testOuterHashJoinStream
[junit4] OK 0.25s J1 | StreamDecoratorTest.testParallelMergeStream
[junit4] OK 0.16s J1 | StreamDecoratorTest.testUniqueStream
[junit4] IGNOR/A 0.14s J1 | StreamDecoratorTest.testPriorityStream
[junit4] > Assumption #1: got: <false>, expected: is <true>
[junit4] OK 2.68s J1 | StreamDecoratorTest.testParallelUpdateStream
[junit4] OK 14.6s J1 | StreamDecoratorTest.testExecutorStream
[junit4] OK 0.22s J1 | StreamDecoratorTest.testParallelRollupStream
[junit4] OK 2.97s J1 | StreamDecoratorTest.testCommitStream
[junit4] OK 0.25s J1 | StreamDecoratorTest.testNullStream
[junit4] OK 0.23s J1 | StreamDecoratorTest.testParallelComplementStream
[junit4] OK 0.25s J1 | StreamDecoratorTest.testHashJoinStreamWithKnownConflict
[junit4] IGNOR/A 0.20s J1 | StreamDecoratorTest.testParallelTerminatingDaemonUpdateStream
[junit4] > Assumption #1: got: <false>, expected: is <true>
[junit4] OK 0.26s J1 | StreamDecoratorTest.testCartesianProductStream
[junit4] OK 0.26s J1 | StreamDecoratorTest.testSelectStream
[junit4] OK 0.33s J1 | StreamDecoratorTest.testMergeStream
[junit4] OK 0.28s J1 | StreamDecoratorTest.testParallelIntersectStream
[junit4] OK 11.4s J1 | StreamDecoratorTest.testDaemonStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testIntersectStream
[junit4] OK 0.33s J1 | StreamDecoratorTest.testFetchStream
[junit4] OK 0.22s J1 | StreamDecoratorTest.testParallelUniqueStream
[junit4] OK 0.33s J1 | StreamDecoratorTest.testParallelHavingStream
[junit4] OK 4.64s J1 | StreamDecoratorTest.testParallelDaemonUpdateStream
[junit4] OK 0.32s J1 | StreamDecoratorTest.testParallelFetchStream
[junit4] OK 0.23s J1 | StreamDecoratorTest.testParallelRankStream
[junit4] OK 0.19s J1 | StreamDecoratorTest.testLeftOuterJoinStream
[junit4] OK 2.48s J1 | StreamDecoratorTest.testParallelCommitStream
[junit4] OK 0.13s J1 | StreamDecoratorTest.testComplementStream
[junit4] OK 0.31s J1 | StreamDecoratorTest.testParallelReducerStream
[junit4] OK 0.17s J1 | StreamDecoratorTest.testParallelNullStream
[junit4] OK 0.16s J1 | StreamDecoratorTest.testParallelShuffleStream
[junit4] Completed [4/5 (1!)] on J1 in 72.51s, 47 tests, 5 skipped
[junit4]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4] OK 0.18s J1 | StreamDecoratorTest.testInnerJoinStream
[junit4] OK 0.10s J1 | StreamDecoratorTest.testLetStream
[junit4] OK 0.09s J1 | StreamDecoratorTest.testReducerStream
[junit4] IGNOR/A 0.09s J1 | StreamDecoratorTest.testTerminatingDaemonStream
[junit4] > Assumption #1: got: <false>, expected: is <true>
[junit4] OK 0.12s J1 | StreamDecoratorTest.testRankStream
[junit4] OK 0.12s J1 | StreamDecoratorTest.testStream
[junit4] OK 4.31s J1 | StreamDecoratorTest.testParallelDaemonCommitStream
[junit4] IGNOR/A 0.07s J1 | StreamDecoratorTest.testClassifyStream
[junit4] > Assumption #1: got: <false>, expected: is <true>
[junit4] OK 0.09s J1 | StreamDecoratorTest.testGetStreamForEOFTuple
[junit4] OK 0.09s J1 | StreamDecoratorTest.testRollupStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testHashJoinStream
[junit4] OK 10.2s J1 | StreamDecoratorTest.testParallelExecutorStream
[junit4] OK 0.11s J1 | StreamDecoratorTest.testSortStream
[junit4] IGNOR/A 0.11s J1 | StreamDecoratorTest.testParallelPriorityStream
[junit4] > Assumption #1: got: <false>, expected: is <true>
[junit4] OK 0.12s J1 | StreamDecoratorTest.testOuterHashJoinStreamWithKnownConflict
[junit4] OK 2.42s J1 | StreamDecoratorTest.testUpdateStream
[junit4] OK 0.22s J1 | StreamDecoratorTest.testHavingStream
[junit4] OK 0.23s J1 | StreamDecoratorTest.testOuterHashJoinStream
[junit4] OK 0.24s J1 | StreamDecoratorTest.testParallelMergeStream
[junit4] OK 0.18s J1 | StreamDecoratorTest.testUniqueStream
[junit4] IGNOR/A 0.15s J1 | StreamDecoratorTest.testPriorityStream
[junit4] > Assumption #1: got: <false>, expected: is <true>
[junit4] OK 2.49s J1 | StreamDecoratorTest.testParallelUpdateStream
[junit4] OK 11.8s J1 | StreamDecoratorTest.testExecutorStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testParallelRollupStream
[junit4] OK 2.37s J1 | StreamDecoratorTest.testCommitStream
[junit4] OK 0.12s J1 | StreamDecoratorTest.testNullStream
[junit4] OK 0.15s J1 | StreamDecoratorTest.testParallelComplementStream
[junit4] OK 0.15s J1 | StreamDecoratorTest.testHashJoinStreamWithKnownConflict
[junit4] IGNOR/A 0.09s J1 | StreamDecoratorTest.testParallelTerminatingDaemonUpdateStream
[junit4] > Assumption #1: got: <false>, expected: is <true>
[junit4] OK 0.14s J1 | StreamDecoratorTest.testCartesianProductStream
[junit4] OK 0.18s J1 | StreamDecoratorTest.testSelectStream
[junit4] OK 0.28s J1 | StreamDecoratorTest.testMergeStream
[junit4] OK 0.24s J1 | StreamDecoratorTest.testParallelIntersectStream
[junit4] OK 11.3s J1 | StreamDecoratorTest.testDaemonStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testIntersectStream
[junit4] OK 0.19s J1 | StreamDecoratorTest.testFetchStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testParallelUniqueStream
[junit4] OK 0.19s J1 | StreamDecoratorTest.testParallelHavingStream
[junit4] OK 4.38s J1 | StreamDecoratorTest.testParallelDaemonUpdateStream
[junit4] OK 0.22s J1 | StreamDecoratorTest.testParallelFetchStream
[junit4] OK 0.17s J1 | StreamDecoratorTest.testParallelRankStream
[junit4] OK 0.18s J1 | StreamDecoratorTest.testLeftOuterJoinStream
[junit4] OK 2.40s J1 | StreamDecoratorTest.testParallelCommitStream
[junit4] OK 0.13s J1 | StreamDecoratorTest.testComplementStream
[junit4] OK 0.20s J1 | StreamDecoratorTest.testParallelReducerStream
[junit4] OK 0.16s J1 | StreamDecoratorTest.testParallelNullStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testParallelShuffleStream
[junit4] Completed [5/5 (1!)] on J1 in 62.35s, 47 tests, 5 skipped
[junit4]
[junit4]
[junit4] Tests with failures [seed: 3C15698AAFEB775D]:
[junit4] - org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream
[junit4]
[junit4]
[junit4] JVM J0: 1.00 .. 162.75 = 161.76s
[junit4] JVM J1: 1.01 .. 224.87 = 223.85s
[junit4] Execution time total: 3 minutes 44 seconds
[junit4] Tests summary: 5 suites, 235 tests, 1 failure, 25 ignored (25 assumptions)
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/common-build.xml:1572: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/common-build.xml:1099: There were test failures: 5 suites, 235 tests, 1 failure, 25 ignored (25 assumptions) [seed: 3C15698AAFEB775D]
Total time: 3 minutes 47 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 1/5 failed: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=d018cd18f4470982ffae6e7ff6c7de3ad868bec3, workspace=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris
[WARNINGS] Computing warning deltas based on reference build #2225
Recording test results
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2