Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1379/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC

2 tests failed.
FAILED:  org.apache.solr.handler.admin.MetricsHandlerTest.testPropertyFilter

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([4750D601AC57E4A7:253D284063D98499]:0)
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNotNull(Assert.java:526)
        at org.junit.Assert.assertNotNull(Assert.java:537)
        at 
org.apache.solr.handler.admin.MetricsHandlerTest.testPropertyFilter(MetricsHandlerTest.java:201)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testArraySort

Error Message:
--> http://127.0.0.1:37742/solr/collection1:Invalid stream expression 
arraySort(array(11.5,12.3,4,3,1,0)) - function 'arraySort' is unknown (not 
mapped to a valid TupleStream)

Stack Trace:
java.io.IOException: --> http://127.0.0.1:37742/solr/collection1:Invalid stream 
expression arraySort(array(11.5,12.3,4,3,1,0)) - function 'arraySort' is 
unknown (not mapped to a valid TupleStream)
        at 
__randomizedtesting.SeedInfo.seed([F71DED6EFEB11E6E:C1AB3B437968E217]:0)
        at 
org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:219)
        at 
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.getTuples(StreamExpressionTest.java:7487)
        at 
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testArraySort(StreamExpressionTest.java:5840)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12767 lines...]
   [junit4] Suite: org.apache.solr.handler.admin.MetricsHandlerTest
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.admin.MetricsHandlerTest_4750D601AC57E4A7-001/init-core-data-001
   [junit4]   2> 2439592 WARN  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 2439593 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 2439594 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 2439595 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2439596 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2439639 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2439665 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 2439749 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2439804 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@248b4dff
   [junit4]   2> 2439804 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@248b4dff
   [junit4]   2> 2439809 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@248b4dff
   [junit4]   2> 2439810 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2439842 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2439869 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2439973 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2439979 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 2439980 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@248b4dff
   [junit4]   2> 2439980 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2439980 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.admin.MetricsHandlerTest_4750D601AC57E4A7-001/init-core-data-001/]
   [junit4]   2> 2439981 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=42, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=54.2919921875, 
