You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/06/02 13:29:51 UTC
[JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_92) - Build #
5886 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/5886/
Java: 32bit/jdk1.8.0_92 -server -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.common.cloud.TestCollectionStateWatchers.testSimpleCollectionWatch
Error Message:
CollectionStateWatcher wasn't cleared after completion
Stack Trace:
java.lang.AssertionError: CollectionStateWatcher wasn't cleared after completion
at __randomizedtesting.SeedInfo.seed([BF0ADABEC5EA6A6B:E23115CE82E7F555]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.common.cloud.TestCollectionStateWatchers.testSimpleCollectionWatch(TestCollectionStateWatchers.java:117)
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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 13044 lines...]
[junit4] Suite: org.apache.solr.common.cloud.TestCollectionStateWatchers
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\init-core-data-001
[junit4] 2> 146850 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 146852 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 146853 INFO (Thread-669) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 146853 INFO (Thread-669) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 146954 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:59502
[junit4] 2> 146954 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 146955 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 146959 INFO (zkCallback-273-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@173dc7 name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 146959 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 146960 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 146960 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 146966 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 146967 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 146967 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9dd176{/solr,null,AVAILABLE}
[junit4] 2> 146970 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 146970 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@879dc9{/solr,null,AVAILABLE}
[junit4] 2> 146972 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e7dec5{/solr,null,AVAILABLE}
[junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@1120115{HTTP/1.1,[http/1.1]}{127.0.0.1:59506}
[junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.Server Started @153844ms
[junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59506}
[junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1'
[junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.ServerConnector Started ServerConnector@13bbd7b{HTTP/1.1,[http/1.1]}{127.0.0.1:59509}
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.Server Started @153844ms
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59509}
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3'
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@5086ab{HTTP/1.1,[http/1.1]}{127.0.0.1:59512}
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.Server Started @153845ms
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59512}
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2'
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 146975 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 146976 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 146976 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f6edac{/solr,null,AVAILABLE}
[junit4] 2> 146976 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.ServerConnector Started ServerConnector@179effc{HTTP/1.1,[http/1.1]}{127.0.0.1:59516}
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.Server Started @153848ms
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59516}
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4'
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 146977 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 146979 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 146979 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 146979 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 146997 INFO (zkCallback-274-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@973178 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 146997 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 146998 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 146999 INFO (zkCallback-276-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f8ad34 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 146999 INFO (zkCallback-277-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1456d8c name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 146999 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 146999 INFO (zkCallback-275-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@18d2ebc name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 146999 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 146999 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 146999 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 146999 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 146999 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 147001 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 147001 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 147001 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 147002 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 147019 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3\.
[junit4] 2> 147019 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.CoreContainer New CoreContainer 32442559
[junit4] 2> 147019 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3]
[junit4] 2> 147019 WARN (jetty-launcher-272-thread-3) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3\lib
[junit4] 2> 147019 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 147022 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2\.
[junit4] 2> 147022 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.CoreContainer New CoreContainer 15733160
[junit4] 2> 147022 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2]
[junit4] 2> 147023 WARN (jetty-launcher-272-thread-2) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2\lib
[junit4] 2> 147023 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 147023 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1\.
[junit4] 2> 147023 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 30625809
[junit4] 2> 147023 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1]
[junit4] 2> 147023 WARN (jetty-launcher-272-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1\lib
[junit4] 2> 147023 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 147029 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
[junit4] 2> 147029 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
[junit4] 2> 147029 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
[junit4] 2> 147029 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 147029 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 147029 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 147029 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.CoreContainer New CoreContainer 14933992
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59502/solr
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4]
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 147030 WARN (jetty-launcher-272-thread-4) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\lib
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59502/solr
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59502/solr
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59502/solr
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147037 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5fd1a1 name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 147037 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147037 INFO (zkCallback-284-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@34d0d6 name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 147037 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147037 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 147037 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 147039 INFO (zkCallback-286-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ffa1dd name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 147039 INFO (zkCallback-289-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10cc3bf name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 147039 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147039 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147039 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 147040 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 147045 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$69/7551549@5984a1
[junit4] 2> 147046 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147051 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$69/7551549@25e5ee
[junit4] 2> 147052 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$69/7551549@bc8ef0
[junit4] 2> 147052 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147057 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147059 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$69/7551549@53bd63
[junit4] 2> 147066 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147076 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@64fec5 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 147076 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147078 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14192c8 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 147078 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d8df24 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 147079 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147079 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147080 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@15a18f name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 147084 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147085 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 147087 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 147090 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 147094 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 147106 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 147106 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 147106 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 147106 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 147122 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 147123 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 147123 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 147123 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 147126 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 147127 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 147127 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 147127 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 147130 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 147130 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 147130 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 147130 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 147134 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 147134 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 147134 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 147134 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 147135 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 147136 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 147136 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 147136 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 147138 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 147138 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 147138 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 147138 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 147141 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 147141 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 147142 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 147142 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 147150 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 147150 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 147150 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 147150 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 147152 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 147152 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 147152 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 147153 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 147153 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 147154 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 147154 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 147154 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 147155 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 147155 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 147155 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 147156 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 147161 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 147161 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 147161 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 147161 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 147164 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 147164 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 147164 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 147164 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 147165 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 147165 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 147165 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 147166 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 147166 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96001988769808394-127.0.0.1:59506_solr-n_0000000000
[junit4] 2> 147166 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96001988769808393-127.0.0.1:59512_solr-n_0000000001
[junit4] 2> 147166 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96001988769808396-127.0.0.1:59509_solr-n_0000000002
[junit4] 2> 147166 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96001988769808395-127.0.0.1:59516_solr-n_0000000003
[junit4] 2> 147166 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59506_solr
[junit4] 2> 147168 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 147168 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96001988769808394-127.0.0.1:59506_solr-n_0000000000 to know if I could be the leader
[junit4] 2> 147168 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96001988769808393-127.0.0.1:59512_solr-n_0000000001 to know if I could be the leader
[junit4] 2> 147169 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96001988769808396-127.0.0.1:59509_solr-n_0000000002 to know if I could be the leader
[junit4] 2> 147169 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59512_solr
[junit4] 2> 147170 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59516_solr
[junit4] 2> 147170 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59509_solr
[junit4] 2> 147170 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59512_solr
[junit4] 2> 147170 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59516_solr
[junit4] 2> 147170 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59509_solr
[junit4] 2> 147170 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer Overseer (id=96001988769808394-127.0.0.1:59506_solr-n_0000000000) starting
[junit4] 2> 147173 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 147173 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 147173 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 147173 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 147175 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 147177 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 147177 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 147178 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 147192 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 147192 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 147194 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2\.
[junit4] 2> 147195 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 147216 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 147216 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 147224 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.
[junit4] 2> 147225 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 147225 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 147225 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 147225 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 147226 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 147226 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 147233 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 147233 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 147235 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3\.
[junit4] 2> 147236 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 147237 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 147237 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 147241 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 147242 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:59506_solr as DOWN
[junit4] 2> 147243 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 147243 INFO (OverseerCollectionConfigSetProcessor-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 147246 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (3)
[junit4] 2> 147247 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:59506_solr"} current state version: 0
[junit4] 2> 147247 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:59506_solr
[junit4] 2> 148244 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59506_solr
[junit4] 2> 148245 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59506_solr
[junit4] 2> 148246 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 148246 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 148246 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 148246 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 148247 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 148248 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 148248 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 148248 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 148277 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 148277 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 148279 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1\.
[junit4] 2> 148280 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 148281 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 148281 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 148282 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 148282 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 148285 INFO (zkCallback-298-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ea133 name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 148285 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 148285 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 148289 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:59502/solr
[junit4] 2> 148291 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 148292 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 148295 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@911730 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 148295 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 148295 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 148295 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 148295 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 148296 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 148299 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/schema-replication1.xml
[junit4] 2> 148302 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/schema-sql.xml
[junit4] 2> 148305 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/schema.xml
[junit4] 2> 148307 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig-managed-schema.xml
[junit4] 2> 148310 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig-slave1.xml
[junit4] 2> 148312 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig-sql.xml
[junit4] 2> 148315 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig.xml
[junit4] 2> 148350 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchesWorkForStateFormat1
[junit4] 2> 148350 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 148351 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 148353 INFO (zkCallback-302-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16aeaee name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 148354 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 148354 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 148370 INFO (qtp25049674-1725) [n:127.0.0.1:59509_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=dff215e7-012c-4c70-9516-16e96c98a31d&replicationFactor=1&collection.configName=config&name=stateformat1&action=CREATE&numShards=1&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 148375 INFO (qtp25049674-1725) [n:127.0.0.1:59509_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=dff215e7-012c-4c70-9516-16e96c98a31d&replicationFactor=1&collection.configName=config&name=stateformat1&action=CREATE&numShards=1&stateFormat=1&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 148377 INFO (qtp25049674-1726) [n:127.0.0.1:59509_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=dff215e7-012c-4c70-9516-16e96c98a31d&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 148377 INFO (backgroundWatchers-271-thread-1) [ ] o.a.s.c.c.ZkStateReader Deleting data for [stateformat1]
[junit4] 2> 148388 INFO (OverseerCollectionConfigSetProcessor-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running/mn-dff215e7-012c-4c70-9516-16e96c98a31d
[junit4] 2> 148388 INFO (qtp25049674-1726) [n:127.0.0.1:59509_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=dff215e7-012c-4c70-9516-16e96c98a31d&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 148391 INFO (OverseerCollectionConfigSetProcessor-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"stateformat1",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "collection.configName":"config",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"1",
[junit4] 2> "async":"dff215e7-012c-4c70-9516-16e96c98a31d",
[junit4] 2> "operation":"create"}
[junit4] 2> 148391 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"stateformat1",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "collection.configName":"config",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"1",
[junit4] 2> "async":"dff215e7-012c-4c70-9516-16e96c98a31d",
[junit4] 2> "operation":"create"}
[junit4] 2> 148391 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler Create collection stateformat1
[junit4] 2> 148392 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/stateformat1
[junit4] 2> 148393 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/stateformat1
[junit4] 2> 148394 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 148394 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 148394 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 148394 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 148394 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 148401 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"stateformat1",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "collection.configName":"config",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"1",
[junit4] 2> "async":"dff215e7-012c-4c70-9516-16e96c98a31d",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 148401 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: stateformat1
[junit4] 2> 148403 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 148403 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 148404 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 148404 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 148404 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 148499 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating SolrCores for new collection stateformat1, shardNames [shard1] , replicationFactor : 1
[junit4] 2> 148499 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core stateformat1_shard1_replica1 as part of shard shard1 of collection stateformat1 on 127.0.0.1:59516_solr
[junit4] 2> 148508 INFO (qtp6577232-1744) [n:127.0.0.1:59516_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=dff215e7-012c-4c70-9516-16e96c98a31d405284475846814&qt=/admin/cores&collection.configName=config&name=stateformat1_shard1_replica1&action=CREATE&numShards=1&collection=stateformat1&shard=shard1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 148509 INFO (qtp6577232-1743) [n:127.0.0.1:59516_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : dff215e7-012c-4c70-9516-16e96c98a31d405284475846814
[junit4] 2> 148510 INFO (qtp6577232-1743) [n:127.0.0.1:59516_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=dff215e7-012c-4c70-9516-16e96c98a31d405284475846814&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 148510 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr ] o.a.s.h.a.CoreAdminOperation core create command async=dff215e7-012c-4c70-9516-16e96c98a31d405284475846814&qt=/admin/cores&collection.configName=config&name=stateformat1_shard1_replica1&action=CREATE&numShards=1&collection=stateformat1&shard=shard1&wt=javabin&version=2
[junit4] 2> 148513 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=stateformat1_shard1_replica1, collection.configName=config, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=stateformat1, numShards=1, dataDir=data\, shard=shard1}
[junit4] 2> 148513 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 148515 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController Registering watch for collection stateformat1
[junit4] 2> 148517 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.c.ZkStateReader Deleting data for [stateformat1]
[junit4] 2> 148518 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 148518 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"stateformat1_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59516/solr",
[junit4] 2> "node_name":"127.0.0.1:59516_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"stateformat1",
[junit4] 2> "operation":"state"} current state version: 1
[junit4] 2> 148519 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"stateformat1_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59516/solr",
[junit4] 2> "node_name":"127.0.0.1:59516_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"stateformat1",
[junit4] 2> "operation":"state"}
[junit4] 2> 148624 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 148624 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 148625 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 148625 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 148625 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
[junit4] 2> 149389 INFO (qtp25049674-1727) [n:127.0.0.1:59509_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=dff215e7-012c-4c70-9516-16e96c98a31d&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 149391 INFO (qtp25049674-1727) [n:127.0.0.1:59509_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=dff215e7-012c-4c70-9516-16e96c98a31d&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 149515 INFO (qtp6577232-1745) [n:127.0.0.1:59516_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : dff215e7-012c-4c70-9516-16e96c98a31d405284475846814
[junit4] 2> 149515 INFO (qtp6577232-1745) [n:127.0.0.1:59516_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=dff215e7-012c-4c70-9516-16e96c98a31d405284475846814&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 149518 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for stateformat1_shard1_replica1
[junit4] 2> 149518 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:stateformat1
[junit4] 2> 149521 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 149521 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/stateformat1]
[junit4] 2> 149522 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/stateformat1] [configName]=[config] specified config exists in ZooKeeper
[junit4] 2> 149522 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\stateformat1_shard1_replica1'
[junit4] 2> 149522 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 149522 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 149525 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 149540 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 149543 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 149551 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 149567 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema [stateformat1_shard1_replica1] Schema name=test
[junit4] 2> 149650 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 149652 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 149653 WARN (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 149653 WARN (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 149662 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/config', cwd=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 149663 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'stateformat1_shard1_replica1' using configuration from collection stateformat1
[junit4] 2> 149663 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 149663 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore [[stateformat1_shard1_replica1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\stateformat1_shard1_replica1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\]
[junit4] 2> 149664 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore JMX monitoring not detected for core: stateformat1_shard1_replica1
[junit4] 2> 149664 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\
[junit4] 2> 149665 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\index/
[junit4] 2> 149665 WARN (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore [stateformat1_shard1_replica1] Solr index directory 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 149666 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\index
[junit4] 2> 149668 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1661112 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1df0f20),segFN=segments_1,generation=1}
[junit4] 2> 149668 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 149669 INFO (OldIndexDirectoryCleanupThreadForCore-stateformat1_shard1_replica1) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core stateformat1_shard1_replica1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\
[junit4] 2> 149670 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 149675 WARN (OldIndexDirectoryCleanupThreadForCore-stateformat1_shard1_replica1) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.DirectoryFactory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 149685 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 149687 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 149689 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 149692 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 149695 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
[junit4] 2> 149695 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 149711 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 149711 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 149714 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 149714 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 149715 INF
[...truncated too long message...]
177658 WARN (zkCallback-293-thread-2-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.LeaderElector
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:297)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:294)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:294)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:488)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:458)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:445)
[junit4] 2> at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:729)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:266)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 177659 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a8dd59{/solr,null,UNAVAILABLE}
[junit4] 2> 177660 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 0x155114cd65b001d, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 177660 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@879dc9{/solr,null,UNAVAILABLE}
[junit4] 2> 177686 WARN (zkCallback-321-thread-2-processing-n:127.0.0.1:59650_solr) [n:127.0.0.1:59650_solr ] o.a.s.c.LeaderElector
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:331)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:328)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:328)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:105)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:266)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 179155 WARN (zkCallback-321-thread-1-processing-n:127.0.0.1:59650_solr) [n:127.0.0.1:59650_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 179166 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b9efbf{/solr,null,UNAVAILABLE}
[junit4] 2> 179176 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59502 59502
[junit4] 2> 179179 INFO (Thread-669) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59502 59502
[junit4] 2> 179182 WARN (Thread-669) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/aliases.json
[junit4] 2> 8 /solr/configs/config
[junit4] 2> 8 /solr/clusterprops.json
[junit4] 2> 7 /solr/security.json
[junit4] 2> 3 /solr/collections/stateformat1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/collections/waitforstate/state.json
[junit4] 2> 8 /solr/clusterstate.json
[junit4] 2> 7 /solr/collections/falsepredicate/state.json
[junit4] 2> 7 /solr/collections/testcollection/state.json
[junit4] 2> 2 /solr/collections/currentstate/state.json
[junit4] 2> 2 /solr/collections/delayed/state.json
[junit4] 2> 2 /solr/collections/tobedeleted/state.json
[junit4] 2> 2 /solr/collections/stateformat1/state.json
[junit4] 2> 2 /solr/overseer_elect/election/96001988769808396-127.0.0.1:59509_solr-n_0000000002
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 179182 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001
[junit4] 2> Jun 02, 2016 1:28:48 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=936, maxMBSortInHeap=7.986845666309913, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=tr, timezone=Europe/Bratislava
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_92 (32-bit)/cpus=3,threads=1,free=108757664,total=253755392
[junit4] 2> NOTE: All tests run in this JVM: [TestDocumentObjectBinder, SchemaTest, TestHash, CloudSolrClientTest, ConcurrentUpdateSolrClientTest, TestJavaBinCodec, SolrDocumentTest, NamedListTest, JdbcDriverTest, FieldAnalysisResponseTest, SolrExampleXMLTest, TestUpdateRequestCodec, SolrExampleStreamingTest, TestSpellCheckResponse, SolrExampleEmbeddedTest, GraphExpressionTest, TestCollectionStateWatchers]
[junit4] Completed [58/83 (1!)] on J0 in 32.87s, 9 tests, 1 failure <<< FAILURES!
[...truncated 85 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:740: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:684: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build.xml:246: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\common-build.xml:531: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1427: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:984: There were test failures: 83 suites, 641 tests, 1 failure, 2 ignored [seed: BF0ADABEC5EA6A6B]
Total time: 79 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Re: [JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_92) - Build # 5886 - Still Failing!
Posted by Alan Woodward <al...@flax.co.uk>.
This looks like another test bug - there’s a race between the latch being triggered and the watch being cleared, so that if the notifications thread gets paused then we can end up checking the watch list before anything has been removed.
I guess I can stick a thread.sleep() in the main test, but that feels a bit hacky (and also not guaranteed to work, of course!)
Alan Woodward
www.flax.co.uk
> On 2 Jun 2016, at 14:29, Policeman Jenkins Server <je...@thetaphi.de> wrote:
>
> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/5886/
> Java: 32bit/jdk1.8.0_92 -server -XX:+UseG1GC
>
> 1 tests failed.
> FAILED: org.apache.solr.common.cloud.TestCollectionStateWatchers.testSimpleCollectionWatch
>
> Error Message:
> CollectionStateWatcher wasn't cleared after completion
>
> Stack Trace:
> java.lang.AssertionError: CollectionStateWatcher wasn't cleared after completion
> at __randomizedtesting.SeedInfo.seed([BF0ADABEC5EA6A6B:E23115CE82E7F555]:0)
> at org.junit.Assert.fail(Assert.java:93)
> at org.junit.Assert.assertTrue(Assert.java:43)
> at org.apache.solr.common.cloud.TestCollectionStateWatchers.testSimpleCollectionWatch(TestCollectionStateWatchers.java:117)
> 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:1764)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
> 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:367)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
> 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:367)
> at java.lang.Thread.run(Thread.java:745)
>
>
>
>
> Build Log:
> [...truncated 13044 lines...]
> [junit4] Suite: org.apache.solr.common.cloud.TestCollectionStateWatchers
> [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\init-core-data-001
> [junit4] 2> 146850 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
> [junit4] 2> 146852 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
> [junit4] 2> 146853 INFO (Thread-669) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
> [junit4] 2> 146853 INFO (Thread-669) [ ] o.a.s.c.ZkTestServer Starting server
> [junit4] 2> 146954 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:59502
> [junit4] 2> 146954 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 146955 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 146959 INFO (zkCallback-273-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@173dc7 name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 146959 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 146960 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 146960 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
> [junit4] 2> 146966 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
> [junit4] 2> 146967 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
> [junit4] 2> 146967 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9dd176{/solr,null,AVAILABLE}
> [junit4] 2> 146970 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
> [junit4] 2> 146970 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@879dc9{/solr,null,AVAILABLE}
> [junit4] 2> 146972 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e7dec5{/solr,null,AVAILABLE}
> [junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@1120115{HTTP/1.1,[http/1.1]}{127.0.0.1:59506}
> [junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.Server Started @153844ms
> [junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59506}
> [junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
> [junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1'
> [junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 146973 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.ServerConnector Started ServerConnector@13bbd7b{HTTP/1.1,[http/1.1]}{127.0.0.1:59509}
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.Server Started @153844ms
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59509}
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3'
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@5086ab{HTTP/1.1,[http/1.1]}{127.0.0.1:59512}
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.Server Started @153845ms
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59512}
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2'
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 146974 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
> [junit4] 2> 146975 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
> [junit4] 2> 146976 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 146976 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f6edac{/solr,null,AVAILABLE}
> [junit4] 2> 146976 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.ServerConnector Started ServerConnector@179effc{HTTP/1.1,[http/1.1]}{127.0.0.1:59516}
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.Server Started @153848ms
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59516}
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4'
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
> [junit4] 2> 146977 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 146979 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 146979 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 146979 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 146997 INFO (zkCallback-274-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@973178 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 146997 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 146998 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 146999 INFO (zkCallback-276-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f8ad34 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 146999 INFO (zkCallback-277-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1456d8c name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 146999 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 146999 INFO (zkCallback-275-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@18d2ebc name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 146999 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 146999 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 146999 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 146999 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 146999 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 147001 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
> [junit4] 2> 147001 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
> [junit4] 2> 147001 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
> [junit4] 2> 147002 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
> [junit4] 2> 147019 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3\.
> [junit4] 2> 147019 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.CoreContainer New CoreContainer 32442559
> [junit4] 2> 147019 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3]
> [junit4] 2> 147019 WARN (jetty-launcher-272-thread-3) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3\lib
> [junit4] 2> 147019 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
> [junit4] 2> 147022 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2\.
> [junit4] 2> 147022 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.CoreContainer New CoreContainer 15733160
> [junit4] 2> 147022 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2]
> [junit4] 2> 147023 WARN (jetty-launcher-272-thread-2) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2\lib
> [junit4] 2> 147023 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
> [junit4] 2> 147023 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1\.
> [junit4] 2> 147023 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 30625809
> [junit4] 2> 147023 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1]
> [junit4] 2> 147023 WARN (jetty-launcher-272-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1\lib
> [junit4] 2> 147023 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
> [junit4] 2> 147029 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
> [junit4] 2> 147029 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
> [junit4] 2> 147029 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
> [junit4] 2> 147029 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 147029 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 147029 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 147029 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.CoreContainer New CoreContainer 14933992
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59502/solr
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4]
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.ZkController zkHost includes chroot
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 147030 WARN (jetty-launcher-272-thread-4) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\lib
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59502/solr
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59502/solr
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.ZkController zkHost includes chroot
> [junit4] 2> 147030 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59502/solr
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.ZkController zkHost includes chroot
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 147031 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 147037 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5fd1a1 name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 147037 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 147037 INFO (zkCallback-284-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@34d0d6 name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 147037 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 147037 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 147037 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 147039 INFO (zkCallback-286-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ffa1dd name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 147039 INFO (zkCallback-289-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10cc3bf name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 147039 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 147039 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 147039 INFO (jetty-launcher-272-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 147040 INFO (jetty-launcher-272-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 147045 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$69/7551549@5984a1
> [junit4] 2> 147046 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 147051 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$69/7551549@25e5ee
> [junit4] 2> 147052 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$69/7551549@bc8ef0
> [junit4] 2> 147052 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 147057 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 147059 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$69/7551549@53bd63
> [junit4] 2> 147066 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 147076 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@64fec5 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 147076 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 147078 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14192c8 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 147078 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d8df24 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 147079 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 147079 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 147080 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@15a18f name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 147084 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 147085 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
> [junit4] 2> 147087 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
> [junit4] 2> 147090 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
> [junit4] 2> 147094 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
> [junit4] 2> 147106 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
> [junit4] 2> 147106 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
> [junit4] 2> 147106 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
> [junit4] 2> 147106 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
> [junit4] 2> 147122 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
> [junit4] 2> 147123 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
> [junit4] 2> 147123 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
> [junit4] 2> 147123 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
> [junit4] 2> 147126 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
> [junit4] 2> 147127 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
> [junit4] 2> 147127 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
> [junit4] 2> 147127 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
> [junit4] 2> 147130 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
> [junit4] 2> 147130 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
> [junit4] 2> 147130 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
> [junit4] 2> 147130 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
> [junit4] 2> 147134 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
> [junit4] 2> 147134 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
> [junit4] 2> 147134 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
> [junit4] 2> 147134 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
> [junit4] 2> 147135 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
> [junit4] 2> 147136 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
> [junit4] 2> 147136 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
> [junit4] 2> 147136 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
> [junit4] 2> 147138 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
> [junit4] 2> 147138 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
> [junit4] 2> 147138 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
> [junit4] 2> 147138 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
> [junit4] 2> 147141 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
> [junit4] 2> 147141 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
> [junit4] 2> 147142 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
> [junit4] 2> 147142 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
> [junit4] 2> 147150 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
> [junit4] 2> 147150 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
> [junit4] 2> 147150 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
> [junit4] 2> 147150 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
> [junit4] 2> 147152 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
> [junit4] 2> 147152 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
> [junit4] 2> 147152 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
> [junit4] 2> 147153 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
> [junit4] 2> 147153 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
> [junit4] 2> 147154 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
> [junit4] 2> 147154 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
> [junit4] 2> 147154 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
> [junit4] 2> 147155 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
> [junit4] 2> 147155 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
> [junit4] 2> 147155 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
> [junit4] 2> 147156 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
> [junit4] 2> 147161 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
> [junit4] 2> 147161 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
> [junit4] 2> 147161 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
> [junit4] 2> 147161 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
> [junit4] 2> 147164 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
> [junit4] 2> 147164 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
> [junit4] 2> 147164 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
> [junit4] 2> 147164 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
> [junit4] 2> 147165 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer Overseer (id=null) closing
> [junit4] 2> 147165 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.Overseer Overseer (id=null) closing
> [junit4] 2> 147165 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.Overseer Overseer (id=null) closing
> [junit4] 2> 147166 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.Overseer Overseer (id=null) closing
> [junit4] 2> 147166 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96001988769808394-127.0.0.1:59506_solr-n_0000000000
> [junit4] 2> 147166 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96001988769808393-127.0.0.1:59512_solr-n_0000000001
> [junit4] 2> 147166 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96001988769808396-127.0.0.1:59509_solr-n_0000000002
> [junit4] 2> 147166 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96001988769808395-127.0.0.1:59516_solr-n_0000000003
> [junit4] 2> 147166 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59506_solr
> [junit4] 2> 147168 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
> [junit4] 2> 147168 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96001988769808394-127.0.0.1:59506_solr-n_0000000000 to know if I could be the leader
> [junit4] 2> 147168 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96001988769808393-127.0.0.1:59512_solr-n_0000000001 to know if I could be the leader
> [junit4] 2> 147169 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96001988769808396-127.0.0.1:59509_solr-n_0000000002 to know if I could be the leader
> [junit4] 2> 147169 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59512_solr
> [junit4] 2> 147170 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59516_solr
> [junit4] 2> 147170 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59509_solr
> [junit4] 2> 147170 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59512_solr
> [junit4] 2> 147170 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59516_solr
> [junit4] 2> 147170 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59509_solr
> [junit4] 2> 147170 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer Overseer (id=96001988769808394-127.0.0.1:59506_solr-n_0000000000) starting
> [junit4] 2> 147173 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
> [junit4] 2> 147173 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
> [junit4] 2> 147173 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
> [junit4] 2> 147173 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
> [junit4] 2> 147175 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
> [junit4] 2> 147177 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
> [junit4] 2> 147177 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
> [junit4] 2> 147178 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
> [junit4] 2> 147192 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
> [junit4] 2> 147192 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.CoreContainer No authentication plugin used.
> [junit4] 2> 147194 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node2\.
> [junit4] 2> 147195 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
> [junit4] 2> 147216 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
> [junit4] 2> 147216 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.CoreContainer No authentication plugin used.
> [junit4] 2> 147224 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.
> [junit4] 2> 147225 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
> [junit4] 2> 147225 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
> [junit4] 2> 147225 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:59512_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
> [junit4] 2> 147225 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
> [junit4] 2> 147226 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
> [junit4] 2> 147226 INFO (jetty-launcher-272-thread-4) [n:127.0.0.1:59516_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
> [junit4] 2> 147233 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
> [junit4] 2> 147233 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.CoreContainer No authentication plugin used.
> [junit4] 2> 147235 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node3\.
> [junit4] 2> 147236 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
> [junit4] 2> 147237 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
> [junit4] 2> 147237 INFO (jetty-launcher-272-thread-3) [n:127.0.0.1:59509_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
> [junit4] 2> 147241 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
> [junit4] 2> 147242 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:59506_solr as DOWN
> [junit4] 2> 147243 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer Starting to work on the main queue
> [junit4] 2> 147243 INFO (OverseerCollectionConfigSetProcessor-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
> [junit4] 2> 147246 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (3)
> [junit4] 2> 147247 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
> [junit4] 2> "operation":"downnode",
> [junit4] 2> "node_name":"127.0.0.1:59506_solr"} current state version: 0
> [junit4] 2> 147247 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:59506_solr
> [junit4] 2> 148244 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59506_solr
> [junit4] 2> 148245 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59506_solr
> [junit4] 2> 148246 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
> [junit4] 2> 148246 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
> [junit4] 2> 148246 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
> [junit4] 2> 148246 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
> [junit4] 2> 148247 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 148248 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 148248 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 148248 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 148277 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
> [junit4] 2> 148277 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.CoreContainer No authentication plugin used.
> [junit4] 2> 148279 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node1\.
> [junit4] 2> 148280 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
> [junit4] 2> 148281 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
> [junit4] 2> 148281 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:59506_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
> [junit4] 2> 148282 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 148282 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 148285 INFO (zkCallback-298-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ea133 name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 148285 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 148285 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 148289 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:59502/solr
> [junit4] 2> 148291 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 148292 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 148295 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@911730 name:ZooKeeperConnection Watcher:127.0.0.1:59502/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 148295 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 148295 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 148295 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
> [junit4] 2> 148295 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
> [junit4] 2> 148296 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
> [junit4] 2> 148299 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/schema-replication1.xml
> [junit4] 2> 148302 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/schema-sql.xml
> [junit4] 2> 148305 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/schema.xml
> [junit4] 2> 148307 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig-managed-schema.xml
> [junit4] 2> 148310 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig-slave1.xml
> [junit4] 2> 148312 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig-sql.xml
> [junit4] 2> 148315 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig.xml
> [junit4] 2> 148350 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchesWorkForStateFormat1
> [junit4] 2> 148350 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
> [junit4] 2> 148351 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
> [junit4] 2> 148353 INFO (zkCallback-302-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16aeaee name:ZooKeeperConnection Watcher:127.0.0.1:59502 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 148354 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
> [junit4] 2> 148354 INFO (TEST-TestCollectionStateWatchers.testWatchesWorkForStateFormat1-seed#[BF0ADABEC5EA6A6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
> [junit4] 2> 148370 INFO (qtp25049674-1725) [n:127.0.0.1:59509_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=dff215e7-012c-4c70-9516-16e96c98a31d&replicationFactor=1&collection.configName=config&name=stateformat1&action=CREATE&numShards=1&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
> [junit4] 2> 148375 INFO (qtp25049674-1725) [n:127.0.0.1:59509_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=dff215e7-012c-4c70-9516-16e96c98a31d&replicationFactor=1&collection.configName=config&name=stateformat1&action=CREATE&numShards=1&stateFormat=1&wt=javabin&version=2} status=0 QTime=5
> [junit4] 2> 148377 INFO (qtp25049674-1726) [n:127.0.0.1:59509_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=dff215e7-012c-4c70-9516-16e96c98a31d&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
> [junit4] 2> 148377 INFO (backgroundWatchers-271-thread-1) [ ] o.a.s.c.c.ZkStateReader Deleting data for [stateformat1]
> [junit4] 2> 148388 INFO (OverseerCollectionConfigSetProcessor-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running/mn-dff215e7-012c-4c70-9516-16e96c98a31d
> [junit4] 2> 148388 INFO (qtp25049674-1726) [n:127.0.0.1:59509_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=dff215e7-012c-4c70-9516-16e96c98a31d&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=11
> [junit4] 2> 148391 INFO (OverseerCollectionConfigSetProcessor-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
> [junit4] 2> "name":"stateformat1",
> [junit4] 2> "fromApi":"true",
> [junit4] 2> "replicationFactor":"1",
> [junit4] 2> "collection.configName":"config",
> [junit4] 2> "numShards":"1",
> [junit4] 2> "stateFormat":"1",
> [junit4] 2> "async":"dff215e7-012c-4c70-9516-16e96c98a31d",
> [junit4] 2> "operation":"create"}
> [junit4] 2> 148391 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
> [junit4] 2> "name":"stateformat1",
> [junit4] 2> "fromApi":"true",
> [junit4] 2> "replicationFactor":"1",
> [junit4] 2> "collection.configName":"config",
> [junit4] 2> "numShards":"1",
> [junit4] 2> "stateFormat":"1",
> [junit4] 2> "async":"dff215e7-012c-4c70-9516-16e96c98a31d",
> [junit4] 2> "operation":"create"}
> [junit4] 2> 148391 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler Create collection stateformat1
> [junit4] 2> 148392 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/stateformat1
> [junit4] 2> 148393 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/stateformat1
> [junit4] 2> 148394 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
> [junit4] 2> 148394 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
> [junit4] 2> 148394 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
> [junit4] 2> 148394 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
> [junit4] 2> 148394 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
> [junit4] 2> 148401 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
> [junit4] 2> "name":"stateformat1",
> [junit4] 2> "fromApi":"true",
> [junit4] 2> "replicationFactor":"1",
> [junit4] 2> "collection.configName":"config",
> [junit4] 2> "numShards":"1",
> [junit4] 2> "stateFormat":"1",
> [junit4] 2> "async":"dff215e7-012c-4c70-9516-16e96c98a31d",
> [junit4] 2> "operation":"create"} current state version: 0
> [junit4] 2> 148401 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: stateformat1
> [junit4] 2> 148403 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 148403 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 148404 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 148404 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 148404 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 148499 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating SolrCores for new collection stateformat1, shardNames [shard1] , replicationFactor : 1
> [junit4] 2> 148499 INFO (OverseerThreadFactory-653-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core stateformat1_shard1_replica1 as part of shard shard1 of collection stateformat1 on 127.0.0.1:59516_solr
> [junit4] 2> 148508 INFO (qtp6577232-1744) [n:127.0.0.1:59516_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=dff215e7-012c-4c70-9516-16e96c98a31d405284475846814&qt=/admin/cores&collection.configName=config&name=stateformat1_shard1_replica1&action=CREATE&numShards=1&collection=stateformat1&shard=shard1&wt=javabin&version=2} status=0 QTime=0
> [junit4] 2> 148509 INFO (qtp6577232-1743) [n:127.0.0.1:59516_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : dff215e7-012c-4c70-9516-16e96c98a31d405284475846814
> [junit4] 2> 148510 INFO (qtp6577232-1743) [n:127.0.0.1:59516_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=dff215e7-012c-4c70-9516-16e96c98a31d405284475846814&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
> [junit4] 2> 148510 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr ] o.a.s.h.a.CoreAdminOperation core create command async=dff215e7-012c-4c70-9516-16e96c98a31d405284475846814&qt=/admin/cores&collection.configName=config&name=stateformat1_shard1_replica1&action=CREATE&numShards=1&collection=stateformat1&shard=shard1&wt=javabin&version=2
> [junit4] 2> 148513 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=stateformat1_shard1_replica1, collection.configName=config, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=stateformat1, numShards=1, dataDir=data\, shard=shard1}
> [junit4] 2> 148513 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController publishing state=down
> [junit4] 2> 148515 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController Registering watch for collection stateformat1
> [junit4] 2> 148517 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.c.ZkStateReader Deleting data for [stateformat1]
> [junit4] 2> 148518 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController look for our core node name
> [junit4] 2> 148518 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
> [junit4] 2> "core":"stateformat1_shard1_replica1",
> [junit4] 2> "roles":null,
> [junit4] 2> "base_url":"http://127.0.0.1:59516/solr",
> [junit4] 2> "node_name":"127.0.0.1:59516_solr",
> [junit4] 2> "numShards":"1",
> [junit4] 2> "state":"down",
> [junit4] 2> "shard":"shard1",
> [junit4] 2> "collection":"stateformat1",
> [junit4] 2> "operation":"state"} current state version: 1
> [junit4] 2> 148519 INFO (OverseerStateUpdate-96001988769808394-127.0.0.1:59506_solr-n_0000000000) [n:127.0.0.1:59506_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
> [junit4] 2> "core":"stateformat1_shard1_replica1",
> [junit4] 2> "roles":null,
> [junit4] 2> "base_url":"http://127.0.0.1:59516/solr",
> [junit4] 2> "node_name":"127.0.0.1:59516_solr",
> [junit4] 2> "numShards":"1",
> [junit4] 2> "state":"down",
> [junit4] 2> "shard":"shard1",
> [junit4] 2> "collection":"stateformat1",
> [junit4] 2> "operation":"state"}
> [junit4] 2> 148624 INFO (zkCallback-290-thread-1-processing-n:127.0.0.1:59512_solr) [n:127.0.0.1:59512_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 148624 INFO (zkCallback-293-thread-1-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 148625 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 148625 INFO (zkCallback-291-thread-1-processing-n:127.0.0.1:59506_solr) [n:127.0.0.1:59506_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 148625 INFO (zkCallback-292-thread-1-processing-n:127.0.0.1:59516_solr) [n:127.0.0.1:59516_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [4])
> [junit4] 2> 149389 INFO (qtp25049674-1727) [n:127.0.0.1:59509_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=dff215e7-012c-4c70-9516-16e96c98a31d&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
> [junit4] 2> 149391 INFO (qtp25049674-1727) [n:127.0.0.1:59509_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=dff215e7-012c-4c70-9516-16e96c98a31d&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
> [junit4] 2> 149515 INFO (qtp6577232-1745) [n:127.0.0.1:59516_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : dff215e7-012c-4c70-9516-16e96c98a31d405284475846814
> [junit4] 2> 149515 INFO (qtp6577232-1745) [n:127.0.0.1:59516_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=dff215e7-012c-4c70-9516-16e96c98a31d405284475846814&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
> [junit4] 2> 149518 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for stateformat1_shard1_replica1
> [junit4] 2> 149518 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:stateformat1
> [junit4] 2> 149521 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
> [junit4] 2> 149521 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/stateformat1]
> [junit4] 2> 149522 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/stateformat1] [configName]=[config] specified config exists in ZooKeeper
> [junit4] 2> 149522 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\stateformat1_shard1_replica1'
> [junit4] 2> 149522 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 149522 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
> [junit4] 2> 149525 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
> [junit4] 2> 149540 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrConfig current version of requestparams : -1
> [junit4] 2> 149543 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
> [junit4] 2> 149551 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
> [junit4] 2> 149567 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema [stateformat1_shard1_replica1] Schema name=test
> [junit4] 2> 149650 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema default search field in schema is text
> [junit4] 2> 149652 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema unique key field: id
> [junit4] 2> 149653 WARN (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
> [junit4] 2> 149653 WARN (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
> [junit4] 2> 149662 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/config', cwd=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0
> [junit4] 2> 149663 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 x:stateformat1_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'stateformat1_shard1_replica1' using configuration from collection stateformat1
> [junit4] 2> 149663 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
> [junit4] 2> 149663 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore [[stateformat1_shard1_replica1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\stateformat1_shard1_replica1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\]
> [junit4] 2> 149664 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore JMX monitoring not detected for core: stateformat1_shard1_replica1
> [junit4] 2> 149664 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\
> [junit4] 2> 149665 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\index/
> [junit4] 2> 149665 WARN (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore [stateformat1_shard1_replica1] Solr index directory 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\index' doesn't exist. Creating new index...
> [junit4] 2> 149666 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\index
> [junit4] 2> 149668 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1661112 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1df0f20),segFN=segments_1,generation=1}
> [junit4] 2> 149668 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
> [junit4] 2> 149669 INFO (OldIndexDirectoryCleanupThreadForCore-stateformat1_shard1_replica1) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core stateformat1_shard1_replica1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\
> [junit4] 2> 149670 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
> [junit4] 2> 149675 WARN (OldIndexDirectoryCleanupThreadForCore-stateformat1_shard1_replica1) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.DirectoryFactory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001\tempDir-001\node4\.\stateformat1_shard1_replica1\data\ does not point to a valid data directory; skipping clean-up of old index directories.
> [junit4] 2> 149685 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
> [junit4] 2> 149687 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
> [junit4] 2> 149689 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
> [junit4] 2> 149692 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
> [junit4] 2> 149695 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
> [junit4] 2> 149695 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
> [junit4] 2> 149711 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
> [junit4] 2> 149711 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
> [junit4] 2> 149714 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
> [junit4] 2> 149714 INFO (parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:59516_solr dff215e7-012c-4c70-9516-16e96c98a31d405284475846814 CREATE) [n:127.0.0.1:59516_solr c:stateformat1 s:shard1 r:core_node1 x:stateformat1_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
> [junit4] 2> 149715 INF
>
> [...truncated too long message...]
>
> 177658 WARN (zkCallback-293-thread-2-processing-n:127.0.0.1:59509_solr) [n:127.0.0.1:59509_solr ] o.a.s.c.LeaderElector
> [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect
> [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
> [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
> [junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:297)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:294)
> [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:294)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:488)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:458)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:445)
> [junit4] 2> at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:729)
> [junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170)
> [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135)
> [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
> [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:266)
> [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
> [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
> [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> [junit4] 2> at java.lang.Thread.run(Thread.java:745)
> [junit4] 2> 177659 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a8dd59{/solr,null,UNAVAILABLE}
> [junit4] 2> 177660 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 0x155114cd65b001d, likely client has closed socket
> [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
> [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
> [junit4] 2> at java.lang.Thread.run(Thread.java:745)
> [junit4] 2> 177660 INFO (jetty-launcher-272-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@879dc9{/solr,null,UNAVAILABLE}
> [junit4] 2> 177686 WARN (zkCallback-321-thread-2-processing-n:127.0.0.1:59650_solr) [n:127.0.0.1:59650_solr ] o.a.s.c.LeaderElector
> [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
> [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
> [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
> [junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:331)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:328)
> [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:328)
> [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:105)
> [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
> [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:266)
> [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
> [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
> [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> [junit4] 2> at java.lang.Thread.run(Thread.java:745)
> [junit4] 2> 179155 WARN (zkCallback-321-thread-1-processing-n:127.0.0.1:59650_solr) [n:127.0.0.1:59650_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
> [junit4] 2> 179166 INFO (jetty-launcher-272-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b9efbf{/solr,null,UNAVAILABLE}
> [junit4] 2> 179176 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59502 59502
> [junit4] 2> 179179 INFO (Thread-669) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59502 59502
> [junit4] 2> 179182 WARN (Thread-669) [ ] o.a.s.c.ZkTestServer Watch limit violations:
> [junit4] 2> Maximum concurrent create/delete watches above limit:
> [junit4] 2>
> [junit4] 2> 8 /solr/aliases.json
> [junit4] 2> 8 /solr/configs/config
> [junit4] 2> 8 /solr/clusterprops.json
> [junit4] 2> 7 /solr/security.json
> [junit4] 2> 3 /solr/collections/stateformat1/state.json
> [junit4] 2>
> [junit4] 2> Maximum concurrent data watches above limit:
> [junit4] 2>
> [junit4] 2> 12 /solr/collections/waitforstate/state.json
> [junit4] 2> 8 /solr/clusterstate.json
> [junit4] 2> 7 /solr/collections/falsepredicate/state.json
> [junit4] 2> 7 /solr/collections/testcollection/state.json
> [junit4] 2> 2 /solr/collections/currentstate/state.json
> [junit4] 2> 2 /solr/collections/delayed/state.json
> [junit4] 2> 2 /solr/collections/tobedeleted/state.json
> [junit4] 2> 2 /solr/collections/stateformat1/state.json
> [junit4] 2> 2 /solr/overseer_elect/election/96001988769808396-127.0.0.1:59509_solr-n_0000000002
> [junit4] 2>
> [junit4] 2> Maximum concurrent children watches above limit:
> [junit4] 2>
> [junit4] 2> 8 /solr/collections
> [junit4] 2> 5 /solr/live_nodes
> [junit4] 2> 2 /solr/overseer/queue
> [junit4] 2> 2 /solr/overseer/queue-work
> [junit4] 2> 2 /solr/overseer/collection-queue-work
> [junit4] 2>
> [junit4] 2> 179182 INFO (SUITE-TestCollectionStateWatchers-seed#[BF0ADABEC5EA6A6B]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
> [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.cloud.TestCollectionStateWatchers_BF0ADABEC5EA6A6B-001
> [junit4] 2> Jun 02, 2016 1:28:48 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
> [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=936, maxMBSortInHeap=7.986845666309913, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=tr, timezone=Europe/Bratislava
> [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_92 (32-bit)/cpus=3,threads=1,free=108757664,total=253755392
> [junit4] 2> NOTE: All tests run in this JVM: [TestDocumentObjectBinder, SchemaTest, TestHash, CloudSolrClientTest, ConcurrentUpdateSolrClientTest, TestJavaBinCodec, SolrDocumentTest, NamedListTest, JdbcDriverTest, FieldAnalysisResponseTest, SolrExampleXMLTest, TestUpdateRequestCodec, SolrExampleStreamingTest, TestSpellCheckResponse, SolrExampleEmbeddedTest, GraphExpressionTest, TestCollectionStateWatchers]
> [junit4] Completed [58/83 (1!)] on J0 in 32.87s, 9 tests, 1 failure <<< FAILURES!
>
> [...truncated 85 lines...]
> BUILD FAILED
> C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:740: The following error occurred while executing this line:
> C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:684: The following error occurred while executing this line:
> C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:59: The following error occurred while executing this line:
> C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build.xml:246: The following error occurred while executing this line:
> C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\common-build.xml:531: The following error occurred while executing this line:
> C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1427: The following error occurred while executing this line:
> C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:984: There were test failures: 83 suites, 641 tests, 1 failure, 2 ignored [seed: BF0ADABEC5EA6A6B]
>
> Total time: 79 minutes 8 seconds
> Build step 'Invoke Ant' marked build as failure
> Archiving artifacts
> [WARNINGS] Skipping publisher since build result is FAILURE
> Recording test results
> Email was triggered for: Failure - Any
> Sending email for trigger: Failure - Any
>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org