Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1448/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDocAbsent
Error Message:
Did not find the expected number of groups for field intGSF expected:<4> but
was:<3>
Stack Trace:
java.lang.AssertionError: Did not find the expected number of groups for field
intGSF expected:<4> but was:<3>
at
__randomizedtesting.SeedInfo.seed([B44C3FCFFA90B617:6AFBE4471FC1F6D2]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDocAbsent(DocValuesNotIndexedTest.java:305)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation
Error Message:
2 threads leaked from SUITE scope at
org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation: 1)
Thread[id=23031, name=jetty-launcher-3705-thread-1-EventThread,
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
2) Thread[id=23041, name=jetty-launcher-3705-thread-2-EventThread,
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:
1) Thread[id=23031, name=jetty-launcher-3705-thread-1-EventThread,
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
2) Thread[id=23041, name=jetty-launcher-3705-thread-2-EventThread,
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
at __randomizedtesting.SeedInfo.seed([B44C3FCFFA90B617]:0)
Build Log:
[...truncated 13089 lines...]
[junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/init-core-data-001
[junit4] 2> 2393580 WARN
(SUITE-DocValuesNotIndexedTest-seed#[B44C3FCFFA90B617]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 2393581 INFO
(SUITE-DocValuesNotIndexedTest-seed#[B44C3FCFFA90B617]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2393582 INFO
(SUITE-DocValuesNotIndexedTest-seed#[B44C3FCFFA90B617]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2393582 INFO
(SUITE-DocValuesNotIndexedTest-seed#[B44C3FCFFA90B617]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2393583 INFO
(SUITE-DocValuesNotIndexedTest-seed#[B44C3FCFFA90B617]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001
[junit4] 2> 2393583 INFO
(SUITE-DocValuesNotIndexedTest-seed#[B44C3FCFFA90B617]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2393583 INFO (Thread-4241) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2393584 INFO (Thread-4241) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2393586 ERROR (Thread-4241) [ ] 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> 2393683 INFO
(SUITE-DocValuesNotIndexedTest-seed#[B44C3FCFFA90B617]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:60627
[junit4] 2> 2393696 INFO (jetty-launcher-2971-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2393696 INFO (jetty-launcher-2971-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2393700 INFO (jetty-launcher-2971-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1410eeb6{/solr,null,AVAILABLE}
[junit4] 2> 2393701 INFO (jetty-launcher-2971-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@262d2e6e{/solr,null,AVAILABLE}
[junit4] 2> 2393701 INFO (jetty-launcher-2971-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@53de0f2c{HTTP/1.1,[http/1.1]}{127.0.0.1:48156}
[junit4] 2> 2393701 INFO (jetty-launcher-2971-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@27f63594{HTTP/1.1,[http/1.1]}{127.0.0.1:62879}
[junit4] 2> 2393701 INFO (jetty-launcher-2971-thread-1) [ ]
o.e.j.s.Server Started @2396873ms
[junit4] 2> 2393701 INFO (jetty-launcher-2971-thread-2) [ ]
o.e.j.s.Server Started @2396873ms
[junit4] 2> 2393701 INFO (jetty-launcher-2971-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=62879}
[junit4] 2> 2393701 INFO (jetty-launcher-2971-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=48156}
[junit4] 2> 2393702 ERROR (jetty-launcher-2971-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2393702 ERROR (jetty-launcher-2971-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2393702 INFO (jetty-launcher-2971-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2393703 INFO (jetty-launcher-2971-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2393703 INFO (jetty-launcher-2971-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2393704 INFO (jetty-launcher-2971-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 2393704 INFO (jetty-launcher-2971-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2393704 INFO (jetty-launcher-2971-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-02T02:41:11.365Z
[junit4] 2> 2393704 INFO (jetty-launcher-2971-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 2393704 INFO (jetty-launcher-2971-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-02T02:41:11.365Z
[junit4] 2> 2393710 INFO (jetty-launcher-2971-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2393710 INFO (jetty-launcher-2971-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2393740 INFO (jetty-launcher-2971-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60627/solr
[junit4] 2> 2393742 INFO (jetty-launcher-2971-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60627/solr
[junit4] 2> 2393884 INFO (jetty-launcher-2971-thread-1)
[n:127.0.0.1:62879_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2393885 INFO (jetty-launcher-2971-thread-1)
[n:127.0.0.1:62879_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:62879_solr
[junit4] 2> 2393886 INFO (jetty-launcher-2971-thread-1)
[n:127.0.0.1:62879_solr ] o.a.s.c.Overseer Overseer
(id=98756989501177862-127.0.0.1:62879_solr-n_0000000000) starting
[junit4] 2> 2393890 INFO (jetty-launcher-2971-thread-2)
[n:127.0.0.1:48156_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2393892 INFO (jetty-launcher-2971-thread-2)
[n:127.0.0.1:48156_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:48156_solr
[junit4] 2> 2393894 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2393894 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2393899 INFO (jetty-launcher-2971-thread-1)
[n:127.0.0.1:62879_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:62879_solr
[junit4] 2> 2393900 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2393900 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2394005 INFO (jetty-launcher-2971-thread-2)
[n:127.0.0.1:48156_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_48156.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2394010 INFO (jetty-launcher-2971-thread-2)
[n:127.0.0.1:48156_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_48156.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2394010 INFO (jetty-launcher-2971-thread-2)
[n:127.0.0.1:48156_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_48156.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2394010 INFO (jetty-launcher-2971-thread-2)
[n:127.0.0.1:48156_solr ] 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.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node2/.
[junit4] 2> 2394030 INFO (jetty-launcher-2971-thread-1)
[n:127.0.0.1:62879_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62879.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2394038 INFO (jetty-launcher-2971-thread-1)
[n:127.0.0.1:62879_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62879.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2394039 INFO (jetty-launcher-2971-thread-1)
[n:127.0.0.1:62879_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62879.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2394040 INFO (jetty-launcher-2971-thread-1)
[n:127.0.0.1:62879_solr ] 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.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node1/.
[junit4] 2> 2394072 INFO
(SUITE-DocValuesNotIndexedTest-seed#[B44C3FCFFA90B617]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2394074 INFO
(SUITE-DocValuesNotIndexedTest-seed#[B44C3FCFFA90B617]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60627/solr ready
[junit4] 2> 2394082 INFO (qtp195727981-18616) [n:127.0.0.1:62879_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2394086 INFO
(OverseerThreadFactory-8632-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.c.CreateCollectionCmd Create collection
dv_coll
[junit4] 2> 2394202 INFO
(OverseerStateUpdate-98756989501177862-127.0.0.1:62879_solr-n_0000000000)
[n:127.0.0.1:62879_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"dv_coll_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:62879/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 2394207 INFO
(OverseerStateUpdate-98756989501177862-127.0.0.1:62879_solr-n_0000000000)
[n:127.0.0.1:62879_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"dv_coll_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:48156/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 2394212 INFO
(OverseerStateUpdate-98756989501177862-127.0.0.1:62879_solr-n_0000000000)
[n:127.0.0.1:62879_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"dv_coll_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:62879/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 2394219 INFO
(OverseerStateUpdate-98756989501177862-127.0.0.1:62879_solr-n_0000000000)
[n:127.0.0.1:62879_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard4",
[junit4] 2> "core":"dv_coll_shard4_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:48156/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 2394418 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2394419 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n4&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2394419 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2394421 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n6&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2394421 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n2&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2394421 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2394531 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2394531 INFO
(zkCallback-2983-thread-2-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2394531 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2394532 INFO
(zkCallback-2982-thread-2-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2395444 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2395493 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.s.IndexSchema [dv_coll_shard2_replica_n2] Schema name=minimal
[junit4] 2> 2395494 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2395496 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2395507 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2395507 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard2_replica_n2' using
configuration from collection dv_coll, trusted=true
[junit4] 2> 2395508 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_48156.solr.core.dv_coll.shard2.replica_n2' (registry
'solr.core.dv_coll.shard2.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2395508 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2395508 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore
[[dv_coll_shard2_replica_n2] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node2/dv_coll_shard2_replica_n2],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node2/./dv_coll_shard2_replica_n2/data/]
[junit4] 2> 2395521 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2395521 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal
[junit4] 2> 2395524 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2395524 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard1_replica_n1' using
configuration from collection dv_coll, trusted=true
[junit4] 2> 2395524 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2395524 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard3_replica_n4' using
configuration from collection dv_coll, trusted=true
[junit4] 2> 2395525 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62879.solr.core.dv_coll.shard1.replica_n1' (registry
'solr.core.dv_coll.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2395525 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62879.solr.core.dv_coll.shard3.replica_n4' (registry
'solr.core.dv_coll.shard3.replica_n4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2395525 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2395525 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2395525 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore
[[dv_coll_shard1_replica_n1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node1/dv_coll_shard1_replica_n1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node1/./dv_coll_shard1_replica_n1/data/]
[junit4] 2> 2395525 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore
[[dv_coll_shard3_replica_n4] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node1/dv_coll_shard3_replica_n4],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node1/./dv_coll_shard3_replica_n4/data/]
[junit4] 2> 2395576 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2395609 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2395609 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2395610 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2395611 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2395612 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4910859a[dv_coll_shard2_replica_n2]
main]
[junit4] 2> 2395617 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.s.IndexSchema [dv_coll_shard4_replica_n6] Schema name=minimal
[junit4] 2> 2395619 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2395619 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard4_replica_n6' using
configuration from collection dv_coll, trusted=true
[junit4] 2> 2395620 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_48156.solr.core.dv_coll.shard4.replica_n6' (registry
'solr.core.dv_coll.shard4.replica_n6') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2395620 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2395620 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore
[[dv_coll_shard4_replica_n6] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node2/dv_coll_shard4_replica_n6],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node2/./dv_coll_shard4_replica_n6/data/]
[junit4] 2> 2395629 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2395630 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2395630 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 2395632 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2395632 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2395632 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1580111834165280768
[junit4] 2> 2395634 INFO
(searcherExecutor-8637-thread-1-processing-n:127.0.0.1:48156_solr
x:dv_coll_shard2_replica_n2 s:shard2 c:dv_coll r:core_node5)
[n:127.0.0.1:48156_solr c:dv_coll s:shard2 r:core_node5
x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2]
Registered new searcher Searcher@4910859a[dv_coll_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2395656 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2395656 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2395657 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2395658 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2395659 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@76f595b[dv_coll_shard1_replica_n1]
main]
[junit4] 2> 2395661 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2395661 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2395662 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2395662 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2395664 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.s.SolrIndexSearcher Opening [Searcher@69246848[dv_coll_shard3_replica_n4]
main]
[junit4] 2> 2395666 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2395673 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2395674 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2395674 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 2395678 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2395678 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 2395679 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2395679 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2395679 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:48156/solr/dv_coll_shard2_replica_n2/
[junit4] 2> 2395679 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2395679 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.c.SyncStrategy http://127.0.0.1:48156/solr/dv_coll_shard2_replica_n2/ has
no replicas
[junit4] 2> 2395679 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2395681 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2395684 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:48156/solr/dv_coll_shard2_replica_n2/ shard2
[junit4] 2> 2395684 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2395684 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1580111834219806720
[junit4] 2> 2395689 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2395691 INFO
(searcherExecutor-8638-thread-1-processing-n:127.0.0.1:62879_solr
x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node3)
[n:127.0.0.1:62879_solr c:dv_coll s:shard1 r:core_node3
x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1]
Registered new searcher Searcher@76f595b[dv_coll_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2395691 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2395691 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1580111834227146752
[junit4] 2> 2395695 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2395695 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2395695 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/
[junit4] 2> 2395696 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2395696 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.SyncStrategy http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/ has
no replicas
[junit4] 2> 2395696 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2395697 INFO
(searcherExecutor-8639-thread-1-processing-n:127.0.0.1:62879_solr
x:dv_coll_shard3_replica_n4 s:shard3 c:dv_coll r:core_node7)
[n:127.0.0.1:62879_solr c:dv_coll s:shard3 r:core_node7
x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4]
Registered new searcher Searcher@69246848[dv_coll_shard3_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2395708 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2395708 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2395708 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:62879/solr/dv_coll_shard3_replica_n4/
[junit4] 2> 2395708 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2395711 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.c.SyncStrategy http://127.0.0.1:62879/solr/dv_coll_shard3_replica_n4/ has
no replicas
[junit4] 2> 2395711 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2395715 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/ shard1
[junit4] 2> 2395715 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2395716 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2395721 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2395721 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2395722 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.s.SolrIndexSearcher Opening [Searcher@beee979[dv_coll_shard4_replica_n6]
main]
[junit4] 2> 2395723 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:62879/solr/dv_coll_shard3_replica_n4/ shard3
[junit4] 2> 2395724 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2395724 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2395727 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 2395728 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2395728 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2395729 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1580111834266992640
[junit4] 2> 2395729 INFO
(searcherExecutor-8644-thread-1-processing-n:127.0.0.1:48156_solr
x:dv_coll_shard4_replica_n6 s:shard4 c:dv_coll r:core_node8)
[n:127.0.0.1:48156_solr c:dv_coll s:shard4 r:core_node8
x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore [dv_coll_shard4_replica_n6]
Registered new searcher Searcher@beee979[dv_coll_shard4_replica_n6]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2395747 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2395747 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2395747 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:48156/solr/dv_coll_shard4_replica_n6/
[junit4] 2> 2395747 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2395747 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.c.SyncStrategy http://127.0.0.1:48156/solr/dv_coll_shard4_replica_n6/ has
no replicas
[junit4] 2> 2395747 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2395757 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:48156/solr/dv_coll_shard4_replica_n6/ shard4
[junit4] 2> 2395874 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2395875 INFO
(zkCallback-2983-thread-2-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2395875 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2395875 INFO
(zkCallback-2982-thread-2-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2395878 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2395884 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2395884 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n4&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1465
[junit4] 2> 2395892 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2395898 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1480
[junit4] 2> 2395900 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n2&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1479
[junit4] 2> 2395913 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2395918 INFO (qtp2126010706-18611) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n6&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1497
[junit4] 2> 2395951 INFO (qtp195727981-18616) [n:127.0.0.1:62879_solr
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 2396069 INFO
(zkCallback-2982-thread-2-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2396070 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2396078 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2396078 INFO
(zkCallback-2983-thread-2-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2396090 INFO
(OverseerCollectionConfigSetProcessor-98756989501177862-127.0.0.1:62879_solr-n_0000000000)
[n:127.0.0.1:62879_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> 2396954 INFO (qtp195727981-18616) [n:127.0.0.1:62879_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2}
status=0 QTime=2872
[junit4] 2> 2396962 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.SchemaManager managed schema loaded . version : 0
[junit4] 2> 2396965 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2397004 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2397021 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.ZkIndexSchemaReader A schema change:
WatchedEvent state:SyncConnected type:NodeDataChanged
path:/configs/conf1/managed-schema, has occurred - updating schema from
ZooKeeper ...
[junit4] 2> 2397021 INFO
(zkCallback-2983-thread-2-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.ZkIndexSchemaReader A schema change:
WatchedEvent state:SyncConnected type:NodeDataChanged
path:/configs/conf1/managed-schema, has occurred - updating schema from
ZooKeeper ...
[junit4] 2> 2397021 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.ZkController Persisted config data to node
/configs/conf1/managed-schema
[junit4] 2> 2397026 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.ZkIndexSchemaReader A schema change:
WatchedEvent state:SyncConnected type:NodeDataChanged
path:/configs/conf1/managed-schema, has occurred - updating schema from
ZooKeeper ...
[junit4] 2> 2397027 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema
version 1 from ZooKeeper
[junit4] 2> 2397029 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.IndexSchema [dv_coll_shard4_replica_n6]
Schema name=minimal
[junit4] 2> 2397029 INFO
(zkCallback-2982-thread-2-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.ZkIndexSchemaReader A schema change:
WatchedEvent state:SyncConnected type:NodeDataChanged
path:/configs/conf1/managed-schema, has occurred - updating schema from
ZooKeeper ...
[junit4] 2> 2397030 INFO
(zkCallback-2982-thread-2-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema
version 1 from ZooKeeper
[junit4] 2> 2397033 INFO
(zkCallback-2982-thread-2-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.IndexSchema [dv_coll_shard2_replica_n2]
Schema name=minimal
[junit4] 2> 2397034 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with
uniqueid field id
[junit4] 2> 2397034 INFO
(zkCallback-2982-thread-1-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing
schema in 7 ms
[junit4] 2> 2397034 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema
version 1 from ZooKeeper
[junit4] 2> 2397037 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4]
Schema name=minimal
[junit4] 2> 2397043 INFO
(zkCallback-2982-thread-2-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with
uniqueid field id
[junit4] 2> 2397043 INFO
(zkCallback-2982-thread-2-processing-n:127.0.0.1:48156_solr)
[n:127.0.0.1:48156_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing
schema in 12 ms
[junit4] 2> 2397045 INFO (Thread-4260) [n:127.0.0.1:48156_solr ]
o.a.s.c.SolrCore config update listener called for core
dv_coll_shard4_replica_n6
[junit4] 2> 2397046 INFO (Thread-4260) [n:127.0.0.1:48156_solr ]
o.a.s.c.SolrCore config update listener called for core
dv_coll_shard2_replica_n2
[junit4] 2> 2397047 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with
uniqueid field id
[junit4] 2> 2397047 INFO
(zkCallback-2983-thread-1-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing
schema in 12 ms
[junit4] 2> 2397048 INFO (Thread-4261) [n:127.0.0.1:62879_solr ]
o.a.s.c.SolrCore config update listener called for core
dv_coll_shard1_replica_n1
[junit4] 2> 2397051 INFO (Thread-4261) [n:127.0.0.1:62879_solr ]
o.a.s.c.SolrCore core reload dv_coll_shard1_replica_n1
[junit4] 2> 2397060 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2397069 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2397074 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2397074 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard1_replica_n1' using
configuration from collection dv_coll
[junit4] 2> 2397161 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62879.solr.core.dv_coll.shard1.replica_n1' (registry
'solr.core.dv_coll.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2397161 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore
[[dv_coll_shard1_replica_n1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node1/dv_coll_shard1_replica_n1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node1/./dv_coll_shard1_replica_n1/data/]
[junit4] 2> 2397241 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2397241 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2397243 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6376189c[dv_coll_shard1_replica_n1]
main]
[junit4] 2> 2397249 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2397249 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2397250 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 2397260 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 2397260 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2397260 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1580111835872362496
[junit4] 2> 2397263 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 2397267 INFO
(searcherExecutor-8657-thread-1-processing-n:127.0.0.1:62879_solr
x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node3)
[n:127.0.0.1:62879_solr c:dv_coll s:shard1 r:core_node3
x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1]
Registered new searcher Searcher@6376189c[dv_coll_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2397267 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@7c0c5716[dv_coll_shard1_replica_n1]
main]
[junit4] 2> 2397267 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 3 replicas to apply
schema update version 1 for collection dv_coll
[junit4] 2> 2397270 INFO
(zkCallback-2983-thread-2-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema
version 1 from ZooKeeper
[junit4] 2> 2397274 INFO
(zkCallback-2983-thread-2-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1]
Schema name=minimal
[junit4] 2> 2397274 INFO (qtp2126010706-18610) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.S.Request
[dv_coll_shard2_replica_n2] webapp=/solr path=/schema/zkversion
params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2397279 INFO
(zkCallback-2983-thread-2-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with
uniqueid field id
[junit4] 2> 2397279 INFO
(zkCallback-2983-thread-2-processing-n:127.0.0.1:62879_solr)
[n:127.0.0.1:62879_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing
schema in 8 ms
[junit4] 2> 2397279 INFO
(searcherExecutor-8657-thread-1-processing-n:127.0.0.1:62879_solr
x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node3)
[n:127.0.0.1:62879_solr c:dv_coll s:shard1 r:core_node3
x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1]
Registered new searcher Searcher@7c0c5716[dv_coll_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2397286 INFO (qtp195727981-18617) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.S.Request
[dv_coll_shard3_replica_n4] webapp=/solr path=/schema/zkversion
params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2397288 INFO (Thread-4261) [n:127.0.0.1:62879_solr ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2397292 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.S.Request
[dv_coll_shard4_replica_n6] webapp=/solr path=/schema/zkversion
params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2397296 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.ManagedIndexSchema Took 28.0ms for 3 replicas to apply schema update
version 1 for collection dv_coll
[junit4] 2> 2397296 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.S.Request
[dv_coll_shard1_replica_n1] webapp=/solr path=/schema
params={wt=javabin&version=2} status=0 QTime=337
[junit4] 2> 2397296 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore
[dv_coll_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@6f2f0d46
[junit4] 2> 2397296 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.dv_coll.shard1.replica_n1, tag=1865354566
[junit4] 2> 2397296 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3896bc0a: rootName =
solr_62879, domain = solr.core.dv_coll.shard1.replica_n1, service url = null,
agent id = null] for registry solr.core.dv_coll.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@58961d90
[junit4] 2> 2397299 INFO (Thread-4261) [n:127.0.0.1:62879_solr ]
o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2397303 INFO (Thread-4261) [n:127.0.0.1:62879_solr ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2397303 INFO (Thread-4261) [n:127.0.0.1:62879_solr ]
o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard1_replica_n1' using
configuration from collection dv_coll
[junit4] 2> 2397304 INFO (qtp195727981-18624) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.dv_coll.shard1.leader, tag=1865354566
[junit4] 2> 2397429 INFO
(TEST-DocValuesNotIndexedTest.testDistribFaceting-seed#[B44C3FCFFA90B617]) [
] o.a.s.SolrTestCaseJ4 ###Starting testDistribFaceting
[junit4] 2> 2397437 INFO (qtp2126010706-18615) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr
path=/update
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard3_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
(-1580111836055863296)} 0 2
[junit4] 2> 2397450 INFO (Thread-4261) [n:127.0.0.1:62879_solr c:dv_coll
s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter
JMX monitoring for 'solr_62879.solr.core.dv_coll.shard1.replica_n1' (registry
'solr.core.dv_coll.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@66f46c05
[junit4] 2> 2397450 INFO (Thread-4261) [n:127.0.0.1:62879_solr c:dv_coll
s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore
[[dv_coll_shard1_replica_n1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node1/dv_coll_shard1_replica_n1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_B44C3FCFFA90B617-001/tempDir-001/node1/./dv_coll_shard1_replica_n1/data/]
[junit4] 2> 2397501 INFO (qtp195727981-18616) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr
path=/update
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard3_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
(-1580111836064251904)} 0 57
[junit4] 2> 2397505 INFO (qtp2126010706-18614) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr
path=/update
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard3_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
(-1580111836054814720)} 0 70
[junit4] 2> 2397510 INFO (qtp195727981-18619) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr
path=/update params={wt=javabin&version=2}{deleteByQuery=*:*
(-1580111836050620416)} 0 80
[junit4] 2> 2397515 INFO (qtp2126010706-18608) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1580111836139749376,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2397515 INFO (qtp2126010706-18608) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 2397515 INFO (qtp2126010706-18608) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.s.SolrIndexSearcher Opening [Searcher@29f63872[dv_coll_shard4_replica_n6]
main]
[junit4] 2> 2397516 INFO (qtp2126010706-18608) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2397516 INFO (qtp195727981-18617) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1580111836140797952,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2397516 INFO (qtp195727981-18617) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 2397516 INFO (qtp195727981-18617) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4ba24c32[dv_coll_shard3_replica_n4]
main]
[junit4] 2> 2397516 INFO (qtp195727981-18617) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2397516 INFO
(searcherExecutor-8644-thread-1-processing-n:127.0.0.1:48156_solr
x:dv_coll_shard4_replica_n6 s:shard4 c:dv_coll r:core_node8)
[n:127.0.0.1:48156_solr c:dv_coll s:shard4 r:core_node8
x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore [dv_coll_shard4_replica_n6]
Registered new searcher Searcher@29f63872[dv_coll_shard4_replica_n6]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2397516 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1580111836140797952,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2397517 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 2397517 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2397517 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 2397517 INFO (qtp2126010706-18608) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 2
[junit4] 2> 2397519 INFO
(searcherExecutor-8639-thread-1-processing-n:127.0.0.1:62879_solr
x:dv_coll_shard3_replica_n4 s:shard3 c:dv_coll r:core_node7)
[n:127.0.0.1:62879_solr c:dv_coll s:shard3 r:core_node7
x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4]
Registered new searcher Searcher@4ba24c32[dv_coll_shard3_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2397519 INFO (qtp195727981-18617) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 3
[junit4] 2> 2397520 INFO (qtp2126010706-18612) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1580111836144992256,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2397520 INFO (qtp2126010706-18612) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 2397520 INFO (qtp2126010706-18612) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3c6b6c7e[dv_coll_shard2_replica_n2]
main]
[junit4] 2> 2397520 INFO (qtp2126010706-18612) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2397525 INFO
(searcherExecutor-8637-thread-1-processing-n:127.0.0.1:48156_solr
x:dv_coll_shard2_replica_n2 s:shard2 c:dv_coll r:core_node5)
[n:127.0.0.1:48156_solr c:dv_coll s:shard2 r:core_node5
x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2]
Registered new searcher Searcher@3c6b6c7e[dv_coll_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2397525 INFO (qtp2126010706-18612) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 5
[junit4] 2> 2397526 INFO (qtp195727981-18620) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr
path=/update
params={_stateVer_=dv_coll:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 12
[junit4] 2> 2397531 INFO (qtp195727981-18623) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr
path=/update params={_stateVer_=dv_coll:4&wt=javabin&version=2}{add=[1
(1580111836154429440)]} 0 1
[junit4] 2> 2397532 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1580111836157575168,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2397532 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 2397532 INFO (qtp2126010706-18610) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1580111836157575168,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2397532 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2397532 INFO (qtp2126010706-18610) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 2397533 INFO (qtp2126010706-18613) [n:127.0.0.1:48156_solr
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 2397533 INFO (qtp2126010706-18610) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2397533 INFO (qtp195727981-18616) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1580111836158623744,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2397533 INFO (qtp195727981-18616) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@47d237df
commitCommandVersion:1580111836158623744
[junit4] 2> 2397533 INFO (qtp2126010706-18610) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 2397537 INFO (qtp195727981-18619) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1580111836162818048,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2397537 INFO (qtp195727981-18619) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 2397609 INFO (qtp195727981-18619) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2397609 INFO (qtp195727981-18619) [n:127.0.0.1:62879_solr
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 76
[junit4] 2> 2397551 INFO (qtp195727981-18616) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3bf439b4[dv_coll_shard1_replica_n1]
main]
[junit4] 2> 2397610 INFO (qtp195727981-18616) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2397612 INFO
(searcherExecutor-8657-thread-1-processing-n:127.0.0.1:62879_solr
x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node3)
[n:127.0.0.1:62879_solr c:dv_coll s:shard1 r:core_node3
x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1]
Registered new searcher Searcher@3bf439b4[dv_coll_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))}
[junit4] 2> 2397612 INFO (qtp195727981-18616) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62879/solr/dv_coll_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 80
[junit4] 2> 2397613 INFO (qtp195727981-18622) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr
path=/update
params={_stateVer_=dv_coll:4&commit=true&wt=javabin&version=2}{commit=} 0 81
[junit4] 2> 2397617 WARN (qtp2126010706-18614) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.r.SimpleFacets Raising facet.mincount from 0 to 1, because field intField
is Points-based.
[junit4] 2> 2397617 WARN (qtp2126010706-18614) [n:127.0.0.1:48156_solr
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2]
o.a.s.r.SimpleFacets Raising facet.mincount from 0 to 1, because field
longField is Points-based.
[junit4] 2> 2397621 WARN (qtp195727981-18617) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.r.SimpleFacets Raising facet.mincount from 0 to 1, because field intField
is Points-based.
[junit4] 2> 2397622 WARN (qtp195727981-18617) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1]
o.a.s.r.SimpleFacets Raising facet.mincount from 0 to 1, because field
longField is Points-based.
[junit4] 2> 2397622 WARN (qtp195727981-18617) [n:127.0.0.1:62879_solr
c:dv_coll s:shard1 r:cor
[...truncated too long message...]
riter, TermsComponentTest, GraphQueryTest, TestRebalanceLeaders,
DocValuesMultiTest, BufferStoreTest, TestNamedUpdateProcessors,
TestLeaderInitiatedRecoveryThread, TestMacroExpander, BasicFunctionalityTest,
TestApiFramework, AnalyticsQueryTest, DocumentBuilderTest, ConvertedLegacyTest,
LeaderFailoverAfterPartitionTest, TestInfoStreamLogging, HdfsRecoveryZkTest,
TestComponentsName, SegmentsInfoRequestHandlerTest,
TestExclusionRuleCollectionAccess, TestLRUCache,
DistributedIntervalFacetingTest, OverriddenZkACLAndCredentialsProvidersTest,
HdfsLockFactoryTest, AtomicUpdateProcessorFactoryTest, TestReqParamsAPI,
CdcrVersionReplicationTest, TestNumericTerms64,
ParsingFieldUpdateProcessorsTest, ShufflingReplicaListTransformerTest,
TestSystemCollAutoCreate, TestSolrDeletionPolicy1,
TestSchemalessBufferedUpdates, AsyncCallRequestStatusResponseTest,
DistributedQueryComponentCustomSortTest, TestSerializedLuceneMatchVersion,
TestManagedResourceStorage, BooleanFieldTest, JsonLoaderTest, PluginInfoTest,
SubstringBytesRefFilterTest, SignatureUpdateProcessorFactoryTest,
TestSolrCloudWithDelegationTokens, TestSchemaNameResource,
TestShardHandlerFactory, DocValuesMissingTest, SpellPossibilityIteratorTest,
TestPullReplica, TestNonDefinedSimilarityFactory, QueryResultKeyTest,
TestLegacyField, SchemaVersionSpecificBehaviorTest, ExternalFileFieldSortTest,
TestTlogReplica, TestFastWriter, PropertiesRequestHandlerTest,
PreAnalyzedFieldTest, TestBM25SimilarityFactory, TestReload,
LeaderElectionIntegrationTest, ShardRoutingTest, ClusterStateUpdateTest,
TestRandomFaceting, LeaderElectionTest, ShardRoutingCustomTest, AutoCommitTest,
TestDistributedGrouping, TestRecovery, TestHashPartitioner, TestStressReorder,
HardAutoCommitTest, TestCoreContainer, SolrCmdDistributorTest, PeerSyncTest,
TestBadConfig, SoftAutoCommitTest, PolyFieldTest,
WordBreakSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest,
TestAtomicUpdateErrorCases, StatelessScriptUpdateProcessorFactoryTest,
IndexBasedSpellCheckerTest, TestQueryUtils, TestWriterPerf,
TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, RequestHandlersTest,
TestIndexingPerformance, FieldAnalysisRequestHandlerTest, ReturnFieldsTest,
TestAnalyzedSuggestions, BinaryUpdateRequestHandlerTest,
PingRequestHandlerTest, HighlighterConfigTest, TestQuerySenderListener,
TestSolrIndexConfig, CopyFieldTest, TestStressRecovery, ScriptEngineTest,
DateMathParserTest, SpellingQueryConverterTest, RAMDirectoryFactoryTest,
TestUtils, ZkNodePropsTest, SystemInfoHandlerTest, SolrTestCaseJ4Test,
TestDistributedMissingSort, ActionThrottleTest,
AssignBackwardCompatibilityTest, CdcrBootstrapTest,
CloudExitableDirectoryReaderTest, CollectionReloadTest,
ConcurrentDeleteAndCreateCollectionTest, ConfigSetsAPITest,
ConnectionManagerTest, CreateCollectionCleanupTest, CustomCollectionTest,
DeleteReplicaTest, DeleteStatusTest, DistribDocExpirationUpdateProcessorTest,
DocValuesNotIndexedTest, LeaderElectionContextKeyTest,
LeaderFailureAfterFreshStartTest, MigrateRouteKeyTest,
MoveReplicaHDFSUlogDirTest, MultiThreadedOCPTest,
OverseerCollectionConfigSetProcessorTest, OverseerStatusTest,
PeerSyncReplicationTest, ReplicationFactorTest, RestartWhileUpdatingTest,
RollingRestartTest, SolrXmlInZkTest, TestAuthenticationFramework,
TestCloudPseudoReturnFields, TestClusterProperties, TestCollectionAPI,
TestCollectionsAPIViaSolrCloudCluster, TestDeleteCollectionOnDownNodes,
TestLockTree, TestPrepRecovery, TestRequestForwarding, TestSSLRandomization,
TestSegmentSorting, TestSolrCloudWithSecureImpersonation]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestSolrCloudWithSecureImpersonation -Dtests.seed=B44C3FCFFA90B617
-Dtests.slow=true -Dtests.locale=ms-MY -Dtests.timezone=America/Indiana/Vevay
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestSolrCloudWithSecureImpersonation (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:
[junit4] > 1) Thread[id=23031,
name=jetty-launcher-3705-thread-1-EventThread, state=TIMED_WAITING,
group=TGRP-TestSolrCloudWithSecureImpersonation]
[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.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] > at
java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] > at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] > at
org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] > at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] > at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] > at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] > at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] > at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] > at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] > at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] > 2) Thread[id=23041,
name=jetty-launcher-3705-thread-2-EventThread, state=TIMED_WAITING,
group=TGRP-TestSolrCloudWithSecureImpersonation]
[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.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] > at
java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] > at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] > at
org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] > at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] > at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] > at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] > at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] > at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] > at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] > at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] > at
__randomizedtesting.SeedInfo.seed([B44C3FCFFA90B617]:0)
[junit4] Completed [565/734 (2!)] on J1 in 45.78s, 12 tests, 1 error <<<
FAILURES!
[...truncated 37788 lines...]
-documentation-lint:
[echo] checking for broken html...
[jtidy] Checking for broken html (such as invalid tags)...
[delete] Deleting directory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/build/jtidy_tmp
[echo] Checking for broken links...
[exec]
[exec] Crawl/parse...
[exec]
[exec] Verify...
[exec]
[exec]
file:///build/docs/sandbox/org/apache/lucene/document/FloatPointNearestNeighbor.html
[exec] BROKEN LINK:
file:///build/docs/core/org/apache/lucene/document/FloatPointNearestNeighbor.NearestHit.html
[exec] BROKEN LINK:
file:///build/docs/core/org/apache/lucene/document/FloatPointNearestNeighbor.NearestHit.html
[exec]
[exec] Broken javadocs links were found! Common root causes:
[exec] * A typo of some sort for manually created links.
[exec] * Public methods referencing non-public classes in their signature.
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/build.xml:826: The
following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/build.xml:101: The
following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/build.xml:142:
The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/build.xml:155:
The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/common-build.xml:2570:
exec returned: 1
Total time: 89 minutes 27 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