floorSegmentMB=1.8828125, forceMergeDeletesPctAllowed=0.4290338019962814, 
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2440099 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2440099 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2440101 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2440101 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2440102 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=44, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=96.5458984375, 
floorSegmentMB=0.6943359375, forceMergeDeletesPctAllowed=25.881177651723718, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 2440103 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@21394f6f[collection1] main]
   [junit4]   2> 2440104 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2440112 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2440128 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2440128 INFO  (coreLoadExecutor-11004-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1570574925589118976
   [junit4]   2> 2440131 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2440136 INFO  
(TEST-MetricsHandlerTest.testPropertyFilter-seed#[4750D601AC57E4A7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testPropertyFilter
   [junit4]   2> 2440137 INFO  
(TEST-MetricsHandlerTest.testPropertyFilter-seed#[4750D601AC57E4A7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testPropertyFilter
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=MetricsHandlerTest 
-Dtests.method=testPropertyFilter -Dtests.seed=4750D601AC57E4A7 
-Dtests.slow=true -Dtests.locale=th-TH -Dtests.timezone=Pacific/Chatham 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.01s J1 | MetricsHandlerTest.testPropertyFilter <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([4750D601AC57E4A7:253D284063D98499]:0)
   [junit4]    >        at 
org.apache.solr.handler.admin.MetricsHandlerTest.testPropertyFilter(MetricsHandlerTest.java:201)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 2440144 INFO  
(TEST-MetricsHandlerTest.test-seed#[4750D601AC57E4A7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 2440147 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2440150 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2440150 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2440158 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2440171 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2440172 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2440172 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2440173 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2440174 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2440175 INFO  
(TEST-MetricsHandlerTest.test-seed#[4750D601AC57E4A7]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 2440177 INFO  
(searcherExecutor-11005-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@21394f6f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2440244 INFO  
(TEST-MetricsHandlerTest.test-seed#[4750D601AC57E4A7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 2440250 INFO  
(TEST-MetricsHandlerTest.testCompact-seed#[4750D601AC57E4A7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testCompact
   [junit4]   2> 2440260 INFO  
(TEST-MetricsHandlerTest.testCompact-seed#[4750D601AC57E4A7]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testCompact
   [junit4]   2> 2440260 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2440260 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1992179836
   [junit4]   2> 2440260 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 2440266 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 2440267 INFO  
(SUITE-MetricsHandlerTest-seed#[4750D601AC57E4A7]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2440268 INFO  (coreCloseExecutor-11010-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7bda9a92
   [junit4]   2> 2440268 INFO  (coreCloseExecutor-11010-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=2077923986
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.admin.MetricsHandlerTest_4750D601AC57E4A7-001
   [junit4]   2> NOTE: test params are: codec=Lucene70, 
sim=RandomSimilarity(queryNorm=false): {}, locale=th-TH, 
timezone=Pacific/Chatham
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=217921960,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [DistanceFunctionTest, 
TestAtomicUpdateErrorCases, ChaosMonkeySafeLeaderTest, 
ExitableDirectoryReaderTest, TestCodecSupport, CustomCollectionTest, 
SolrJmxReporterTest, TestQueryTypes, TestNestedDocsSort, UtilsToolTest, 
RequestLoggingTest, ConvertedLegacyTest, SmileWriterTest, TestSearchPerf, 
TestScoreJoinQPScore, TestQueryWrapperFilter, DirectUpdateHandlerOptimizeTest, 
TestQueryUtils, TestReplicaProperties, TestSolrCLIRunExample, 
SpellCheckCollatorTest, TestSolrCloudSnapshots, PeerSyncReplicationTest, 
DocValuesNotIndexedTest, TestLMJelinekMercerSimilarityFactory, TestLockTree, 
TimeZoneUtilsTest, TestUninvertingReader, TestStressCloudBlindAtomicUpdates, 
SolrCloudExampleTest, TestUseDocValuesAsStored2, TestPivotHelperCode, 
MoveReplicaTest, CloudMLTQParserTest, TestAddFieldRealTimeGet, 
TestSchemaResource, TestDynamicFieldResource, TestFieldCacheReopen, 
TestZkChroot, DistributedTermsComponentTest, TestSolr4Spatial, 
SpatialRPTFieldTypeTest, TestRecoveryHdfs, LeaderFailoverAfterPartitionTest, 
TestLeaderInitiatedRecoveryThread, HdfsBasicDistributedZkTest, 
HdfsSyncSliceTest, BlockDirectoryTest, TestAuthenticationFramework, 
TestCustomDocTransformer, TestNoOpRegenerator, TestCustomSort, 
TestQuerySenderListener, FieldAnalysisRequestHandlerTest, 
LeaderElectionIntegrationTest, TestWriterPerf, JavabinLoaderTest, 
TestRangeQuery, JvmMetricsTest, DistanceUnitsTest, 
OverriddenZkACLAndCredentialsProvidersTest, TestFieldCacheSortRandom, 
BasicFunctionalityTest, TestLuceneMatchVersion, TestPointFields, 
TestCrossCoreJoin, BigEndianAscendingWordDeserializerTest, TestSolrIndexConfig, 
RollingRestartTest, TestJsonFacets, TestConfigSetsAPIExclusivity, 
TestNonDefinedSimilarityFactory, SpellCheckComponentTest, 
CollectionsAPIAsyncDistributedZkTest, DebugComponentTest, NodeMutatorTest, 
BaseCdcrDistributedZkTest, TestSolrDeletionPolicy2, 
SpellPossibilityIteratorTest, TestOrdValues, SolrIndexMetricsTest, 
OverseerModifyCollectionTest, TestDFRSimilarityFactory, TestSolrQueryResponse, 
HLLUtilTest, BasicDistributedZk2Test, UUIDFieldTest, CacheHeaderTest, TestTrie, 
TestDistribDocBasedVersion, HttpSolrCallGetCoreTest, StatsComponentTest, 
SolrCloudReportersTest, TestSchemalessBufferedUpdates, 
TestAnalyzeInfixSuggestions, BadIndexSchemaTest, ChangedSchemaMergeTest, 
HdfsRecoveryZkTest, TestFoldingMultitermQuery, OverseerStatusTest, 
TestCollectionAPI, HdfsNNFailoverTest, TestDocumentBuilder, 
SecurityConfHandlerTest, TestClusterStateMutator, BasicZkTest, 
TestInPlaceUpdatesDistrib, TestInfoStreamLogging, TestGeoJSONResponseWriter, 
TestShardHandlerFactory, CoreAdminCreateDiscoverTest, 
TestRandomRequestDistribution, SolrCoreMetricManagerTest, 
FullSolrCloudDistribCmdsTest, VMParamsZkACLAndCredentialsProvidersTest, 
TestExceedMaxTermLength, TestIndexSearcher, DistributedQueueTest, 
FacetPivotSmallTest, SolrCoreCheckLockOnStartupTest, RegexBoostProcessorTest, 
SliceStateTest, TestNumericRangeQuery32, ShardRoutingCustomTest, 
LargeFieldTest, JsonLoaderTest, TestLegacyTerms, BlockJoinFacetRandomTest, 
CachingDirectoryFactoryTest, StatsReloadRaceTest, URLClassifyProcessorTest, 
SimpleCollectionCreateDeleteTest, TestReversedWildcardFilterFactory, 
MetricUtilsTest, TestFastLRUCache, TestSolrConfigHandlerConcurrent, 
TestTrieFacet, TestCloudInspectUtil, CoreAdminHandlerTest, 
TestNumericTokenStream, FileBasedSpellCheckerTest, TestConfigOverlay, 
TestCoreDiscovery, SharedFSAutoReplicaFailoverTest, TestReload, 
TestManagedSchemaAPI, TermVectorComponentTest, 
ClassificationUpdateProcessorIntegrationTest, TestRawResponseWriter, 
HdfsDirectoryTest, RuleEngineTest, TestIndexingPerformance, 
DefaultValueUpdateProcessorTest, OpenCloseCoreStressTest, 
TestCloudManagedSchema, TestSolrCoreProperties, WordBreakSolrSpellCheckerTest, 
HttpPartitionTest, TestClassicSimilarityFactory, SolrCmdDistributorTest, 
PKIAuthenticationIntegrationTest, GraphQueryTest, TestSolrCloudWithKerberosAlt, 
TestHashQParserPlugin, AssignTest, TestAnalyzedSuggestions, 
TestPHPSerializedResponseWriter, TestUniqueKeyFieldResource, 
DeleteLastCustomShardedReplicaTest, CheckHdfsIndexTest, BadCopyFieldTest, 
PrimUtilsTest, ClusterStateTest, SuggesterTSTTest, SpatialHeatmapFacetsTest, 
EchoParamsTest, CollectionsAPIDistributedZkTest, OverseerTest, RecoveryZkTest, 
TestReplicationHandler, TestRandomFaceting, LeaderElectionTest, 
ZkSolrClientTest, TestRandomDVFaceting, ZkCLITest, TestDistributedSearch, 
AutoCommitTest, TestDistributedGrouping, TestRecovery, 
TermVectorComponentDistributedTest, TestRealTimeGet, TestJoin, 
TestGroupingSearch, QueryElevationComponentTest, PeerSyncTest, TestFiltering, 
TestBadConfig, SoftAutoCommitTest, StandardRequestHandlerTest, 
PrimitiveFieldTypeTest, TermsComponentTest, RequestHandlersTest, 
TestValueSourceCache, FastVectorHighlighterTest, SolrPluginUtilsTest, 
IndexSchemaTest, MBeansHandlerTest, BinaryUpdateRequestHandlerTest, 
CSVRequestHandlerTest, HighlighterConfigTest, UpdateParamsTest, 
TestQuerySenderNoQuery, ResponseLogComponentTest, SolrIndexConfigTest, 
MultiTermTest, TestDocSet, MinimalSchemaTest, TestConfig, 
TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, 
TestPostingsSolrHighlighter, DirectSolrConnectionTest, 
NotRequiredUniqueKeyTest, SynonymTokenizerTest, TestXIncludeConfig, 
TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, 
TestBM25SimilarityFactory, TestFastWriter, ResourceLoaderTest, 
ScriptEngineTest, OpenExchangeRatesOrgProviderTest, PluginInfoTest, 
DateMathParserTest, ChaosMonkeyNothingIsSafeTest, TestHighlightDedupGrouping, 
TestSimpleTrackingShardHandler, ActionThrottleTest, CdcrBootstrapTest, 
ChaosMonkeySafeLeaderWithPullReplicasTest, CollectionStateFormat2Test, 
ConcurrentDeleteAndCreateCollectionTest, ConfigSetsAPITest, 
ConnectionManagerTest, DeleteInactiveReplicaTest, DeleteReplicaTest, 
DistribJoinFromCollectionTest, LeaderElectionContextKeyTest, 
LeaderFailureAfterFreshStartTest, LeaderInitiatedRecoveryOnShardRestartTest, 
MigrateRouteKeyTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
OverseerCollectionConfigSetProcessorTest, RecoveryAfterSoftCommitTest, 
RemoteQueryErrorTest, ReplicationFactorTest, SSLMigrationTest, 
SaslZkACLProviderTest, ShardSplitTest, TestSSLRandomization, 
TestSegmentSorting, TestSolrCloudWithSecureImpersonation, TestStressLiveNodes, 
TestTolerantUpdateProcessorCloud, TestPolicyCloud, 
HdfsRestartWhileUpdatingTest, HdfsTlogReplayBufferedWhileIndexingTest, 
HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, 
StressHdfsTest, TestInitParams, TestNRTOpen, TestSimpleTextCodec, 
TestSolrConfigHandler, TestSolrXml, TestConfigReload, 
TestHdfsBackupRestoreCore, TestSystemCollAutoCreate, V2ApiIntegrationTest, 
V2StandaloneTest, CoreMergeIndexesAdminHandlerTest, MetricsHandlerTest]
   [junit4] Completed [585/725 (1!)] on J1 in 0.74s, 3 tests, 1 failure <<< 
FAILURES!

[...truncated 1248 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/init-core-data-001
   [junit4]   2> 138865 WARN  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
   [junit4]   2> 138865 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 138866 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-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> 138866 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/tempDir-001
   [junit4]   2> 138866 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 138866 INFO  (Thread-347) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 138866 INFO  (Thread-347) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 138886 ERROR (Thread-347) [    ] 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> 138969 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:55662
   [junit4]   2> 138976 INFO  (jetty-launcher-688-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 138977 INFO  (jetty-launcher-688-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 138978 INFO  (jetty-launcher-688-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 138978 INFO  (jetty-launcher-688-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@74c65080{/solr,null,AVAILABLE}
   [junit4]   2> 138978 INFO  (jetty-launcher-688-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 138980 INFO  (jetty-launcher-688-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6b57176b{/solr,null,AVAILABLE}
   [junit4]   2> 138981 INFO  (jetty-launcher-688-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@41d00c55{/solr,null,AVAILABLE}
   [junit4]   2> 138983 INFO  (jetty-launcher-688-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@585feac1{/solr,null,AVAILABLE}
   [junit4]   2> 138985 INFO  (jetty-launcher-688-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2d41c690{HTTP/1.1,[http/1.1]}{127.0.0.1:37742}
   [junit4]   2> 138985 INFO  (jetty-launcher-688-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@45344ea4{HTTP/1.1,[http/1.1]}{127.0.0.1:56995}
   [junit4]   2> 138985 INFO  (jetty-launcher-688-thread-3) [    ] 
o.e.j.s.Server Started @141677ms
   [junit4]   2> 138985 INFO  (jetty-launcher-688-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56995}
   [junit4]   2> 138985 ERROR (jetty-launcher-688-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 138986 INFO  (jetty-launcher-688-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 138986 INFO  (jetty-launcher-688-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 138986 INFO  (jetty-launcher-688-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 138986 INFO  (jetty-launcher-688-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-18T20:21:39.460Z
   [junit4]   2> 138987 INFO  (jetty-launcher-688-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@59936e1b{HTTP/1.1,[http/1.1]}{127.0.0.1:38371}
   [junit4]   2> 138987 INFO  (jetty-launcher-688-thread-4) [    ] 
o.e.j.s.Server Started @141679ms
   [junit4]   2> 138987 INFO  (jetty-launcher-688-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=38371}
   [junit4]   2> 138987 ERROR (jetty-launcher-688-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 138987 INFO  (jetty-launcher-688-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 138987 INFO  (jetty-launcher-688-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 138987 INFO  (jetty-launcher-688-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 138987 INFO  (jetty-launcher-688-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-18T20:21:39.461Z
   [junit4]   2> 138990 INFO  (jetty-launcher-688-thread-1) [    ] 
o.e.j.s.Server Started @141677ms
   [junit4]   2> 138990 INFO  (jetty-launcher-688-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=37742}
   [junit4]   2> 138990 ERROR (jetty-launcher-688-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 138992 INFO  (jetty-launcher-688-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@71ef6974{HTTP/1.1,[http/1.1]}{127.0.0.1:51662}
   [junit4]   2> 138994 INFO  (jetty-launcher-688-thread-2) [    ] 
o.e.j.s.Server Started @141686ms
   [junit4]   2> 138995 INFO  (jetty-launcher-688-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=51662}
   [junit4]   2> 138995 ERROR (jetty-launcher-688-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 138995 INFO  (jetty-launcher-688-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 138994 INFO  (jetty-launcher-688-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 138995 INFO  (jetty-launcher-688-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 138995 INFO  (jetty-launcher-688-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 138995 INFO  (jetty-launcher-688-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 138995 INFO  (jetty-launcher-688-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-18T20:21:39.469Z
   [junit4]   2> 138995 INFO  (jetty-launcher-688-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 138996 INFO  (jetty-launcher-688-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-18T20:21:39.470Z
   [junit4]   2> 138997 INFO  (jetty-launcher-688-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 139003 INFO  (jetty-launcher-688-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 139003 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x15cbcde39900002, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 139004 INFO  (jetty-launcher-688-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 139006 INFO  (jetty-launcher-688-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55662/solr
   [junit4]   2> 139009 INFO  (jetty-launcher-688-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 139017 INFO  (jetty-launcher-688-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55662/solr
   [junit4]   2> 139019 INFO  (jetty-launcher-688-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55662/solr
   [junit4]   2> 139020 INFO  (jetty-launcher-688-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55662/solr
   [junit4]   2> 139070 INFO  (jetty-launcher-688-thread-4) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 139071 INFO  (jetty-launcher-688-thread-3) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 139071 INFO  (jetty-launcher-688-thread-1) 
[n:127.0.0.1:37742_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 139071 INFO  (jetty-launcher-688-thread-2) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 139072 INFO  (jetty-launcher-688-thread-4) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:38371_solr
   [junit4]   2> 139073 INFO  (jetty-launcher-688-thread-3) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56995_solr
   [junit4]   2> 139073 INFO  (jetty-launcher-688-thread-1) 
[n:127.0.0.1:37742_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37742_solr
   [junit4]   2> 139074 INFO  (jetty-launcher-688-thread-2) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:51662_solr
   [junit4]   2> 139074 INFO  (jetty-launcher-688-thread-4) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.Overseer Overseer 
(id=98160954529808393-127.0.0.1:38371_solr-n_0000000000) starting
   [junit4]   2> 139077 INFO  
(zkCallback-708-thread-1-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 139078 INFO  
(zkCallback-707-thread-1-processing-n:127.0.0.1:38371_solr) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 139081 INFO  
(zkCallback-710-thread-1-processing-n:127.0.0.1:56995_solr) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 139085 INFO  
(zkCallback-709-thread-1-processing-n:127.0.0.1:37742_solr) 
[n:127.0.0.1:37742_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 139098 INFO  
(zkCallback-708-thread-1-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 139115 INFO  
(zkCallback-707-thread-1-processing-n:127.0.0.1:38371_solr) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 139150 INFO  (jetty-launcher-688-thread-4) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:38371_solr
   [junit4]   2> 139152 INFO  
(zkCallback-708-thread-1-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 139152 INFO  
(zkCallback-709-thread-1-processing-n:127.0.0.1:37742_solr) 
[n:127.0.0.1:37742_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 139159 INFO  
(zkCallback-707-thread-1-processing-n:127.0.0.1:38371_solr) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 139159 INFO  
(zkCallback-710-thread-1-processing-n:127.0.0.1:56995_solr) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 139200 INFO  (jetty-launcher-688-thread-1) 
[n:127.0.0.1:37742_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37742.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139207 INFO  (jetty-launcher-688-thread-1) 
[n:127.0.0.1:37742_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37742.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139207 INFO  (jetty-launcher-688-thread-1) 
[n:127.0.0.1:37742_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37742.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139208 INFO  (jetty-launcher-688-thread-1) 
[n:127.0.0.1:37742_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/tempDir-001/node1/.
   [junit4]   2> 139242 INFO  (jetty-launcher-688-thread-3) 
[n:127.0.0.1:56995_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56995.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139245 INFO  (jetty-launcher-688-thread-3) 
[n:127.0.0.1:56995_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56995.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139245 INFO  (jetty-launcher-688-thread-3) 
[n:127.0.0.1:56995_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56995.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139246 INFO  (jetty-launcher-688-thread-3) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/tempDir-001/node2/.
   [junit4]   2> 139305 INFO  (jetty-launcher-688-thread-2) 
[n:127.0.0.1:51662_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51662.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139308 INFO  (jetty-launcher-688-thread-2) 
[n:127.0.0.1:51662_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51662.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139308 INFO  (jetty-launcher-688-thread-2) 
[n:127.0.0.1:51662_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51662.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139309 INFO  (jetty-launcher-688-thread-2) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/tempDir-001/node4/.
   [junit4]   2> 139317 INFO  (jetty-launcher-688-thread-4) 
[n:127.0.0.1:38371_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38371.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139320 INFO  (jetty-launcher-688-thread-4) 
[n:127.0.0.1:38371_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38371.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139320 INFO  (jetty-launcher-688-thread-4) 
[n:127.0.0.1:38371_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38371.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 139321 INFO  (jetty-launcher-688-thread-4) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/tempDir-001/node3/.
   [junit4]   2> 139357 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 139358 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55662/solr ready
   [junit4]   2> 139370 INFO  (qtp1044401176-2499) [n:127.0.0.1:51662_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 139374 INFO  
(OverseerThreadFactory-894-thread-1-processing-n:127.0.0.1:38371_solr) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
collection1_collection
   [junit4]   2> 139484 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 139484 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 139484 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 139484 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 139595 INFO  
(zkCallback-710-thread-1-processing-n:127.0.0.1:56995_solr) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 139595 INFO  
(zkCallback-708-thread-1-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 140506 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 140506 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 140527 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.s.IndexSchema [collection1_collection_shard2_replica_n1] Schema name=test
   [junit4]   2> 140533 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.s.IndexSchema [collection1_collection_shard1_replica_n1] Schema name=test
   [junit4]   2> 140596 WARN  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 140596 WARN  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 140596 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 140596 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 
'collection1_collection_shard2_replica_n1' using configuration from collection 
collection1_collection, trusted=true
   [junit4]   2> 140597 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51662.solr.core.collection1_collection.shard2.replica_n1' (registry 
'solr.core.collection1_collection.shard2.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 140597 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 140597 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.SolrCore [[collection1_collection_shard2_replica_n1] ] Opening new 
SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/tempDir-001/node4/collection1_collection_shard2_replica_n1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/tempDir-001/node4/./collection1_collection_shard2_replica_n1/data/]
   [junit4]   2> 140601 WARN  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 140601 WARN  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 140602 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 140602 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 
'collection1_collection_shard1_replica_n1' using configuration from collection 
collection1_collection, trusted=true
   [junit4]   2> 140602 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56995.solr.core.collection1_collection.shard1.replica_n1' (registry 
'solr.core.collection1_collection.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3ae5cb7
   [junit4]   2> 140602 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 140603 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.SolrCore [[collection1_collection_shard1_replica_n1] ] Opening new 
SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/tempDir-001/node2/collection1_collection_shard1_replica_n1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001/tempDir-001/node2/./collection1_collection_shard1_replica_n1/data/]
   [junit4]   2> 140661 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 140661 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 140662 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 140662 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 140663 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@472fe7ac[collection1_collection_shard2_replica_n1] main]
   [junit4]   2> 140663 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 140663 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 140665 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 140665 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 140666 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 140666 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 140666 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 140666 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1570575274360176640
   [junit4]   2> 140667 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@621a112c[collection1_collection_shard1_replica_n1] main]
   [junit4]   2> 140670 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 140670 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 140671 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 140673 INFO  
(searcherExecutor-904-thread-1-processing-n:127.0.0.1:56995_solr 
x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection) 
[n:127.0.0.1:56995_solr c:collection1_collection s:shard1  
x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica_n1] Registered new searcher 
Searcher@621a112c[collection1_collection_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 140673 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1570575274367516672
   [junit4]   2> 140673 INFO  
(searcherExecutor-903-thread-1-processing-n:127.0.0.1:51662_solr 
x:collection1_collection_shard2_replica_n1 s:shard2 c:collection1_collection) 
[n:127.0.0.1:51662_solr c:collection1_collection s:shard2  
x:collection1_collection_shard2_replica_n1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica_n1] Registered new searcher 
Searcher@472fe7ac[collection1_collection_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 140674 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 140674 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 140674 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:51662/solr/collection1_collection_shard2_replica_n1/
   [junit4]   2> 140674 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 140674 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:51662/solr/collection1_collection_shard2_replica_n1/ has no 
replicas
   [junit4]   2> 140674 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 140680 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:51662/solr/collection1_collection_shard2_replica_n1/ shard2
   [junit4]   2> 140688 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 140688 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 140688 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/
   [junit4]   2> 140688 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 140688 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/ has no 
replicas
   [junit4]   2> 140688 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 140692 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/ shard1
   [junit4]   2> 140797 INFO  
(zkCallback-710-thread-1-processing-n:127.0.0.1:56995_solr) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 140797 INFO  
(zkCallback-708-thread-1-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 140834 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 140837 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1353
   [junit4]   2> 140848 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 140851 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1368
   [junit4]   2> 140856 INFO  (qtp1044401176-2499) [n:127.0.0.1:51662_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> 140963 INFO  
(zkCallback-708-thread-1-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 140963 INFO  
(zkCallback-710-thread-1-processing-n:127.0.0.1:56995_solr) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 141384 INFO  
(OverseerCollectionConfigSetProcessor-98160954529808393-127.0.0.1:38371_solr-n_0000000000)
 [n:127.0.0.1:38371_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> 141863 INFO  (qtp1044401176-2499) [n:127.0.0.1:51662_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=2494
   [junit4]   2> 141864 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1_collection failOnTimeout:true timeout (sec):90
   [junit4]   2> 141864 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: 
collection1_collection
   [junit4]   2> 141865 INFO  (qtp1044401176-2502) [n:127.0.0.1:51662_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params 
collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 141975 INFO  (qtp1044401176-2502) [n:127.0.0.1:51662_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2}
 status=0 QTime=109
   [junit4]   2> 142005 INFO  
(TEST-StreamExpressionTest.testStatsStream-seed#[F71DED6EFEB11E6E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testStatsStream
   [junit4]   2> 142014 INFO  (qtp1044401176-2498) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570575275771559936)} 0 2
   [junit4]   2> 142016 INFO  (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570575275775754240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 142016 INFO  (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 142016 INFO  (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 142016 INFO  (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_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:56995/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 142017 INFO  (qtp2006625070-2510) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570575275776802816,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 142017 INFO  (qtp2006625070-2510) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 142018 INFO  (qtp2006625070-2510) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 142018 INFO  (qtp2006625070-2510) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_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:56995/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 142018 INFO  (qtp2006625070-2512) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570575275767365632),commit=} 0 11
   [junit4]   2> 142020 INFO  (qtp1044401176-2500) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&wt=javabin&version=2}{add=[2 
(1570575275779948544), 3 (1570575275779948545), 5 (1570575275779948546), 6 
(1570575275779948547), 7 (1570575275779948548), 9 (1570575275779948549)]} 0 0
   [junit4]   2> 142026 INFO  (qtp2006625070-2511) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&wt=javabin&version=2}{add=[0 
(1570575275779948544), 4 (1570575275785191424), 1 (1570575275786240000), 8 
(1570575275786240001)]} 0 6
   [junit4]   2> 142032 INFO  (qtp1044401176-2502) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570575275792531456,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 142032 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570575275792531456,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 142033 INFO  (qtp1044401176-2502) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@75d03dbb 
commitCommandVersion:1570575275792531456
   [junit4]   2> 142034 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a229d83 
commitCommandVersion:1570575275792531456
   [junit4]   2> 142053 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@56b7f791[collection1_collection_shard1_replica_n1] main]
   [junit4]   2> 142053 INFO  (qtp1044401176-2502) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@71202915[collection1_collection_shard2_replica_n1] main]
   [junit4]   2> 142053 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 142054 INFO  (qtp1044401176-2502) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 142054 INFO  
(searcherExecutor-904-thread-1-processing-n:127.0.0.1:56995_solr 
x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:56995_solr c:collection1_collection s:shard1 
r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica_n1] Registered new searcher 
Searcher@56b7f791[collection1_collection_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C4)))}
   [junit4]   2> 142055 INFO  
(searcherExecutor-903-thread-1-processing-n:127.0.0.1:51662_solr 
x:collection1_collection_shard2_replica_n1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:51662_solr c:collection1_collection s:shard2 
r:core_node1 x:collection1_collection_shard2_replica_n1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica_n1] Registered new searcher 
Searcher@71202915[collection1_collection_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C6)))}
   [junit4]   2> 142056 INFO  (qtp1044401176-2502) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_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:51662/solr/collection1_collection_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 23
   [junit4]   2> 142056 INFO  (qtp2006625070-2508) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_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:51662/solr/collection1_collection_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 23
   [junit4]   2> 142056 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&commit=true&wt=javabin&version=2}{commit=}
 0 29
   [junit4]   2> 142064 INFO  
(TEST-StreamExpressionTest.testStatsStream-seed#[F71DED6EFEB11E6E]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 142066 INFO  
(TEST-StreamExpressionTest.testStatsStream-seed#[F71DED6EFEB11E6E]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55662/solr ready
   [junit4]   2> 142073 INFO  (qtp2006625070-2506) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1_collection:3&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&collection=collection1_collection&shard.url=http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497817302542&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i}
 hits=4 status=0 QTime=1
   [junit4]   2> 142073 INFO  (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1_collection:3&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&collection=collection1_collection&shard.url=http://127.0.0.1:51662/solr/collection1_collection_shard2_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497817302542&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i}
 hits=6 status=0 QTime=2
   [junit4]   2> 142074 INFO  (qtp1044401176-2498) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1_collection:3&stats=true&rows=0&wt=javabin&version=2&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i}
 hits=10 status=0 QTime=6
   [junit4]   2> 142085 INFO  (qtp1044401176-2499) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/select 
params={distrib=false&df=text&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:51662/solr/collection1_collection_shard2_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497817302556&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i}
 hits=6 status=0 QTime=1
   [junit4]   2> 142091 INFO  (qtp2006625070-2506) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica_n1]  webapp=/solr path=/select 
params={distrib=false&df=text&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497817302556&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i}
 hits=4 status=0 QTime=0
   [junit4]   2> 142092 INFO  (qtp1044401176-2500) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/select 
params={q=*:*&shards=http://127.0.0.1:51662/solr/collection1_collection_shard2_replica_n1/,http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/&distrib=true&stats=true&rows=0&wt=javabin&version=2&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i}
 hits=10 status=0 QTime=9
   [junit4]   2> 142131 INFO  (qtp1044401176-2499) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/stream 
params={myCollection.shards=http://127.0.0.1:51662/solr/collection1_collection_shard2_replica_n1/,http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/&expr=stats(myCollection,+q%3D*:*,+sum(a_i),+sum(a_f),+min(a_i),+min(a_f),+max(a_i),+max(a_f),+avg(a_i),+avg(a_f),+count(*))&wt=json&version=2.2}
 status=0 QTime=1
   [junit4]   2> 142138 INFO  (qtp2006625070-2510) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica_n1]  webapp=/solr path=/select 
params={distrib=false&df=text&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497817302607&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i}
 hits=4 status=0 QTime=3
   [junit4]   2> 142139 INFO  (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/select 
params={distrib=false&df=text&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:51662/solr/collection1_collection_shard2_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497817302607&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i}
 hits=6 status=0 QTime=0
   [junit4]   2> 142140 INFO  (qtp1044401176-2503) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/select 
params={q=*:*&shards=http://127.0.0.1:51662/solr/collection1_collection_shard2_replica_n1/,http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/&distrib=true&stats=true&rows=0&wt=javabin&version=2&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i}
 hits=10 status=0 QTime=6
   [junit4]   2> 142146 INFO  (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/stream 
params={expr=stats(myCollection,+q%3D*:*,+sum(a_i),+sum(a_f),+min(a_i),+min(a_f),+max(a_i),+max(a_f),+avg(a_i),+avg(a_f),+count(*))&wt=json&version=2.2}
 status=0 QTime=0
   [junit4]   2> 142155 INFO  (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.c.ZkStateReader Updated 
live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 142157 INFO  (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55662/solr ready
   [junit4]   2> 142157 ERROR (qtp1044401176-2496) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.c.s.i.s.ExceptionStream 
java.io.IOException: org.apache.solr.common.SolrException: Collection not 
found: myCollection
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.StatsStream.open(StatsStream.java:214)
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.ExceptionStream.open(ExceptionStream.java:51)
   [junit4]   2>        at 
org.apache.solr.handler.StreamHandler$TimerStream.open(StreamHandler.java:558)
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.TupleStream.writeMap(TupleStream.java:83)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter.writeMap(JSONResponseWriter.java:547)
   [junit4]   2>        at 
org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:198)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter.writeNamedListAsMapWithDups(JSONResponseWriter.java:209)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter.writeNamedList(JSONResponseWriter.java:325)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter.writeResponse(JSONResponseWriter.java:120)
   [junit4]   2>        at 
org.apache.solr.response.JSONResponseWriter.write(JSONResponseWriter.java:71)
   [junit4]   2>        at 
org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:65)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.writeResponse(HttpSolrCall.java:808)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:537)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: org.apache.solr.common.SolrException: Collection 
not found: myCollection
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.getCollectionNames(CloudSolrClient.java:1153)
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:836)
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807)
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.StatsStream.open(StatsStream.java:211)
   [junit4]   2>        ... 38 more
   [junit4]   2> 
   [junit4]   2> 142160 INFO  
(TEST-StreamExpressionTest.testStatsStream-seed#[F71DED6EFEB11E6E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testStatsStream
   [junit4]   2> 142214 INFO  
(TEST-StreamExpressionTest.testAnova-seed#[F71DED6EFEB11E6E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testAnova
   [junit4]   2> 142226 INFO  (qtp1044401176-2498) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4952e505[collection1_collection_shard2_replica_n1] realtime]
   [junit4]   2> 142227 INFO  (qtp2006625070-2512) [n:127.0.0.1:56995_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@33ad85c1[collection1_collection_shard1_replica_n1] realtime]
   [junit4]   2> 142227 INFO  (qtp1044401176-2498) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica_n1]  webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:56995/solr/collection1_collection_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570575275984420864)} 0 12
   [junit4]   2> 142229 INFO  (qtp1044401176-2501) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start

[...truncated too long message...]

   2> 208886 INFO  (coreCloseExecutor-1035-thread-1) [n:127.0.0.1:51662_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.core.collection1_collection.shard2.replica_n1, tag=1675861483
   [junit4]   2> 208891 INFO  
(zkCallback-2168-thread-1-processing-n:127.0.0.1:56995_solr 
x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:56995_solr c:collection1_collection s:shard1 
r:core_node2 x:collection1_collection_shard1_replica_n1] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 208894 INFO  
(zkCallback-710-thread-2-processing-n:127.0.0.1:56995_solr) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:56995_solr
   [junit4]   2> 208895 INFO  
(zkCallback-710-thread-1-processing-n:127.0.0.1:56995_solr) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (2)
   [junit4]   2> 208896 WARN  
(zkCallback-707-thread-2-processing-n:127.0.0.1:38371_solr) 
[n:127.0.0.1:38371_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 208896 INFO  (jetty-closer-689-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@585feac1{/solr,null,UNAVAILABLE}
   [junit4]   2> 208897 INFO  
(zkCallback-708-thread-2-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (2)
   [junit4]   2> 208899 INFO  (coreCloseExecutor-1035-thread-1) 
[n:127.0.0.1:51662_solr c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.collection1_collection.shard2.leader, tag=1675861483
   [junit4]   2> 210383 WARN  
(zkCallback-709-thread-1-processing-n:127.0.0.1:37742_solr) 
[n:127.0.0.1:37742_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 210384 INFO  (jetty-closer-689-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@74c65080{/solr,null,UNAVAILABLE}
   [junit4]   2> 210395 WARN  
(zkCallback-2168-thread-1-processing-n:127.0.0.1:56995_solr 
x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:56995_solr c:collection1_collection s:shard1 
r:core_node2 x:collection1_collection_shard1_replica_n1] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 210401 INFO  
(zkCallback-708-thread-2-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:51662_solr
   [junit4]   2> 210402 INFO  
(zkCallback-708-thread-1-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 211902 WARN  
(zkCallback-710-thread-2-processing-n:127.0.0.1:56995_solr) 
[n:127.0.0.1:56995_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 211903 INFO  (jetty-closer-689-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@41d00c55{/solr,null,UNAVAILABLE}
   [junit4]   2> 211903 WARN  
(zkCallback-708-thread-3-processing-n:127.0.0.1:51662_solr) 
[n:127.0.0.1:51662_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 211904 INFO  (jetty-closer-689-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6b57176b{/solr,null,UNAVAILABLE}
   [junit4]   2> 211907 ERROR 
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 211907 INFO  
(SUITE-StreamExpressionTest-seed#[F71DED6EFEB11E6E]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:55662 55662
   [junit4]   2> 212704 INFO  (Thread-347) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:55662 55662
   [junit4]   2> 212705 WARN  (Thread-347) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        71      /solr/aliases.json
   [junit4]   2>        71      /solr/clusterprops.json
   [junit4]   2>        9       /solr/configs/conf
   [junit4]   2>        4       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        71      /solr/clusterstate.json
   [junit4]   2>        4       /solr/collections/destination/state.json
   [junit4]   2>        4       
/solr/collections/parallelDestinationCollection/state.json
   [junit4]   2>        4       
/solr/collections/destinationCollection/state.json
   [junit4]   2>        4       /solr/collections/mainCorpus/state.json
   [junit4]   2>        3       
/solr/collections/parallelDestinationCollection1/state.json
   [junit4]   2>        2       
/solr/overseer_elect/election/98160954529808396-127.0.0.1:56995_solr-n_0000000001
   [junit4]   2>        2       
/solr/collections/collection1_collection/state.json
   [junit4]   2>        2       /solr/collections/workQueue/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        71      /solr/live_nodes
   [junit4]   2>        71      /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_F71DED6EFEB11E6E-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{expr_s=BlockTreeOrds(blocksize=128), join2_s=Lucene50(blocksize=128), 
multiDefault=Lucene50(blocksize=128), subject=Lucene50(blocksize=128), 
price_f=Lucene50(blocksize=128), a1_s=Lucene50(blocksize=128), 
a_f=Lucene50(blocksize=128), intDefault=PostingsFormat(name=LuceneFixedGap), 
join1_i=BlockTreeOrds(blocksize=128), a2_s=Lucene50(blocksize=128), 
a_i=BlockTreeOrds(blocksize=128), test_i=Lucene50(blocksize=128), 
join3_i=Lucene50(blocksize=128), field_i=Lucene50(blocksize=128), 
level1_s=BlockTreeOrds(blocksize=128), body_t=Lucene50(blocksize=128), 
s_multi=Lucene50(blocksize=128), level2_s=PostingsFormat(name=LuceneFixedGap), 
col_s=Lucene50(blocksize=128), a_ss=PostingsFormat(name=LuceneFixedGap), 
a_s=Lucene50(blocksize=128), a_t=PostingsFormat(name=LuceneFixedGap), 
id=BlockTreeOrds(blocksize=128), text=Lucene50(blocksize=128), 
timestamp=PostingsFormat(name=LuceneFixedGap), 
i_multi=BlockTreeOrds(blocksize=128), b_ls=Lucene50(blocksize=128), 
test_dt=BlockTreeOrds(blocksize=128), test_t=Lucene50(blocksize=128), 
ident_s=Lucene50(blocksize=128), order_i=Lucene50(blocksize=128), 
_version_=PostingsFormat(name=LuceneFixedGap), side_s=Lucene50(blocksize=128), 
miles_i=Lucene50(blocksize=128)}, 
docValues:{expr_s=DocValuesFormat(name=Lucene70), 
join2_s=DocValuesFormat(name=Direct), 
multiDefault=DocValuesFormat(name=Direct), 
price_f=DocValuesFormat(name=Direct), a1_s=DocValuesFormat(name=Direct), 
a_f=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Lucene70), 
join1_i=DocValuesFormat(name=Lucene70), a2_s=DocValuesFormat(name=Memory), 
a_i=DocValuesFormat(name=Lucene70), test_i=DocValuesFormat(name=Direct), 
join3_i=DocValuesFormat(name=Memory), field_i=DocValuesFormat(name=Direct), 
level1_s=DocValuesFormat(name=Lucene70), s_multi=DocValuesFormat(name=Memory), 
level2_s=DocValuesFormat(name=Lucene70), col_s=DocValuesFormat(name=Direct), 
a_ss=DocValuesFormat(name=Lucene70), a_s=DocValuesFormat(name=Memory), 
id=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70), 
i_multi=DocValuesFormat(name=Lucene70), b_ls=DocValuesFormat(name=Direct), 
test_dt=DocValuesFormat(name=Lucene70), ident_s=DocValuesFormat(name=Direct), 
order_i=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Lucene70), 
side_s=DocValuesFormat(name=Memory), miles_i=DocValuesFormat(name=Direct)}, 
maxPointsInLeafNode=201, maxMBSortInHeap=5.7925829133051625, 
sim=RandomSimilarity(queryNorm=true): {}, locale=zh-SG, timezone=GB
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=125731656,total=339738624
   [junit4]   2> NOTE: All tests run in this JVM: [SolrExampleJettyTest, 
CommonAdminParamsTest, DocumentAnalysisResponseTest, TestNamedListCodec, 
SolrParamTest, TestConfigSetAdminRequest, StreamExpressionToExplanationTest, 
CoalesceEvaluatorTest, SineEvaluatorTest, SolrPingTest, ModuloEvaluatorTest, 
TestRetryUtil, GetByIdTest, SolrExceptionTest, FieldEvaluatorTest, 
TestSuggesterResponse, SolrExampleStreamingBinaryTest, NaturalLogEvaluatorTest, 
BasicHttpSolrClientTest, SolrQueryTest, QueryResponseTest, 
ConcurrentUpdateSolrClientTest, CloudSolrClientTest, TestV2Request, 
SolrExampleBinaryTest, ContentStreamTest, EqualsEvaluatorTest, 
RawValueEvaluatorTest, HyperbolicCosineEvaluatorTest, ShardParamsTest, 
GraphExpressionTest, ClientUtilsTest, LargeVolumeBinaryJettyTest, 
TestBatchUpdate, JettyWebappTest, TestSpellCheckResponse, 
MergeIndexesEmbeddedTest, TestCoreAdmin, CloudSolrClientBuilderTest, 
CloudSolrClientCacheTest, CloudSolrClientMultiConstructorTest, 
ConcurrentUpdateSolrClientBuilderTest, HttpSolrClientBuilderTest, 
HttpSolrClientConPoolTest, HttpSolrClientSSLAuthConPoolTest, 
LBHttpSolrClientBuilderTest, JdbcDriverTest, JdbcTest, JDBCStreamTest, 
SelectWithEvaluatorsTest, StreamExpressionTest]
   [junit4] Completed [135/135 (1!)] on J1 in 73.86s, 89 tests, 1 error, 9 
skipped <<< FAILURES!

[...truncated 42630 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to