Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1638/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RollingRestartTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.cloud.RollingRestartTest: 1) Thread[id=978, name=qtp1660790768-978, state=TIMED_WAITING, group=TGRP-RollingRestartTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.RollingRestartTest: 1) Thread[id=978, name=qtp1660790768-978, state=TIMED_WAITING, group=TGRP-RollingRestartTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([4DD3A648D35DC9B7]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RollingRestartTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=978, name=qtp1660790768-978, state=TIMED_WAITING, group=TGRP-RollingRestartTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=978, name=qtp1660790768-978, state=TIMED_WAITING, group=TGRP-RollingRestartTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([4DD3A648D35DC9B7]:0) Build Log: [...truncated 11725 lines...] [junit4] Suite: org.apache.solr.cloud.RollingRestartTest [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_4DD3A648D35DC9B7-001/init-core-data-001 [junit4] 2> 158342 WARN (SUITE-RollingRestartTest-seed#[4DD3A648D35DC9B7]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10 [junit4] 2> 158346 INFO (SUITE-RollingRestartTest-seed#[4DD3A648D35DC9B7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 158348 INFO (SUITE-RollingRestartTest-seed#[4DD3A648D35DC9B7]-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> 158350 INFO (SUITE-RollingRestartTest-seed#[4DD3A648D35DC9B7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 158350 INFO (SUITE-RollingRestartTest-seed#[4DD3A648D35DC9B7]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /igs/uc [junit4] 2> 158356 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 158357 INFO (Thread-122) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 158357 INFO (Thread-122) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 158361 ERROR (Thread-122) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 158456 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.ZkTestServer start zk server on port:58082 [junit4] 2> 158462 INFO (zkConnectionManagerCallback-134-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 158468 INFO (zkConnectionManagerCallback-136-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 158481 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158484 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158486 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158488 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158490 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158492 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158494 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158495 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158500 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158505 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158510 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractZkTestCase 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> 158517 INFO (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 158525 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise [junit4] 2> 158710 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T11:27:37-10:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 158710 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 158710 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 158710 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 158710 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4de42639{/igs/uc,null,AVAILABLE} [junit4] 2> 158714 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@39d65562{SSL,[ssl, http/1.1]}{127.0.0.1:42492} [junit4] 2> 158714 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.Server Started @161050ms [junit4] 2> 158714 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] 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.RollingRestartTest_4DD3A648D35DC9B7-001/tempDir-001/control/data, replicaType=NRT, hostContext=/igs/uc, hostPort=42492, 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.RollingRestartTest_4DD3A648D35DC9B7-001/control-001/cores} [junit4] 2> 158714 ERROR (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 158714 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 158714 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 158714 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 158714 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-21T16:20:00.797Z [junit4] 2> 158724 INFO (zkConnectionManagerCallback-141-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 158728 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 158728 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] 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.RollingRestartTest_4DD3A648D35DC9B7-001/control-001/solr.xml [junit4] 2> 158731 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 158731 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 158732 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 158733 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58082/solr [junit4] 2> 158739 INFO (zkConnectionManagerCallback-145-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 158753 INFO (zkConnectionManagerCallback-147-thread-1-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 158904 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 158905 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42492_igs%2Fuc [junit4] 2> 158906 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.Overseer Overseer (id=99388725686697989-127.0.0.1:42492_igs%2Fuc-n_0000000000) starting [junit4] 2> 158934 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42492_igs%2Fuc [junit4] 2> 158937 INFO (OverseerStateUpdate-99388725686697989-127.0.0.1:42492_igs%2Fuc-n_0000000000) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 158996 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 159024 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 159024 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 159025 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:42492_igs%2Fuc ] 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.RollingRestartTest_4DD3A648D35DC9B7-001/control-001/cores [junit4] 2> 159043 INFO (zkConnectionManagerCallback-153-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 159045 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 159046 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58082/solr ready [junit4] 2> 159118 INFO (qtp1380590914-793) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42492_igs%252Fuc&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 159121 INFO (OverseerThreadFactory-406-thread-1-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection [junit4] 2> 159266 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc ] 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> 159273 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 159388 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] 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> 160300 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 160308 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160360 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160378 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160378 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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@75a0629 [junit4] 2> 160383 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 160383 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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.RollingRestartTest_4DD3A648D35DC9B7-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.RollingRestartTest_4DD3A648D35DC9B7-001/control-001/cores/control_collection_shard1_replica_n1/data/] [junit4] 2> 160386 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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=28, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=13.9189453125, floorSegmentMB=0.3515625, forceMergeDeletesPctAllowed=15.885781921769365, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6980662586473276 [junit4] 2> 160390 WARN (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160427 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160427 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160428 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 160428 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 160429 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5389223065436952] [junit4] 2> 160429 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c80c8da[control_collection_shard1_replica_n1] main] [junit4] 2> 160431 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 160431 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160432 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 160432 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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 1590219613158768640 [junit4] 2> 160432 INFO (searcherExecutor-409-thread-1-processing-n:127.0.0.1:42492_igs%2Fuc x:control_collection_shard1_replica_n1 s:shard1 c:control_collection) [n:127.0.0.1:42492_igs%2Fuc 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@3c80c8da[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 160437 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 160437 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160437 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:42492/igs/uc/control_collection_shard1_replica_n1/ [junit4] 2> 160437 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160437 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:42492/igs/uc/control_collection_shard1_replica_n1/ has no replicas [junit4] 2> 160437 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 160441 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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:42492/igs/uc/control_collection_shard1_replica_n1/ shard1 [junit4] 2> 160544 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] 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> 160593 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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> 160596 INFO (qtp1380590914-791) [n:127.0.0.1:42492_igs%2Fuc 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=1357 [junit4] 2> 160601 INFO (qtp1380590914-793) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 160697 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] 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> 161124 INFO (OverseerCollectionConfigSetProcessor-99388725686697989-127.0.0.1:42492_igs%2Fuc-n_0000000000) [n:127.0.0.1:42492_igs%2Fuc ] 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> 161603 INFO (qtp1380590914-793) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42492_igs%252Fuc&wt=javabin&version=2} status=0 QTime=2485 [junit4] 2> 161615 INFO (zkConnectionManagerCallback-157-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 161619 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 161621 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58082/solr ready [junit4] 2> 161624 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 161634 INFO (qtp1380590914-786) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 161640 INFO (OverseerThreadFactory-406-thread-2-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1 [junit4] 2> 161643 WARN (OverseerThreadFactory-406-thread-2-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores. [junit4] 2> 161853 INFO (qtp1380590914-786) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 161854 INFO (qtp1380590914-786) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=219 [junit4] 2> 161981 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] 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.RollingRestartTest_4DD3A648D35DC9B7-001/shard-1-001 of type NRT [junit4] 2> 161984 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T11:27:37-10:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 161985 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 161985 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 161985 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 161985 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28a30236{/igs/uc,null,AVAILABLE} [junit4] 2> 161986 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@422a5d5b{SSL,[ssl, http/1.1]}{127.0.0.1:65525} [junit4] 2> 161986 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.Server Started @164323ms [junit4] 2> 161986 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] 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.RollingRestartTest_4DD3A648D35DC9B7-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/igs/uc, hostPort=65525, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_4DD3A648D35DC9B7-001/shard-1-001/cores} [junit4] 2> 161986 ERROR (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 161986 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 161986 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 161986 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 161986 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-21T16:20:04.069Z [junit4] 2> 161991 INFO (zkConnectionManagerCallback-159-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 161993 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 161993 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] 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.RollingRestartTest_4DD3A648D35DC9B7-001/shard-1-001/solr.xml [junit4] 2> 161997 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 161997 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 161997 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 161999 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58082/solr [junit4] 2> 162003 INFO (zkConnectionManagerCallback-163-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 162010 INFO (zkConnectionManagerCallback-165-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 162017 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 162020 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 162023 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65525_igs%2Fuc [junit4] 2> 162024 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 162033 INFO (zkCallback-156-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 162033 INFO (zkCallback-164-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 162110 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 162117 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 162117 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 162118 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_4DD3A648D35DC9B7-001/shard-1-001/cores [junit4] 2> 162139 INFO (qtp1380590914-793) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:65525_igs%252Fuc&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 162142 INFO (OverseerCollectionConfigSetProcessor-99388725686697989-127.0.0.1:42492_igs%2Fuc-n_0000000000) [n:127.0.0.1:42492_igs%2Fuc ] 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> 162144 INFO (OverseerThreadFactory-406-thread-3-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:65525_igs%2Fuc for creating new replica [junit4] 2> 162167 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 162168 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 162272 INFO (zkCallback-164-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 163183 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 163191 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema [collection1_shard2_replica_n41] Schema name=test [junit4] 2> 163259 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 163279 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n41' using configuration from collection collection1, trusted=true [junit4] 2> 163279 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n41' (registry 'solr.core.collection1.shard2.replica_n41') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 163279 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 163279 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore [[collection1_shard2_replica_n41] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_4DD3A648D35DC9B7-001/shard-1-001/cores/collection1_shard2_replica_n41], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_4DD3A648D35DC9B7-001/shard-1-001/cores/collection1_shard2_replica_n41/data/] [junit4] 2> 163281 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=13.9189453125, floorSegmentMB=0.3515625, forceMergeDeletesPctAllowed=15.885781921769365, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6980662586473276 [junit4] 2> 163285 WARN (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] 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> 163344 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 163344 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 163345 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 163345 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 163346 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5389223065436952] [junit4] 2> 163346 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.SolrIndexSearcher Opening [Searcher@4cae8af0[collection1_shard2_replica_n41] main] [junit4] 2> 163350 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 163351 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 163351 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 163352 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1590219616220610560 [junit4] 2> 163352 INFO (searcherExecutor-420-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc x:collection1_shard2_replica_n41 s:shard2 c:collection1) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore [collection1_shard2_replica_n41] Registered new searcher Searcher@4cae8af0[collection1_shard2_replica_n41] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 163357 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 163357 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 163357 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:65525/igs/uc/collection1_shard2_replica_n41/ [junit4] 2> 163357 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 163357 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy https://127.0.0.1:65525/igs/uc/collection1_shard2_replica_n41/ has no replicas [junit4] 2> 163357 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 163361 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:65525/igs/uc/collection1_shard2_replica_n41/ shard2 [junit4] 2> 163502 INFO (zkCallback-164-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 163503 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 163519 INFO (qtp2026262601-837) [n:127.0.0.1:65525_igs%2Fuc c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1351 [junit4] 2> 163525 INFO (qtp1380590914-793) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:65525_igs%252Fuc&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2} status=0 QTime=1386 [junit4] 2> 163619 INFO (zkCallback-164-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 163667 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] 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.RollingRestartTest_4DD3A648D35DC9B7-001/shard-2-001 of type NRT [junit4] 2> 163669 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T11:27:37-10:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 163671 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 163671 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 163671 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 163671 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@44de1da5{/igs/uc,null,AVAILABLE} [junit4] 2> 163672 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a34d376{SSL,[ssl, http/1.1]}{127.0.0.1:64475} [junit4] 2> 163672 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.Server Started @166009ms [junit4] 2> 163672 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] 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.RollingRestartTest_4DD3A648D35DC9B7-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/igs/uc, hostPort=64475, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_4DD3A648D35DC9B7-001/shard-2-001/cores} [junit4] 2> 163672 ERROR (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 163672 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 163672 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 163673 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 163673 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-21T16:20:05.756Z [junit4] 2> 163675 INFO (zkConnectionManagerCallback-169-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 163677 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 163677 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] 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.RollingRestartTest_4DD3A648D35DC9B7-001/shard-2-001/solr.xml [junit4] 2> 163681 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 163681 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 163681 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 163684 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58082/solr [junit4] 2> 163686 INFO (zkConnectionManagerCallback-173-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 163694 INFO (zkConnectionManagerCallback-175-thread-1-processing-n:127.0.0.1:64475_igs%2Fuc) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 163703 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 163706 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 163711 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64475_igs%2Fuc [junit4] 2> 163737 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 163738 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:64475_igs%2Fuc) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 163738 INFO (zkCallback-156-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 163738 INFO (zkCallback-164-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 163783 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 163789 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 163789 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 163790 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_4DD3A648D35DC9B7-001/shard-2-001/cores [junit4] 2> 163815 INFO (qtp1380590914-786) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:64475_igs%252Fuc&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 163819 INFO (OverseerCollectionConfigSetProcessor-99388725686697989-127.0.0.1:42492_igs%2Fuc-n_0000000000) [n:127.0.0.1:42492_igs%2Fuc ] 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> 163820 INFO (OverseerThreadFactory-406-thread-4-processing-n:127.0.0.1:42492_igs%2Fuc) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:64475_igs%2Fuc for creating new replica [junit4] 2> 163839 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 163839 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 163944 INFO (zkCallback-164-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 163944 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:64475_igs%2Fuc) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 164857 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 164865 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema [collection1_shard1_replica_n43] Schema name=test [junit4] 2> 164925 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 164944 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n43' using configuration from collection collection1, trusted=true [junit4] 2> 164945 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n43' (registry 'solr.core.collection1.shard1.replica_n43') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75a0629 [junit4] 2> 164945 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 164945 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore [[collection1_shard1_replica_n43] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_4DD3A648D35DC9B7-001/shard-2-001/cores/collection1_shard1_replica_n43], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_4DD3A648D35DC9B7-001/shard-2-001/cores/collection1_shard1_replica_n43/data/] [junit4] 2> 164947 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=13.9189453125, floorSegmentMB=0.3515625, forceMergeDeletesPctAllowed=15.885781921769365, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6980662586473276 [junit4] 2> 164954 WARN (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] 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> 164994 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 164994 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 164994 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 164995 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 164996 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5389223065436952] [junit4] 2> 164996 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.SolrIndexSearcher Opening [Searcher@4661b9fa[collection1_shard1_replica_n43] main] [junit4] 2> 164998 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 164998 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 164999 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 164999 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1590219617947615232 [junit4] 2> 165000 INFO (searcherExecutor-431-thread-1-processing-n:127.0.0.1:64475_igs%2Fuc x:collection1_shard1_replica_n43 s:shard1 c:collection1) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore [collection1_shard1_replica_n43] Registered new searcher Searcher@4661b9fa[collection1_shard1_replica_n43] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 165005 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 165006 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 165006 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:64475/igs/uc/collection1_shard1_replica_n43/ [junit4] 2> 165006 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 165006 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy https://127.0.0.1:64475/igs/uc/collection1_shard1_replica_n43/ has no replicas [junit4] 2> 165006 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 165012 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:64475/igs/uc/collection1_shard1_replica_n43/ shard1 [junit4] 2> 165115 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:64475_igs%2Fuc) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 165115 INFO (zkCallback-164-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 165163 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 165167 INFO (qtp1587076867-874) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1328 [junit4] 2> 165173 INFO (qtp1380590914-786) [n:127.0.0.1:42492_igs%2Fuc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:64475_igs%252Fuc&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=1358 [junit4] 2> 165179 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 165179 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):330 [junit4] 2> 165267 INFO (zkCallback-164-thread-1-processing-n:127.0.0.1:65525_igs%2Fuc) [n:127.0.0.1:65525_igs%2Fuc ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 165268 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:64475_igs%2Fuc) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 165822 INFO (OverseerCollectionConfigSetProcessor-99388725686697989-127.0.0.1:42492_igs%2Fuc-n_0000000000) [n:127.0.0.1:42492_igs%2Fuc ] 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> 166181 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1 [junit4] 2> 166182 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.RollingRestartTest Current overseer leader = 127.0.0.1:42492_igs%2Fuc [junit4] 1> / (11) [junit4] 1> /configs (2) [junit4] 1> /configs/_default (7) [junit4] 1> /configs/_default/protwords.txt (0) [junit4] 1> DATA: ...supressed... [junit4] 1> /configs/_default/managed-schema (0) [junit4] 1> DATA: [junit4] 1> <?xml version="1.0" encoding="UTF-8" ?> [junit4] 1> <!-- [junit4] 1> Licensed to the Apache Software Foundation (ASF) under one or more [junit4] 1> contributor license agreements. See the NOTICE file distributed with [junit4] 1> this work for additional information regarding copyright ownership. [junit4] 1> The ASF licenses this file to You under the Apache License, Version 2.0 [junit4] 1> (the "License"); you may not use this file except in compliance with [junit4] 1> the License. You may obtain a copy of the License at [junit4] 1> [junit4] 1> http://www.apache.org/licenses/LICENSE-2.0 [junit4] 1> [junit4] 1> Unless required by applicable law or agreed to in writing, software [junit4] 1> distributed under the License is distributed on an "AS IS" BASIS, [junit4] 1> WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. [junit4] 1> See the License for the specific language governing permissions and [junit4] 1> limitations under the License. [junit4] 1> --> [junit4] 1> [junit4] 1> <!-- [junit4] 1> [junit4] 1> This example schema is the recommended starting point for users. [junit4] 1> It should be kept correct and concise, usable out-of-the-box. [junit4] 1> [junit4] 1> [junit4] 1> For more information, on how to customize this file, please see [junit4] 1> http://lucene.apache.org/solr/guide/documents-fields-and-schema-design.html [junit4] 1> [junit4] 1> PERFORMANCE NOTE: this schema includes many optional features and should not [junit4] 1> be used for benchmarking. To improve performance one could [junit4] 1> - set stored="false" for all fields possible (esp large fields) when you [junit4] 1> only need to search on the field but don't need to return the original [junit4] 1> value. [junit4] 1> - set indexed="false" if you don't need to search on the field, but only [junit4] 1> return the field as a result of searching on other indexed fields. [junit4] 1> - remove all unneeded copyField statements [junit4] 1> - for best index size and searching performance, set "index" to false [junit4] 1> for all general text fields, use copyField to copy them to the [junit4] 1> catchall "text" field, and use that for searching. [junit4] 1> --> [junit4] 1> [junit4] 1> <schema name="default-config" version="1.6"> [junit4] 1> <!-- attribute "name" is the name of this schema and is only used for display purposes. [junit4] 1> version="x.y" is Solr's version number for the schema syntax and [junit4] 1> semantics. It should not normally be changed by applications. [junit4] 1> [junit4] 1> 1.0: multiValued attribute did not exist, all fields are multiValued [junit4] 1> by nature [junit4] 1> 1.1: multiValued attribute introduced, false by default [junit4] 1> 1.2: omitTermFreqAndPositions attribute introduced, true by default [junit4] 1> except for text fields. [junit4] 1> 1.3: removed optional field compress feature [junit4] 1> 1.4: autoGeneratePhraseQueries attribute introduced to drive QueryParser [junit4] 1> behavior when a single string produces multiple tokens. Defaults [junit4] 1> to off for version >= 1.4 [junit4] 1> 1.5: omitNorms defaults to true for primitive field types [junit4] 1> (int, float, boolean, string...) [junit4] 1> 1.6: useDocValuesAsStored defaults to true. [junit4] 1> --> [junit4] 1> [junit4] 1> <!-- Valid attributes for fields: [junit4] 1> name: mandatory - the name for the field [junit4] 1> type: mandatory - the name of a field type from the [junit4] 1> fieldTypes section [junit4] 1> indexed: true if this field should be indexed (searchable or sortable) [junit4] 1> stored: true if this field should be retrievable [junit4] 1> docValues: true if this field should have doc values. Doc Values is [junit4] 1> recommended (required, if you are using *Point fields) for faceting, [junit4] 1> grouping, sorting and function queries. Doc Values will make the index [junit4] 1> faster to load, more NRT-friendly and more memory-efficient. [junit4] 1> They are currently only supported by StrField, UUIDField, all [junit4] 1> *PointFields, and depending on the field type, they might require [junit4] 1> the field to be single-valued, be required or have a default value [junit4] 1> (check the documentation of the field type you're interested in for [junit4] 1> more information) [junit4] 1> multiValued: true if this field may contain multiple values per document [junit4] 1> omitNorms: (expert) set to true to omit the norms associated with [junit4] 1> this field (this disables length normalization and index-time [junit4] 1> boosting for the field, and saves some memory). Only full-text [junit4] 1> fields or fields that need an index-time boost need norms. [junit4] 1> Norms are omitted for primitive (non-analyzed) types by default. [junit4] 1> termVectors: [false] set to true to store the term vector for a [junit4] 1> given field. [junit4] 1> When using MoreLikeThis, fields used for similarity should be [junit4] 1> stored for best performance. [junit4] 1> termPositions: Store position information with the term vector. [junit4] 1> This will increase storage costs. [junit4] 1> termOffsets: Store offset information with the term vector. This [junit4] 1> will increase storage costs. [junit4] 1> required: The field is required. It will throw an error if the [junit4] 1> value does not exist [junit4] 1> default: a value that should be used if no value is specified [junit4] 1> when adding a document. [junit4] 1> --> [junit4] 1> [junit4] 1> <!-- field names should consist of alphanumeric or underscore characters only and [junit4] 1> not start with a digit. This is not currently strictly enforced, [junit4] 1> but other field names will not have first class support from all components [junit4] 1> and back compatibility is not guaranteed. Names with both leading and [junit4] 1> trailing underscores (e.g. _version_) are reserved. [junit4] 1> --> [junit4] 1> [junit4] 1> <!-- In this _default configset, only four fields are pre-declared: [junit4] 1> id, _version_, and _text_ and _root_. All other fields will be type guessed and added via the [junit4] 1> "add-unknown-fields-to-the-schema" update request processor chain declared in solrconfig.xml. [junit4] 1> [junit4] 1> Note that many dynamic fields are also defined - you can use them to specify a [junit4] 1> field's type via field naming conventions - see below. [junit4] 1> [junit4] 1> WARNING: The _text_ catch-all field will significantly increase your index size. [junit4] 1> If you don't need it, consider removing it and the corresponding copyField directive. [junit4] 1> --> [junit4] 1> [junit4] 1> <field name="id" type="string" indexed="true" stored="true" required="true" multiValued="false" /> [junit4] 1> <!-- docValues are enabled by default for long type so we don't need to index the version field --> [junit4] 1> <field name="_version_" type="plong" indexed="false" stored="false"/> [junit4] 1> <field name="_root_" type="string" indexed="true" stored="false" docValues="false" /> [junit4] 1> <field name="_text_" type="text_general" indexed="true" stored="false" multiValued="true"/> [junit4] 1> [junit4] 1> <!-- This can be enabled, in case the client does not know what fields may be searched. It isn't enabled by default [junit4] 1> because it's very expensive to index everything twice. --> [junit4] 1> <!-- <copyField source="*" dest="_text_"/> --> [junit4] 1> [junit4] 1> <!-- Dynamic field definitions allow using convention over configuration [junit4] 1> for fields via the specification of patterns to match field names. [junit4] 1> EXAMPLE: name="*_i" will match any field ending in _i (like myid_i, z_i) [junit4] 1> RESTRICTION: the glob-like pattern in the name attribute must have a "*" only at the start or the end. --> [junit4] 1> [junit4] 1> <dynamicField name="*_i" type="pint" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_is" type="pints" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_s" type="string" indexed="true" stored="true" /> [junit4] 1> <dynamicField name="*_ss" type="strings" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_l" type="plong" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_ls" type="plongs" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_txt" type="text_general" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_b" type="boolean" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_bs" type="booleans" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_f" type="pfloat" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_fs" type="pfloats" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_d" type="pdouble" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_ds" type="pdoubles" indexed="true" stored="true"/> [junit4] 1> [junit4] 1> <!-- Type used for data-driven schema, to add a string copy for each text field --> [junit4] 1> <dynamicField name="*_str" type="strings" stored="false" docValues="true" indexed="false" /> [junit4] 1> [junit4] 1> <dynamicField name="*_dt" type="pdate" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_dts" type="pdate" indexed="true" stored="true" multiValued="true"/> [junit4] 1> <dynamicField name="*_p" type="location" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_srpt" type="location_rpt" indexed="true" stored="true"/> [junit4] 1> [junit4] 1> <!-- payloaded dynamic fields --> [junit4] 1> <dynamicField name="*_dpf" type="delimited_payloads_float" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_dpi" type="delimited_payloads_int" indexed="true" stored="true"/> [junit4] 1> <dynamicField name="*_dps" type="delimited_payloads_string" indexed="true" stored="true"/> [junit4] 1> [junit4] 1> <dynamicField name="attr_*" type="text_general" indexed="true" stored="true" multiValued="true"/> [junit4] 1> [junit4] 1> <!-- Field to use to determine and enforce document uniqueness. [junit4] 1> Unless this field is marked with required="false", it will be a required field [junit4] 1> --> [junit4] 1> <uniqueKey>id</uniqueKey> [junit4] 1> [junit4] 1> <!-- copyField commands copy one field to another at the time a document [junit4] 1> is added to the index. It's used either to index the same field differently, [junit4] 1> or to add multiple fields to the same field for easier/faster searching. [junit4] 1> [junit4] 1> <copyField source="sourceFieldName" dest="destinationFieldName"/> [junit4] 1> --> [junit4] 1> [junit4] 1> <!-- field type definitions. The "name" attribute is [junit4] 1> just a label to be used by field definitions. The "class" [junit4] 1> attribute and any other attributes determine the real [junit4] 1> behavior of the fieldType. [junit4] 1> Class names starting with "solr" refer to java classes in a [junit4] 1> standard package such as org.apache.solr.analysis [junit4] 1> --> [junit4] 1> [junit4] 1> <!-- sortMissingLast and sortMissingFirst attributes are optional attributes are [junit4] 1> currently supported on types that are sorted internally as strings [junit4] 1> and on numeric types. [junit4] 1> This includes "string", "boolean", "pint", "pfloat", "plong", "pdate", "pdouble". [junit4] 1> - If sortMissingLast="true", then a sort on this field will cause documents [junit4] 1> without the field to come after documents with the field, [junit4] 1> regardless of the requested sort order (asc or desc). [junit4] 1> - If sortMissingFirst="true", then a sort on this field will cause documents [junit4] 1> without the field to come before documents with the field, [junit4] 1> regardless of the requested sort order. [junit4] 1> - If sortMissingLast="false" and sortMissingFirst="false" (the default), [junit4] 1> then default lucene sorting will be used which places docs without the [junit4] 1> field first in an ascending sort and last in a descending sort. [junit4] 1> --> [junit4] 1> [junit4] 1> <!-- The StrField type is not analyzed, but indexed/stored verbatim. --> [junit4] 1> <fieldType name="string" class="solr.StrField" sortMissingLast="true" docValues="true" /> [junit4] 1> <fieldType name="strings" class="solr.StrField" sortMissingLast="true" multiValued="true" docValues="true" /> [junit4] 1> [junit4] 1> <!-- boolean type: "true" or "false" --> [junit4] 1> <fieldType name="boolean" class="solr.BoolField" sortMissingLast="true"/> [junit4] 1> <fieldType name="booleans" class="solr.BoolField" sortMissingLast="true" multiValued="true"/> [junit4] 1> [junit4] 1> <!-- [junit4] 1> Numeric field types that index values using KD-trees. [junit4] 1> Point fields don't support FieldCache, so they must have docValues="true" if needed for sorting, faceting, functions, etc. [junit4] 1> --> [junit4] 1> <fieldType name="pint" class="solr.IntPointField" docValues="true"/> [junit4] 1> <fieldType name="pfloat" class="solr.FloatPointField" docValues="true"/> [junit4] 1> <fieldType name="plong" class="solr.LongPointField" docValues="true"/> [junit4] 1> <fieldType name="pdouble" class="solr.DoublePointField" docValues="true"/> [junit4] 1> [junit4] 1> <fieldType name="pints" class="solr.IntPointField" docValues="true" multiValued="true"/> [junit4] 1> <fieldType name="pfloats" class="solr.FloatPointField" docValues="true" multiValued="true"/> [junit4] 1> <fieldType name="plongs" class="solr.LongPointField" docValues="true" multiValued="true"/> [junit4] 1> <fieldType name="pdoubles" class="solr.DoublePointField" docValues="true" multiValued="true"/> [junit4] 1> [junit4] 1> <!-- The format for this date field is of the form 1995-12-31T23:59:59Z, and [junit4] 1> is a more restricted form of the canonical representation of dateTime [junit4] 1> http://www.w3.org/TR/xmlschema-2/#dateTime [junit4] 1> The trailing "Z" designates UTC time and is mandatory. [junit4] 1> Optional fractional seconds are allowed: 1995-12-31T23:59:59.999Z [junit4] 1> All other components are mandatory. [junit4] 1> [junit4] 1> Expressions can also be used to denote calculations that should be [junit4] 1> performed relative to "NOW" to determine the value, ie... [junit4] 1> [junit4] 1> NOW/HOUR [junit4] 1> ... Round to the start of the current hour [junit4] 1> NOW-1DAY [junit4] 1> ... Exactly 1 day prior to now [junit4] 1> NOW/DAY+6MONTHS+3DAYS [junit4] 1> ... 6 months and 3 days in the future from the start of [junit4] 1> the current day [junit4] 1> [junit4] 1> --> [junit4] 1> <!-- KD-tree versions of date fields --> [junit4] 1> <fieldType name="pdate" class="solr.DatePointField" docValues="true"/> [junit4] 1> <fieldType name="pdates" class="solr.DatePointField" docValues="true" multiValued="true"/> [junit4] 1> [junit4] 1> <!--Binary data type. The data should be sent/retrieved in as Base64 encoded Strings --> [junit4] 1> <fieldType name="binary" class="solr.BinaryField"/> [junit4] 1> [junit4] 1> <!-- solr.TextField allows the specification of custom text analyzers [junit4] 1> specified as a tokenizer and a list of token filters. Different [junit4] 1> analyzers may be specified for indexing and querying. [junit4] 1> [junit4] 1> The optional positionIncrementGap puts space between multiple fields of [junit4] 1> this type on the same document, with the purpose of preventing false phrase [junit4] 1> matching across fields. [junit4] 1> [junit4] 1> For more info on customizing your analyzer chain, please see [junit4] 1> http://lucene.apache.org/solr/guide/understanding-analyzers-tokenizers-and-filters.html#understanding-analyzers-tokenizers-and-filters [junit4] 1> --> [junit4] 1> [junit4] 1> <!-- One can also specify an existing Analyzer class that has a [junit4] 1> default constructor via the class attribute on the analyzer element. [junit4] 1> Example: [junit4] 1> <fieldType name="text_greek" class="solr.TextField"> [junit4] 1> <analyzer class="org.apache.lucene.analysis.el.GreekAnalyzer"/> [junit4] 1> </fieldType> [junit4] 1> --> [junit4] 1> [junit4] 1> <!-- A text field that only splits on whitespace for exact matching of words --> [junit4] 1> <dynamicField name="*_ws" type="text_ws" indexed="true" stored="true"/> [junit4] 1> <fieldType name="text_ws" class="solr.TextField" positionIncrementGap="100"> [junit4] 1> <analyzer> [junit4] 1> <tokenizer class="solr.WhitespaceTokenizerFactory"/> [junit4] 1> </analyzer> [junit4] 1> </fieldType> [junit4] 1> [junit4] 1> <!-- A general text field that has reasonable, generic [junit4] 1> cross-language defaults: it tokenizes with StandardTokenizer, [junit4] 1> removes stop words from case-insensitive "stopwords.txt" [junit4] 1> (empty by default), and down cases. At query time only, it [junit4] 1> also applies synonyms. [junit4] 1> --> [junit4] 1> <fieldType name="text_general" class="solr.TextField" positionIncrementGap="100" multiValued="true"> [junit4] 1> <analyzer type="index"> [junit4] 1> <tokenizer class="solr.StandardTokenizerFactory"/> [junit4] 1> <filter class="solr.StopFilterFactory" ignoreCase="true" words="stopwords.txt" /> [junit4] 1> <!-- in this example, we will only use synonyms at query time [junit4] 1> [...truncated too long message...] ale.metrics.MetricRegistry@245d83c2 [junit4] 2> 210101 INFO (zkCallback-174-thread-2-processing-n:127.0.0.1:64475_igs%2Fuc) [n:127.0.0.1:64475_igs%2Fuc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 210101 INFO (zkCallback-156-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 210105 INFO (coreCloseExecutor-544-thread-1) [n:127.0.0.1:64475_igs%2Fuc c:collection1 s:shard1 r:core_node44 x:collection1_shard1_replica_n43] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader, tag=673836824 [junit4] 2> 210110 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x1611984f93c000d, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 210113 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1a34d376{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 210113 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@44de1da5{/igs/uc,null,UNAVAILABLE} [junit4] 2> 210113 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 210119 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.ChaosMonkey monkey: stop jetty! 42492 [junit4] 2> 210119 ERROR (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 210122 INFO (TEST-RollingRestartTest.test-seed#[4DD3A648D35DC9B7]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58082 58082 [junit4] 2> 210166 INFO (Thread-122) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58082 58082 [junit4] 2> 210167 WARN (Thread-122) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 9 /solr/aliases.json [junit4] 2> 7 /solr/security.json [junit4] 2> 7 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 14 /solr/autoscaling.json [junit4] 2> 9 /solr/clusterprops.json [junit4] 2> 9 /solr/clusterstate.json [junit4] 2> 6 /solr/collections/collection1/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 14 /solr/overseer/queue-work [junit4] 2> 12 /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 10 /solr/overseer/collection-queue-work [junit4] 2> 9 /solr/live_nodes [junit4] 2> 9 /solr/collections [junit4] 2> 5 /solr/overseer/queue [junit4] 2> [junit4] 2> 210168 INFO (SUITE-RollingRestartTest-seed#[4DD3A648D35DC9B7]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> Jan 21, 2018 4:20:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> Jan 21, 2018 4:21:12 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.cloud.RollingRestartTest: [junit4] 2> 1) Thread[id=978, name=qtp1660790768-978, state=TIMED_WAITING, group=TGRP-RollingRestartTest] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Jan 21, 2018 4:21:12 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=978, name=qtp1660790768-978, state=TIMED_WAITING, group=TGRP-RollingRestartTest] [junit4] 2> Jan 21, 2018 4:21:15 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated: [junit4] 2> 1) Thread[id=978, name=qtp1660790768-978, state=TIMED_WAITING, group=TGRP-RollingRestartTest] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1273, maxMBSortInHeap=7.242748469927479, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@f01e7a7), locale=ca, timezone=Pacific/Truk [junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=2,free=32510888,total=320864256 [junit4] 2> NOTE: All tests run in this JVM: [SubstringBytesRefFilterTest, TestExtendedDismaxParser, TestCloudPseudoReturnFields, CachingDirectoryFactoryTest, TermVectorComponentTest, CollectionsAPIDistributedZkTest, TestSolrIndexConfig, SortByFunctionTest, TestShardHandlerFactory, CdcrReplicationDistributedZkTest, TestQueryWrapperFilter, TestPerFieldSimilarityWithDefaultOverride, CoreAdminHandlerTest, RollingRestartTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RollingRestartTest -Dtests.seed=4DD3A648D35DC9B7 -Dtests.slow=true -Dtests.locale=ca -Dtests.timezone=Pacific/Truk -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.00s J1 | RollingRestartTest (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.RollingRestartTest: [junit4] > 1) Thread[id=978, name=qtp1660790768-978, state=TIMED_WAITING, group=TGRP-RollingRestartTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([4DD3A648D35DC9B7]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=978, name=qtp1660790768-978, state=TIMED_WAITING, group=TGRP-RollingRestartTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([4DD3A648D35DC9B7]:0) [junit4] Completed [41/768 (1!)] on J1 in 75.12s, 1 test, 2 errors <<< FAILURES! [...truncated 50407 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org