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/05/20 01:50:48 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 3283
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3283/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.schema.TestManagedSchemaAPI.test
Error Message:
Error from server at http://127.0.0.1:65332/solr/testschemaapi_shard1_replica2: ERROR: [doc=2] unknown field 'myNewField1'
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at http://127.0.0.1:65332/solr/testschemaapi_shard1_replica2: ERROR: [doc=2] unknown field 'myNewField1'
at __randomizedtesting.SeedInfo.seed([DA51344E5BA2A917:52050B94F55EC4EF]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:697)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1109)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934)
at org.apache.solr.schema.TestManagedSchemaAPI.testAddFieldAndDocument(TestManagedSchemaAPI.java:86)
at org.apache.solr.schema.TestManagedSchemaAPI.test(TestManagedSchemaAPI.java:55)
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)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:65332/solr/testschemaapi_shard1_replica2: ERROR: [doc=2] unknown field 'myNewField1'
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:606)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:366)
at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:674)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
... 1 more
Build Log:
[...truncated 12187 lines...]
[junit4] Suite: org.apache.solr.schema.TestManagedSchemaAPI
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/init-core-data-001
[junit4] 2> 3419079 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 3419084 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3419085 INFO (Thread-6711) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3419085 INFO (Thread-6711) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3419190 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:65329
[junit4] 2> 3419190 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3419191 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3419327 INFO (zkCallback-3452-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4959bf77 name:ZooKeeperConnection Watcher:127.0.0.1:65329 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3419327 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3419328 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3419328 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 3419349 INFO (jetty-launcher-3451-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 3419349 INFO (jetty-launcher-3451-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 3419357 INFO (jetty-launcher-3451-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@148416b0{/solr,null,AVAILABLE}
[junit4] 2> 3419363 INFO (jetty-launcher-3451-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@e1f5fec{/solr,null,AVAILABLE}
[junit4] 2> 3419367 INFO (jetty-launcher-3451-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@7056c2c6{HTTP/1.1,[http/1.1]}{127.0.0.1:65332}
[junit4] 2> 3419368 INFO (jetty-launcher-3451-thread-2) [ ] o.e.j.s.Server Started @3425506ms
[junit4] 2> 3419368 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=65332}
[junit4] 2> 3419367 INFO (jetty-launcher-3451-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@7f8eaeba{HTTP/1.1,[http/1.1]}{127.0.0.1:65331}
[junit4] 2> 3419368 INFO (jetty-launcher-3451-thread-1) [ ] o.e.j.s.Server Started @3425506ms
[junit4] 2> 3419368 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=65331}
[junit4] 2> 3419369 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3419369 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2'
[junit4] 2> 3419370 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3419370 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3419370 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3419370 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1'
[junit4] 2> 3419370 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3419371 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3419371 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3419372 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3419372 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3419374 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3419376 INFO (zkCallback-3453-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e20b867 name:ZooKeeperConnection Watcher:127.0.0.1:65329/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3419377 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3419377 INFO (zkCallback-3454-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c687c41 name:ZooKeeperConnection Watcher:127.0.0.1:65329/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3419377 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3419377 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3419378 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3419379 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3419379 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3419398 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/.
[junit4] 2> 3419399 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 1784834820
[junit4] 2> 3419399 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2]
[junit4] 2> 3419398 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/.
[junit4] 2> 3419399 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.CoreContainer New CoreContainer 2639679
[junit4] 2> 3419399 WARN (jetty-launcher-3451-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/lib
[junit4] 2> 3419399 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1]
[junit4] 2> 3419400 INFO (jetty-launcher-3451-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> 3419400 WARN (jetty-launcher-3451-thread-2) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/lib
[junit4] 2> 3419400 INFO (jetty-launcher-3451-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> 3419404 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
[junit4] 2> 3419404 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
[junit4] 2> 3419405 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3419405 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3419405 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65329/solr
[junit4] 2> 3419405 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3419405 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3419405 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 3419406 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65329/solr
[junit4] 2> 3419406 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 3419406 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3419406 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3419406 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3419408 INFO (zkCallback-3459-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29ca8339 name:ZooKeeperConnection Watcher:127.0.0.1:65329 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3419409 INFO (zkCallback-3460-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c618a75 name:ZooKeeperConnection Watcher:127.0.0.1:65329 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3419409 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3419409 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3419410 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3419410 INFO (jetty-launcher-3451-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3419410 INFO (jetty-launcher-3451-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3419416 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$67/1320903660@31fdf7c9
[junit4] 2> 3419416 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$67/1320903660@720d1b72
[junit4] 2> 3419418 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3419420 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3419421 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62c33a55 name:ZooKeeperConnection Watcher:127.0.0.1:65329/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3419421 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3419421 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6caba83 name:ZooKeeperConnection Watcher:127.0.0.1:65329/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3419421 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3419422 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 3419422 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 3419425 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 3419425 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 3419429 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 3419430 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 3419432 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 3419432 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 3419435 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 3419435 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 3419437 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 3419437 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 3419439 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 3419440 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 3419444 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 3419445 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 3419449 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 3419449 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 3419452 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 3419453 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 3419454 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3419455 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3419456 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 3419456 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 3419458 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 3419460 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 3419467 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 3419468 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 3419472 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 3419472 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 3419473 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3419474 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3419474 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95925620891582470-127.0.0.1:65331_solr-n_0000000001
[junit4] 2> 3419474 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95925620891582469-127.0.0.1:65332_solr-n_0000000000
[junit4] 2> 3419475 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:65332_solr
[junit4] 2> 3419475 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 3419476 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95925620891582469-127.0.0.1:65332_solr-n_0000000000 to know if I could be the leader
[junit4] 2> 3419476 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65331_solr
[junit4] 2> 3419477 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:65331_solr
[junit4] 2> 3419477 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer Overseer (id=95925620891582469-127.0.0.1:65332_solr-n_0000000000) starting
[junit4] 2> 3419479 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_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> 3419479 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_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> 3419480 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3419481 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3419482 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 3419491 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 3419492 INFO (OverseerCollectionConfigSetProcessor-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 3419493 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 3419493 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:65332_solr as DOWN
[junit4] 2> 3419495 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 3419497 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:65332_solr"} current state version: 0
[junit4] 2> 3419497 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:65332_solr
[junit4] 2> 3419510 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 3419510 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 3419513 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/.
[junit4] 2> 3419513 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 3419515 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 3419515 INFO (jetty-launcher-3451-thread-1) [n:127.0.0.1:65331_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 3420500 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65332_solr
[junit4] 2> 3420501 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:65332_solr
[junit4] 2> 3420503 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_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: [1])
[junit4] 2> 3420503 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_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: [1])
[junit4] 2> 3420504 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3420504 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3420517 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 3420518 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 3420520 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/.
[junit4] 2> 3420520 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 3420522 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 3420522 INFO (jetty-launcher-3451-thread-2) [n:127.0.0.1:65332_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 3420522 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3420523 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3420524 INFO (zkCallback-3465-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@73f6d181 name:ZooKeeperConnection Watcher:127.0.0.1:65329 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3420524 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3420524 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3420527 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:65329/solr
[junit4] 2> 3420528 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3420529 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3420530 INFO (zkCallback-3468-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@20933d29 name:ZooKeeperConnection Watcher:127.0.0.1:65329/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3420530 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3420530 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3420530 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3420532 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 3420533 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3420536 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/managed-schema
[junit4] 2> 3420541 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 3420568 INFO (TEST-TestManagedSchemaAPI.test-seed#[DA51344E5BA2A917]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 3420574 INFO (qtp1707044697-21965) [n:127.0.0.1:65331_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf1&name=testschemaapi&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3420578 INFO (OverseerCollectionConfigSetProcessor-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"testschemaapi",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 3420579 INFO (OverseerThreadFactory-9797-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"testschemaapi",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 3420579 INFO (OverseerThreadFactory-9797-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerCollectionMessageHandler Create collection testschemaapi
[junit4] 2> 3420580 INFO (OverseerThreadFactory-9797-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/testschemaapi
[junit4] 2> 3420581 INFO (OverseerThreadFactory-9797-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi
[junit4] 2> 3420583 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 3420583 INFO (zkCallback-3468-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 3420583 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 3420588 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"testschemaapi",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 3420588 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testschemaapi
[junit4] 2> 3420590 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testschemaapi/state.json
[junit4] 2> 3420690 INFO (OverseerThreadFactory-9797-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating SolrCores for new collection testschemaapi, shardNames [shard1] , replicationFactor : 2
[junit4] 2> 3420690 INFO (OverseerThreadFactory-9797-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testschemaapi_shard1_replica2 as part of shard shard1 of collection testschemaapi on 127.0.0.1:65332_solr
[junit4] 2> 3420691 INFO (OverseerThreadFactory-9797-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testschemaapi_shard1_replica1 as part of shard shard1 of collection testschemaapi on 127.0.0.1:65331_solr
[junit4] 2> 3420706 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=testschemaapi_shard1_replica1&action=CREATE&numShards=1&collection=testschemaapi&shard=shard1&wt=javabin&version=2
[junit4] 2> 3420708 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=testschemaapi_shard1_replica2&action=CREATE&numShards=1&collection=testschemaapi&shard=shard1&wt=javabin&version=2
[junit4] 2> 3420708 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testschemaapi_shard1_replica1, collection.configName=conf1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testschemaapi, numShards=1, dataDir=data/, shard=shard1}
[junit4] 2> 3420709 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testschemaapi_shard1_replica2, collection.configName=conf1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testschemaapi, numShards=1, dataDir=data/, shard=shard1}
[junit4] 2> 3420709 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3420709 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3420711 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Registering watch for collection testschemaapi
[junit4] 2> 3420711 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Registering watch for collection testschemaapi
[junit4] 2> 3420712 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader Add data for [testschemaapi] ver [0]
[junit4] 2> 3420712 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65332/solr",
[junit4] 2> "node_name":"127.0.0.1:65332_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3420712 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65332/solr",
[junit4] 2> "node_name":"127.0.0.1:65332_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"}
[junit4] 2> 3420712 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader Add data for [testschemaapi] ver [0]
[junit4] 2> 3420713 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3420713 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3420716 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"testschemaapi_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65331/solr",
[junit4] 2> "node_name":"127.0.0.1:65331_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3420716 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testschemaapi_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65331/solr",
[junit4] 2> "node_name":"127.0.0.1:65331_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"}
[junit4] 2> 3420853 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 0
[junit4] 2> 3420856 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3420857 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [0] to [1]
[junit4] 2> 3420857 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3420859 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [0] to [1]
[junit4] 2> 3421714 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testschemaapi_shard1_replica2
[junit4] 2> 3421714 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testschemaapi_shard1_replica1
[junit4] 2> 3421714 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Check for collection zkNode:testschemaapi
[junit4] 2> 3421714 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:testschemaapi
[junit4] 2> 3421715 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 3421715 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 3421715 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 3421715 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 3421716 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3421716 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3421716 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/testschemaapi_shard1_replica1'
[junit4] 2> 3421716 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/testschemaapi_shard1_replica2'
[junit4] 2> 3421716 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3421717 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3421716 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3421717 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3421719 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 3421719 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 3421724 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 3421724 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 3421741 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 3421762 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 3421802 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3421803 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3421806 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSchema [testschemaapi_shard1_replica1] Schema name=minimal
[junit4] 2> 3421806 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSchema [testschemaapi_shard1_replica2] Schema name=minimal
[junit4] 2> 3421811 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 3421812 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 3421814 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_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/conf1', cwd=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 3421814 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 3421815 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'testschemaapi_shard1_replica1' using configuration from collection testschemaapi
[junit4] 2> 3421815 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore 'testschemaapi_shard1_replica2' using configuration from collection testschemaapi
[junit4] 2> 3421815 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3421815 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3421815 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [[testschemaapi_shard1_replica1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/testschemaapi_shard1_replica1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/]
[junit4] 2> 3421815 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [[testschemaapi_shard1_replica2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/testschemaapi_shard1_replica2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/]
[junit4] 2> 3421815 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_replica1
[junit4] 2> 3421815 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_replica2
[junit4] 2> 3421816 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data
[junit4] 2> 3421816 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data
[junit4] 2> 3421818 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/index/
[junit4] 2> 3421818 WARN (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [testschemaapi_shard1_replica1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 3421819 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/index
[junit4] 2> 3421819 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/index/
[junit4] 2> 3421819 WARN (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [testschemaapi_shard1_replica2] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/index' doesn't exist. Creating new index...
[junit4] 2> 3421820 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/index
[junit4] 2> 3421823 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@35319140 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d5c9b30),segFN=segments_1,generation=1}
[junit4] 2> 3421823 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3421823 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@564b36c4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c4d031e),segFN=segments_1,generation=1}
[junit4] 2> 3421823 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3421824 INFO (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica1) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core testschemaapi_shard1_replica1 in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/
[junit4] 2> 3421824 WARN (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica1) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.DirectoryFactory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 3421826 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3421830 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3421831 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3421831 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3421832 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3421834 INFO (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica2) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore Looking for old index directories to cleanup for core testschemaapi_shard1_replica2 in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/
[junit4] 2> 3421835 WARN (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica2) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.DirectoryFactory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 3421836 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3421837 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3421838 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3421839 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3421840 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,/update/csv,/sql,/update/json/docs,/admin/luke,/graph,/export,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
[junit4] 2> 3421840 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 3421840 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3421843 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,/update/csv,/sql,/update/json/docs,/admin/luke,/graph,/export,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
[junit4] 2> 3421844 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 3421844 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3421845 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3421845 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3421845 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3421852 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3421852 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3421854 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3421855 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3421864 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@564b36c4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c4d031e),segFN=segments_1,generation=1}
[junit4] 2> 3421864 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3421865 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@35319140 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d5c9b30),segFN=segments_1,generation=1}
[junit4] 2> 3421866 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3421866 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@79790438[testschemaapi_shard1_replica1] main]
[junit4] 2> 3421867 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@25a0d4ba[testschemaapi_shard1_replica2] main]
[junit4] 2> 3421867 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 3421867 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 3421868 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3421868 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 3421868 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3421868 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 3421869 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 3421869 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 3421869 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3421869 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 3421869 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 3421869 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3421869 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 3421870 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3421870 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 3421870 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 3421870 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 3421870 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3421870 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 3421870 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 3421871 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Added new OnReconnect listener ZkIndexSchemaReader: /configs/conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica1:1166951218207985
[junit4] 2> 3421871 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 3421871 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 3421871 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 3421871 INFO (searcherExecutor-9802-thread-1-processing-n:127.0.0.1:65331_solr x:testschemaapi_shard1_replica1 s:shard1 c:testschemaapi r:core_node2) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [testschemaapi_shard1_replica1] Registered new searcher Searcher@79790438[testschemaapi_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3421871 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 3421871 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Added new OnReconnect listener ZkIndexSchemaReader: /configs/conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica2:1166951218279915
[junit4] 2> 3421871 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 3421872 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 3421872 INFO (searcherExecutor-9803-thread-1-processing-n:127.0.0.1:65332_solr x:testschemaapi_shard1_replica2 s:shard1 c:testschemaapi r:core_node1) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [testschemaapi_shard1_replica2] Registered new searcher Searcher@25a0d4ba[testschemaapi_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3421872 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 3421872 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 3421871 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1534809937182457856
[junit4] 2> 3421872 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1534809937183506432
[junit4] 2> 3421874 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1534809937183506432
[junit4] 2> 3421874 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1534809937182457856
[junit4] 2> 3421874 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 3421874 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 3421875 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.CoreContainer registering core: testschemaapi_shard1_replica1
[junit4] 2> 3421875 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Register replica - core:testschemaapi_shard1_replica1 address:http://127.0.0.1:65331/solr collection:testschemaapi shard:shard1
[junit4] 2> 3421875 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.CoreContainer registering core: testschemaapi_shard1_replica2
[junit4] 2> 3421875 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Register replica - core:testschemaapi_shard1_replica2 address:http://127.0.0.1:65332/solr collection:testschemaapi shard:shard1
[junit4] 2> 3421876 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi/leader_elect/shard1/election
[junit4] 2> 3421877 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi/leader_elect/shard1/election
[junit4] 2> 3421882 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.LeaderElector Joined leadership election with path: /collections/testschemaapi/leader_elect/shard1/election/95925620891582469-core_node1-n_0000000000
[junit4] 2> 3421882 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/testschemaapi/leader_elect/shard1/election/95925620891582470-core_node2-n_0000000001
[junit4] 2> 3421883 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000
[junit4] 2> 3421884 INFO (qtp1707044697-21966) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.LeaderElector Watching path /collections/testschemaapi/leader_elect/shard1/election/95925620891582469-core_node1-n_0000000000 to know if I could be the leader
[junit4] 2> 3421886 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 3421886 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi"} current state version: 0
[junit4] 2> 3421886 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 3421886 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:65332/solr/testschemaapi_shard1_replica2/
[junit4] 2> 3421887 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.PeerSync PeerSync: core=testschemaapi_shard1_replica2 url=http://127.0.0.1:65332/solr START replicas=[http://127.0.0.1:65331/solr/testschemaapi_shard1_replica1/] nUpdates=100
[junit4] 2> 3421889 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u.PeerSync PeerSync: core=testschemaapi_shard1_replica2 url=http://127.0.0.1:65332/solr DONE. We have no versions. sync failed.
[junit4] 2> 3421889 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 3421890 INFO (qtp1707044697-21967) [n:127.0.0.1:65331_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.S.Request [testschemaapi_shard1_replica1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3421891 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 3421893 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi/leaders/shard1
[junit4] 2> 3421897 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testschemaapi/leaders/shard1/leader after winning as /collections/testschemaapi/leader_elect/shard1/election/95925620891582469-core_node1-n_0000000000
[junit4] 2> 3421901 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:65332/solr/testschemaapi_shard1_replica2/ shard1
[junit4] 2> 3421902 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "base_url":"http://127.0.0.1:65332/solr",
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 3422007 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 1
[junit4] 2> 3422008 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3422009 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3422009 INFO (zkCallback-3462-thread-1-processing-n:127.0.0.1:65331_solr) [n:127.0.0.1:65331_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [1] to [2]
[junit4] 2> 3422010 INFO (zkCallback-3461-thread-1-processing-n:127.0.0.1:65332_solr) [n:127.0.0.1:65332_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [1] to [2]
[junit4] 2> 3422058 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController We are http://127.0.0.1:65332/solr/testschemaapi_shard1_replica2/ and leader is http://127.0.0.1:65332/solr/testschemaapi_shard1_replica2/
[junit4] 2> 3422058 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController No LogReplay needed for core=testschemaapi_shard1_replica2 baseURL=http://127.0.0.1:65332/solr
[junit4] 2> 3422058 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 3422058 INFO (qtp1407607667-21972) [n:127.0.0.1:65332_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController publishing state=active
[junit4] 2> 3422061 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65332/solr",
[junit4] 2> "node_name":"127.0.0.1:65332_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3422061 INFO (OverseerStateUpdate-95925620891582469-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65332/solr"
[...truncated too long message...]
unit4] 2> 3434405 INFO (jetty-launcher-3451-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@e1f5fec{/solr,null,UNAVAILABLE}
[junit4] 2> 3434417 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65329 65329
[junit4] 2> 3438661 INFO (Thread-6711) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65329 65329
[junit4] 2> 3441050 WARN (Thread-6711) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/configs/conf1
[junit4] 2> 5 /solr/configs/conf1/managed-schema
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/testschemaapi/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 3441051 INFO (SUITE-TestManagedSchemaAPI-seed#[DA51344E5BA2A917]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_DA51344E5BA2A917-001
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=2, maxDocsPerChunk=658, blockSize=6), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=2, blockSize=6)), sim=ClassicSimilarity, locale=fr-CH, timezone=SystemV/MST7MDT
[junit4] 2> NOTE: Mac OS X 10.11.4 x86_64/Oracle Corporation 1.8.0_72 (64-bit)/cpus=3,threads=1,free=283567680,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [LukeRequestHandlerTest, TestInitParams, TestFieldTypeCollectionResource, TestFilteredDocIdSet, QueryResultKeyTest, DebugComponentTest, TestRangeQuery, TestPerFieldSimilarityWithDefaultOverride, OverseerTaskQueueTest, TestSchemaNameResource, TestStressUserVersions, ShowFileRequestHandlerTest, TestMaxScoreQueryParser, BasicZkTest, ResponseLogComponentTest, ExplicitHLLTest, SharedFSAutoReplicaFailoverUtilsTest, DistanceUnitsTest, DistributedIntervalFacetingTest, VersionInfoTest, DirectUpdateHandlerOptimizeTest, CursorMarkTest, TestLRUStatsCache, JavabinLoaderTest, CoreMergeIndexesAdminHandlerTest, DateRangeFieldTest, DeleteReplicaTest, TestDFISimilarityFactory, SuggesterWFSTTest, CollectionReloadTest, BadCopyFieldTest, TestQueryUtils, BlockCacheTest, TestFastWriter, SimpleMLTQParserTest, TestSortingResponseWriter, DataDrivenBlockJoinTest, TestHashQParserPlugin, HLLSerializationTest, DefaultValueUpdateProcessorTest, HdfsLockFactoryTest, TestSolr4Spatial, TestRealTimeGet, DistributedDebugComponentTest, TestCollationField, TestCharFilters, ExitableDirectoryReaderTest, LeaderInitiatedRecoveryOnShardRestartTest, RemoteQueryErrorTest, TestCrossCoreJoin, TestDistributedMissingSort, MinimalSchemaTest, TestRandomRequestDistribution, TestScoreJoinQPNoScore, HdfsBasicDistributedZkTest, DistribDocExpirationUpdateProcessorTest, CurrencyFieldOpenExchangeTest, HighlighterConfigTest, TestSubQueryTransformerDistrib, TestReplicationHandlerBackup, TestSolrDeletionPolicy2, DistanceFunctionTest, StatsComponentTest, BigEndianAscendingWordSerializerTest, SuggesterTSTTest, CleanupOldIndexTest, TestIntervalFaceting, EchoParamsTest, TestRecoveryHdfs, SolrInfoMBeanTest, TestSchemaSimilarityResource, SpellCheckCollatorTest, CopyFieldTest, ZkNodePropsTest, TestDistributedStatsComponentCardinality, SolrPluginUtilsTest, TestOmitPositions, TestSolrFieldCacheMBean, SearchHandlerTest, TestQueryTypes, TestCloudPivotFacet, TestConfigSetImmutable, TestManagedResourceStorage, RAMDirectoryFactoryTest, TestSolrIndexConfig, FieldAnalysisRequestHandlerTest, SuggesterTest, DistributedQueryComponentOptimizationTest, CSVRequestHandlerTest, BlobRepositoryCloudTest, TestCustomDocTransformer, DistributedFacetPivotLargeTest, TestSolrCloudWithKerberosAlt, LeaderElectionIntegrationTest, TestSolrJ, TermVectorComponentDistributedTest, TestFreeTextSuggestions, TestBlobHandler, TestSearcherReuse, EnumFieldTest, CdcrReplicationHandlerTest, TestCSVResponseWriter, TestExactSharedStatsCache, CreateCollectionCleanupTest, TestWriterPerf, TestMiniSolrCloudClusterBase, TestSortByMinMaxFunction, TestBulkSchemaAPI, MultiTermTest, FileBasedSpellCheckerTest, TestJettySolrRunner, PluginInfoTest, TestMiniSolrCloudClusterSSL, TestRTimerTree, RestartWhileUpdatingTest, TestRequestStatusCollectionAPI, SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest, DirectSolrConnectionTest, DateFieldTest, IndexSchemaRuntimeFieldTest, AutoCommitTest, TestSha256AuthenticationProvider, TestBinaryResponseWriter, ShardRoutingCustomTest, WordBreakSolrSpellCheckerTest, TestReloadAndDeleteDocs, CollectionTooManyReplicasTest, ZkStateWriterTest, TestBadConfig, UniqFieldsUpdateProcessorFactoryTest, DistributedMLTComponentTest, TestBM25SimilarityFactory, TestQuerySenderListener, TestDistributedGrouping, DistributedFacetPivotWhiteBoxTest, CdcrVersionReplicationTest, TestConfigSets, TestFieldCollectionResource, SortByFunctionTest, TestReplicaProperties, LeaderFailoverAfterPartitionTest, TestValueSourceCache, CachingDirectoryFactoryTest, HdfsTlogReplayBufferedWhileIndexingTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest, LeaderElectionTest, TestZkChroot, ZkControllerTest, HardAutoCommitTest, TestCoreContainer, SpellCheckComponentTest, SolrCmdDistributorTest, TestFiltering, BasicFunctionalityTest, HighlighterTest, CurrencyFieldXmlFileTest, SolrIndexSplitterTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, CoreAdminHandlerTest, SpatialFilterTest, NoCacheHeaderTest, TestAtomicUpdateErrorCases, TestRemoteStreaming, CacheHeaderTest, IndexBasedSpellCheckerTest, StandardRequestHandlerTest, XmlUpdateRequestHandlerTest, TermVectorComponentTest, TestSolrQueryParser, RegexBoostProcessorTest, ReturnFieldsTest, QueryParsingTest, TestPartialUpdateDeduplication, UpdateParamsTest, TestMergePolicyConfig, SampleTest, TestDocSet, TestBinaryField, NumericFieldsTest, TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, SynonymTokenizerTest, TestXIncludeConfig, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, ResourceLoaderTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, DateMathParserTest, TestSystemIdResolver, PrimUtilsTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, ClusterStateTest, TestLRUCache, TestUtils, TestDocumentBuilder, UUIDFieldTest, FileUtilsTest, TestRTGBase, TestCursorMarkWithoutUniqueKey, TestSimpleTrackingShardHandler, TestEmbeddedSolrServerConstructors, AliasIntegrationTest, BaseCdcrDistributedZkTest, CdcrReplicationDistributedZkTest, CloudExitableDirectoryReaderTest, CollectionStateFormat2Test, ConcurrentDeleteAndCreateCollectionTest, ConfigSetsAPITest, ConnectionManagerTest, CustomCollectionTest, DistributedQueueTest, DistributedVersionInfoTest, HttpPartitionTest, MultiThreadedOCPTest, OverseerRolesTest, ShardSplitTest, TestCloudBackupRestore, TestCloudDeleteByQuery, TestConfigSetsAPI, TestConfigSetsAPIZkFailure, TestDistribDocBasedVersion, TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry, TestLeaderInitiatedRecoveryThread, TestTolerantUpdateProcessorCloud, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, RuleEngineTest, RulesTest, DistributedFacetPivotLongTailTest, FacetPivotSmallTest, TestExpandComponent, TestPivotHelperCode, WrapperMergePolicyFactoryTest, CheckHdfsIndexTest, TestMacros, TestGraphMLResponseWriter, TestChildDocTransformer, TestRestManager, TestFieldResource, TestSchemaResource, TestSerializedLuceneMatchVersion, TestSolrQueryParserResource, TestManagedStopFilterFactory, ChangedSchemaMergeTest, DocValuesMissingTest, TestBulkSchemaConcurrent, TestCollationFieldDocValues, TestManagedSchema, TestManagedSchemaAPI]
[junit4] Completed [537/597 (1!)] on J1 in 22.32s, 1 test, 1 error <<< FAILURES!
[...truncated 194 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:740: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:684: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build.xml:233: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/common-build.xml:529: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1458: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1015: There were test failures: 597 suites (10 ignored), 2428 tests, 1 error, 121 ignored (71 assumptions) [seed: DA51344E5BA2A917]
Total time: 84 minutes 32 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
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 3285
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3285/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.lucene.benchmark.byTask.TestPerfTasksLogic.testHighlighting
Error Message:
org/apache/lucene/spatial/geopoint/search/GeoPointInBBoxQuery
Stack Trace:
java.lang.NoClassDefFoundError: org/apache/lucene/spatial/geopoint/search/GeoPointInBBoxQuery
at __randomizedtesting.SeedInfo.seed([72732A99BDA22D35:FEF26C7DCA98CC60]:0)
at org.apache.lucene.search.highlight.WeightedSpanTermExtractor.extract(WeightedSpanTermExtractor.java:215)
at org.apache.lucene.search.highlight.WeightedSpanTermExtractor.getWeightedSpanTerms(WeightedSpanTermExtractor.java:511)
at org.apache.lucene.search.highlight.QueryScorer.initExtractor(QueryScorer.java:218)
at org.apache.lucene.search.highlight.QueryScorer.init(QueryScorer.java:186)
at org.apache.lucene.search.highlight.Highlighter.getBestTextFragments(Highlighter.java:195)
at org.apache.lucene.benchmark.byTask.tasks.CountingHighlighterTestTask$1.doHighlight(CountingHighlighterTestTask.java:62)
at org.apache.lucene.benchmark.byTask.tasks.ReadTask.doLogic(ReadTask.java:170)
at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doSerialTasks(TaskSequence.java:197)
at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doLogic(TaskSequence.java:138)
at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doSerialTasks(TaskSequence.java:197)
at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doLogic(TaskSequence.java:138)
at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
at org.apache.lucene.benchmark.byTask.utils.Algorithm.execute(Algorithm.java:332)
at org.apache.lucene.benchmark.byTask.Benchmark.execute(Benchmark.java:77)
at org.apache.lucene.benchmark.BenchmarkTestCase.execBenchmark(BenchmarkTestCase.java:75)
at org.apache.lucene.benchmark.byTask.TestPerfTasksLogic.testHighlighting(TestPerfTasksLogic.java:183)
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 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 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)
Caused by: java.lang.ClassNotFoundException: org.apache.lucene.spatial.geopoint.search.GeoPointInBBoxQuery
at java.net.URLClassLoader.findClass(URLClassLoader.java:381)
at java.lang.ClassLoader.loadClass(ClassLoader.java:424)
at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:331)
at java.lang.ClassLoader.loadClass(ClassLoader.java:357)
... 54 more
FAILED: org.apache.lucene.benchmark.byTask.TestPerfTasksLogic.testHighlightingTV
Error Message:
org/apache/lucene/spatial/geopoint/search/GeoPointInBBoxQuery
Stack Trace:
java.lang.NoClassDefFoundError: org/apache/lucene/spatial/geopoint/search/GeoPointInBBoxQuery
at __randomizedtesting.SeedInfo.seed([72732A99BDA22D35:12510CEF22BDC5AC]:0)
at org.apache.lucene.search.highlight.WeightedSpanTermExtractor.extract(WeightedSpanTermExtractor.java:215)
at org.apache.lucene.search.highlight.WeightedSpanTermExtractor.getWeightedSpanTerms(WeightedSpanTermExtractor.java:511)
at org.apache.lucene.search.highlight.QueryScorer.initExtractor(QueryScorer.java:218)
at org.apache.lucene.search.highlight.QueryScorer.init(QueryScorer.java:186)
at org.apache.lucene.search.highlight.Highlighter.getBestTextFragments(Highlighter.java:195)
at org.apache.lucene.benchmark.byTask.tasks.CountingHighlighterTestTask$1.doHighlight(CountingHighlighterTestTask.java:62)
at org.apache.lucene.benchmark.byTask.tasks.ReadTask.doLogic(ReadTask.java:170)
at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doSerialTasks(TaskSequence.java:197)
at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doLogic(TaskSequence.java:138)
at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doSerialTasks(TaskSequence.java:197)
at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doLogic(TaskSequence.java:138)
at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
at org.apache.lucene.benchmark.byTask.utils.Algorithm.execute(Algorithm.java:332)
at org.apache.lucene.benchmark.byTask.Benchmark.execute(Benchmark.java:77)
at org.apache.lucene.benchmark.BenchmarkTestCase.execBenchmark(BenchmarkTestCase.java:75)
at org.apache.lucene.benchmark.byTask.TestPerfTasksLogic.testHighlightingTV(TestPerfTasksLogic.java:223)
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 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 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 5555 lines...]
[junit4] Suite: org.apache.lucene.benchmark.byTask.TestPerfTasksLogic
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: CreateIndex
[junit4] 1> ------------> starting task: Seq_Exhaust
[junit4] 1> ------------> starting task: Seq_Exhaust
[junit4] 1> ------------> starting task: CloseIndex
[junit4] 1> ------------> starting task: Rounds_2
[junit4] 1> Changed Locale to: root locale
[junit4] 1> Changed Analyzer to: org.apache.lucene.collation.CollationKeyAnalyzer()
[junit4] 1>
[junit4] 1> --> Round 0-->1
[junit4] 1>
[junit4] 1> Changed Locale to: de
[junit4] 1> Changed Analyzer to: org.apache.lucene.collation.CollationKeyAnalyzer(de)
[junit4] 1>
[junit4] 1> --> Round 0-->1
[junit4] 1>
[junit4] 1> Changed Locale to: en_US
[junit4] 1> Changed Analyzer to: org.apache.lucene.collation.CollationKeyAnalyzer(en_US)
[junit4] 1>
[junit4] 1> --> Round 0-->1
[junit4] 1>
[junit4] 1> Changed Locale to: no_NO_NY
[junit4] 1> Changed Analyzer to: org.apache.lucene.collation.CollationKeyAnalyzer(no_NO_NY)
[junit4] 1>
[junit4] 1> --> Round 0-->1
[junit4] 1>
[junit4] 1> ------------> starting task: Seq
[junit4] 1>
[junit4] 1> ------------> Report Sum By Prefix (X) (1 about 1 out of 1012)
[junit4] 1> Operation round runCnt recsPerRun rec/s elapsedSec avgUsedMem avgTotalMem
[junit4] 1> XSearch_2_Par 0 1 3924 7,445.92 0.53 17,461,440 54,853,632
[junit4] 1>
[junit4] 1> ------------> starting task: Seq
[junit4] 1> 0.09 sec --> TEST-TestPerfTasksLogic.testIndexAndSearchTasks-seed#[72732A99BDA22D35] added 1000 docs
[junit4] 1> ------------> starting task: Seq
[junit4] 1> 0.27 sec --> TEST-TestPerfTasksLogic.testHighlightingNoTvNoStore-seed#[72732A99BDA22D35] added 1000 docs
[junit4] 1> ------------> starting task: Seq
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestPerfTasksLogic -Dtests.method=testHighlighting -Dtests.seed=72732A99BDA22D35 -Dtests.slow=true -Dtests.locale=el-GR -Dtests.timezone=America/Cayenne -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.58s J0 | TestPerfTasksLogic.testHighlighting <<<
[junit4] > Throwable #1: java.lang.NoClassDefFoundError: org/apache/lucene/spatial/geopoint/search/GeoPointInBBoxQuery
[junit4] > at __randomizedtesting.SeedInfo.seed([72732A99BDA22D35:FEF26C7DCA98CC60]:0)
[junit4] > at org.apache.lucene.search.highlight.WeightedSpanTermExtractor.extract(WeightedSpanTermExtractor.java:215)
[junit4] > at org.apache.lucene.search.highlight.WeightedSpanTermExtractor.getWeightedSpanTerms(WeightedSpanTermExtractor.java:511)
[junit4] > at org.apache.lucene.search.highlight.QueryScorer.initExtractor(QueryScorer.java:218)
[junit4] > at org.apache.lucene.search.highlight.QueryScorer.init(QueryScorer.java:186)
[junit4] > at org.apache.lucene.search.highlight.Highlighter.getBestTextFragments(Highlighter.java:195)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.CountingHighlighterTestTask$1.doHighlight(CountingHighlighterTestTask.java:62)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.ReadTask.doLogic(ReadTask.java:170)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doSerialTasks(TaskSequence.java:197)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doLogic(TaskSequence.java:138)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doSerialTasks(TaskSequence.java:197)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doLogic(TaskSequence.java:138)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
[junit4] > at org.apache.lucene.benchmark.byTask.utils.Algorithm.execute(Algorithm.java:332)
[junit4] > at org.apache.lucene.benchmark.byTask.Benchmark.execute(Benchmark.java:77)
[junit4] > at org.apache.lucene.benchmark.BenchmarkTestCase.execBenchmark(BenchmarkTestCase.java:75)
[junit4] > at org.apache.lucene.benchmark.byTask.TestPerfTasksLogic.testHighlighting(TestPerfTasksLogic.java:183)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.lang.ClassNotFoundException: org.apache.lucene.spatial.geopoint.search.GeoPointInBBoxQuery
[junit4] > at java.net.URLClassLoader.findClass(URLClassLoader.java:381)
[junit4] > at java.lang.ClassLoader.loadClass(ClassLoader.java:424)
[junit4] > at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:331)
[junit4] > at java.lang.ClassLoader.loadClass(ClassLoader.java:357)
[junit4] > ... 54 more
[junit4] 1> ------------> starting task: Seq
[junit4] 1> Changed Locale to: null
[junit4] 1>
[junit4] 1> --> Round 0-->1
[junit4] 1>
[junit4] 1> Changed Locale to: root locale
[junit4] 1>
[junit4] 1> --> Round 0-->1
[junit4] 1>
[junit4] 1> Changed Locale to: de
[junit4] 1>
[junit4] 1> --> Round 0-->1
[junit4] 1>
[junit4] 1> Changed Locale to: en_US
[junit4] 1>
[junit4] 1> --> Round 0-->1
[junit4] 1>
[junit4] 1> Changed Locale to: no_NO_NY
[junit4] 1>
[junit4] 1> --> Round 0-->1
[junit4] 1>
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Rounds
[junit4] 1>
[junit4] 1> ------------> Report Sum By (any) Name (4 about 4 out of 5)
[junit4] 1> Operation round runCnt recsPerRun rec/s elapsedSec avgUsedMem avgTotalMem
[junit4] 1> Rounds 0 1 20 289.86 0.07 14,181,232 54,853,632
[junit4] 1> CreateIndex - - - 0 - - 1 - - - - 0 - - - 0.00 - - 0.00 - 11,794,312 - 54,853,632
[junit4] 1> AddDocs_Exhaust 0 1 20 10,000.00 0.00 12,223,168 54,853,632
[junit4] 1> CloseIndex - - - 0 - - 1 - - - - 0 - - - 0.00 - - 0.03 - 14,181,232 - 54,853,632
[junit4] 1>
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Rounds
[junit4] 1>
[junit4] 1> ------------> Report Sum By (any) Name (4 about 4 out of 5)
[junit4] 1> Operation round runCnt recsPerRun rec/s elapsedSec avgUsedMem avgTotalMem
[junit4] 1> Rounds 0 1 22 180.33 0.12 14,183,088 54,853,632
[junit4] 1> CreateIndex - - - 0 - - 1 - - - - 1 - 1,000.00 - - 0.00 - 11,794,080 - 54,853,632
[junit4] 1> AddDocs_Exhaust 0 1 20 10,000.00 0.00 12,225,024 54,853,632
[junit4] 1> CloseIndex - - - 0 - - 1 - - - - 1 - - 33.33 - - 0.03 - 14,183,088 - 54,853,632
[junit4] 1>
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 1> 0.75 sec --> TEST-TestPerfTasksLogic.testHighlightingTV-seed#[72732A99BDA22D35] added 1000 docs
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestPerfTasksLogic -Dtests.method=testHighlightingTV -Dtests.seed=72732A99BDA22D35 -Dtests.slow=true -Dtests.locale=el-GR -Dtests.timezone=America/Cayenne -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 1.03s J0 | TestPerfTasksLogic.testHighlightingTV <<<
[junit4] > Throwable #1: java.lang.NoClassDefFoundError: org/apache/lucene/spatial/geopoint/search/GeoPointInBBoxQuery
[junit4] > at __randomizedtesting.SeedInfo.seed([72732A99BDA22D35:12510CEF22BDC5AC]:0)
[junit4] > at org.apache.lucene.search.highlight.WeightedSpanTermExtractor.extract(WeightedSpanTermExtractor.java:215)
[junit4] > at org.apache.lucene.search.highlight.WeightedSpanTermExtractor.getWeightedSpanTerms(WeightedSpanTermExtractor.java:511)
[junit4] > at org.apache.lucene.search.highlight.QueryScorer.initExtractor(QueryScorer.java:218)
[junit4] > at org.apache.lucene.search.highlight.QueryScorer.init(QueryScorer.java:186)
[junit4] > at org.apache.lucene.search.highlight.Highlighter.getBestTextFragments(Highlighter.java:195)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.CountingHighlighterTestTask$1.doHighlight(CountingHighlighterTestTask.java:62)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.ReadTask.doLogic(ReadTask.java:170)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doSerialTasks(TaskSequence.java:197)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doLogic(TaskSequence.java:138)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doSerialTasks(TaskSequence.java:197)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.TaskSequence.doLogic(TaskSequence.java:138)
[junit4] > at org.apache.lucene.benchmark.byTask.tasks.PerfTask.runAndMaybeStats(PerfTask.java:146)
[junit4] > at org.apache.lucene.benchmark.byTask.utils.Algorithm.execute(Algorithm.java:332)
[junit4] > at org.apache.lucene.benchmark.byTask.Benchmark.execute(Benchmark.java:77)
[junit4] > at org.apache.lucene.benchmark.BenchmarkTestCase.execBenchmark(BenchmarkTestCase.java:75)
[junit4] > at org.apache.lucene.benchmark.byTask.TestPerfTasksLogic.testHighlightingTV(TestPerfTasksLogic.java:223)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 1>
[junit4] 1> --> Round 0-->1: doc.term.vector:false-->true compound:true-->false
[junit4] 1>
[junit4] 1>
[junit4] 1> --> Round 1-->2: doc.term.vector:true-->false compound:false-->true
[junit4] 1>
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 1> ------------> starting task: Seq
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/build/benchmark/test/J0/temp/lucene.benchmark.byTask.TestPerfTasksLogic_72732A99BDA22D35-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {country=PostingsFormat(name=Memory doPackFST= false), random_string=FST50, $full_path$=FST50, $facets=Lucene50(blocksize=128), sort_field=PostingsFormat(name=Memory doPackFST= true), docid=PostingsFormat(name=Memory doPackFST= true), $payloads$=PostingsFormat(name=Memory doPackFST= true), body=PostingsFormat(name=Memory doPackFST= false), docname=PostingsFormat(name=Memory doPackFST= true), docdate=PostingsFormat(name=Memory doPackFST= false), doctitle=Lucene50(blocksize=128)}, docValues:{$facets=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=166, maxMBSortInHeap=5.737755341439696, sim=ClassicSimilarity, locale=el-GR, timezone=America/Cayenne
[junit4] 2> NOTE: Mac OS X 10.11.4 x86_64/Oracle Corporation 1.8.0_72 (64-bit)/cpus=3,threads=1,free=39806608,total=54853632
[junit4] 2> NOTE: All tests run in this JVM: [CreateIndexTaskTest, StreamUtilsTest, TestPerfTasksLogic]
[junit4] Completed [13/18 (1!)] on J0 in 11.86s, 24 tests, 2 errors <<< FAILURES!
[...truncated 26 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:740: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:684: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/build.xml:476: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:2188: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/module-build.xml:58: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1458: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1015: There were test failures: 18 suites, 93 tests, 2 errors [seed: 72732A99BDA22D35]
Total time: 16 minutes 14 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
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 3284
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3284/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.util.TestSolrCLIRunExample.testInteractiveSolrCloudExample
Error Message:
Expected 10 to be found in the testCloudExamplePrompt collection but only found 3
Stack Trace:
java.lang.AssertionError: Expected 10 to be found in the testCloudExamplePrompt collection but only found 3
at __randomizedtesting.SeedInfo.seed([E1DE3034EF6792C3:3AAFD0FED81257A5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.util.TestSolrCLIRunExample.testInteractiveSolrCloudExample(TestSolrCLIRunExample.java:457)
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 12361 lines...]
[junit4] Suite: org.apache.solr.util.TestSolrCLIRunExample
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/init-core-data-001
[junit4] 2> 3555051 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSchemalessExample
[junit4] 2> 3555052 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.u.TestSolrCLIRunExample Selected port 64570 to start schemaless example Solr instance on ...
[junit4] 2> 3555206 INFO (Thread-7444) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 3555208 INFO (Thread-7444) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@231ce113{/solr,null,AVAILABLE}
[junit4] 2> 3555215 INFO (Thread-7444) [ ] o.e.j.s.ServerConnector Started ServerConnector@44a64e36{HTTP/1.1,[http/1.1]}{127.0.0.1:64570}
[junit4] 2> 3555215 INFO (Thread-7444) [ ] o.e.j.s.Server Started @3560305ms
[junit4] 2> 3555215 INFO (Thread-7444) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64570}
[junit4] 2> 3555215 INFO (Thread-7444) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3555215 INFO (Thread-7444) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr'
[junit4] 2> 3555215 INFO (Thread-7444) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3555215 INFO (Thread-7444) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3555216 INFO (Thread-7444) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/solr.xml
[junit4] 2> 3555218 ERROR (qtp281116386-44699) [ ] o.a.s.s.SolrDispatchFilter Error processing the request. CoreContainer is either not initialized or shutting down.
[junit4] 2> 3555218 WARN (qtp281116386-44699) [ ] o.e.j.s.ServletHandler /solr/admin/info/system
[junit4] 2> org.apache.solr.common.SolrException: Error processing the request. CoreContainer is either not initialized or shutting down.
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:217)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:111)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:518)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3555222 INFO (Thread-7444) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr
[junit4] 2> 3555223 INFO (Thread-7444) [ ] o.a.s.c.CoreContainer New CoreContainer 442952293
[junit4] 2> 3555223 INFO (Thread-7444) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr]
[junit4] 2> 3555223 WARN (Thread-7444) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/lib
[junit4] 2> 3555223 INFO (Thread-7444) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 3555224 INFO (Thread-7444) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
[junit4] 2> 3555224 INFO (Thread-7444) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3555225 INFO (Thread-7444) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3555225 INFO (Thread-7444) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 3555225 INFO (Thread-7444) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 3555226 INFO (Thread-7444) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr
[junit4] 2> 3555226 INFO (Thread-7444) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 3555226 INFO (Thread-7444) [ ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 3555227 INFO (Thread-7444) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 3557279 INFO (qtp281116386-44702) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=55
[junit4] 2> 3557285 INFO (qtp281116386-44699) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=gettingstarted&action=STATUS&wt=json} status=0 QTime=0
[junit4] 2> 3557315 INFO (qtp281116386-44703) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=22
[junit4] 2> 3557345 INFO (qtp281116386-44704) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=21
[junit4] 2> 3557350 INFO (qtp281116386-44702) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=gettingstarted&action=STATUS&wt=json} status=0 QTime=0
[junit4] 2> 3557556 INFO (qtp281116386-44697) [ ] o.a.s.h.a.CoreAdminOperation core create command name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json
[junit4] 2> 3557557 INFO (qtp281116386-44697) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=gettingstarted, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 3557557 INFO (qtp281116386-44697) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted'
[junit4] 2> 3557557 INFO (qtp281116386-44697) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3557557 INFO (qtp281116386-44697) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3557564 INFO (qtp281116386-44697) [ ] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 3557565 INFO (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 3557566 WARN (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/extraction/lib
[junit4] 2> 3557566 WARN (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 3557567 WARN (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/clustering/lib
[junit4] 2> 3557567 WARN (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 3557568 WARN (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/langid/lib
[junit4] 2> 3557568 WARN (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 3557568 WARN (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/velocity/lib
[junit4] 2> 3557569 WARN (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 3557574 INFO (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 3557614 INFO (qtp281116386-44697) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3557622 INFO (qtp281116386-44697) [ ] o.a.s.s.IndexSchema [gettingstarted] Schema name=example-data-driven-schema
[junit4] 2> 3557759 INFO (qtp281116386-44697) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 3557793 INFO (qtp281116386-44697) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3557796 INFO (qtp281116386-44697) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3557816 INFO (qtp281116386-44697) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted'
[junit4] 2> 3557816 INFO (qtp281116386-44697) [ ] o.a.s.c.CoreContainer Creating SolrCore 'gettingstarted' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted
[junit4] 2> 3557817 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3557817 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data/]
[junit4] 2> 3557817 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5bf209d3
[junit4] 2> 3557818 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Added SolrEventListener for newSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}]
[junit4] 2> 3557818 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Added SolrEventListener for firstSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}]
[junit4] 2> 3557818 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SchemaCodecFactory Using default compressionMode: BEST_SPEED
[junit4] 2> 3557819 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data
[junit4] 2> 3557820 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data/index/
[junit4] 2> 3557820 WARN (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data/index' doesn't exist. Creating new index...
[junit4] 2> 3557821 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data/index
[junit4] 2> 3557824 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6ce87650 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6bfddbd8),segFN=segments_1,generation=1}
[junit4] 2> 3557824 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3557825 INFO (OldIndexDirectoryCleanupThreadForCore-gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore Looking for old index directories to cleanup for core gettingstarted in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data/
[junit4] 2> 3557825 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.PluginBag queryResponseWriter : 'velocity' created with startup=lazy
[junit4] 2> 3557825 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 3557825 WARN (OldIndexDirectoryCleanupThreadForCore-gettingstarted) [ x:gettingstarted] o.a.s.c.DirectoryFactory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 3557828 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 3557829 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3557833 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3557834 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3557835 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3557836 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3557840 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/update/extract' created with startup=lazy
[junit4] 2> 3557841 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/analysis/field' created with startup=lazy
[junit4] 2> 3557841 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/analysis/document' created with startup=lazy
[junit4] 2> 3557841 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/spell' created with startup=lazy
[junit4] 2> 3557841 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/tvrh' created with startup=lazy
[junit4] 2> 3557841 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/terms' created with startup=lazy
[junit4] 2> 3557841 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/elevate' created with startup=lazy
[junit4] 2> 3557841 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,/browse,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/elevate,/update/json,/admin/threads,/query,/analysis/field,/analysis/document,/spell,/update/csv,/sql,/graph,/tvrh,/select,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/debug/dump,/terms,/update/extract
[junit4] 2> 3557841 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 3557842 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3557842 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3557843 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 3557843 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3557843 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6ce87650 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6bfddbd8),segFN=segments_1,generation=1}
[junit4] 2> 3557844 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3557844 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.s.SolrIndexSearcher Opening [Searcher@78c9d27a[gettingstarted] main]
[junit4] 2> 3557844 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/conf
[junit4] 2> 3557844 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/conf}
[junit4] 2> 3557844 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/conf
[junit4] 2> 3557844 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 3557845 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 3557845 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 3557845 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent No queryConverter defined, using default converter
[junit4] 2> 3557845 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/conf/elevate.xml
[junit4] 2> 3557849 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 3557849 INFO (searcherExecutor-11521-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@78c9d27a[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3557849 INFO (searcherExecutor-11521-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 3557849 INFO (searcherExecutor-11521-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 3557850 INFO (searcherExecutor-11521-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Registered new searcher Searcher@78c9d27a[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3557851 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 3557851 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 3557851 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 3557851 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1534846095044116480
[junit4] 2> 3557853 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1534846095044116480
[junit4] 2> 3557853 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.c.CoreContainer registering core: gettingstarted
[junit4] 2> 3557854 INFO (qtp281116386-44697) [ x:gettingstarted] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json} status=0 QTime=297
[junit4] 2> 3557856 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[E1DE3034EF6792C3]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@44a64e36{HTTP/1.1,[http/1.1]}{127.0.0.1:64570}
[junit4] 2> 3557856 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=442952293
[junit4] 2> 3557857 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] CLOSING SolrCore org.apache.solr.core.SolrCore@2c6ebcb7
[junit4] 2> 3557857 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommit maxTime=15000ms,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 3557857 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 3557857 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 3557857 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 3557858 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Closing main searcher on request.
[junit4] 2> 3557863 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 3557864 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data;done=false>>]
[junit4] 2> 3557864 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 3557864 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data
[junit4] 2> 3557864 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data/index;done=false>>]
[junit4] 2> 3557864 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 3557864 INFO (coreCloseExecutor-11526-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-001/schemaless/solr/gettingstarted/data/index
[junit4] 2> 3557865 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[E1DE3034EF6792C3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@231ce113{/solr,null,UNAVAILABLE}
[junit4] 2> 3557866 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.u.TestSolrCLIRunExample Stopped standalone Solr instance running on port 64570
[junit4] 2> 3557866 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSchemalessExample
[junit4] 2> 3557868 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInteractiveSolrCloudExample
[junit4] 2> 3557882 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3557883 INFO (Thread-7457) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3557883 INFO (Thread-7457) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3557993 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.ZkTestServer start zk server on port:64584
[junit4] 2> 3557993 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3557993 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3558004 INFO (zkCallback-9782-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3e4cd430 name:ZooKeeperConnection Watcher:127.0.0.1:64584 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3558005 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3558005 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3558005 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 3558013 INFO (jetty-launcher-9781-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 3558014 INFO (jetty-launcher-9781-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a9db895{/solr,null,AVAILABLE}
[junit4] 2> 3558015 INFO (jetty-launcher-9781-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@1afad23d{HTTP/1.1,[http/1.1]}{127.0.0.1:64581}
[junit4] 2> 3558015 INFO (jetty-launcher-9781-thread-1) [ ] o.e.j.s.Server Started @3563105ms
[junit4] 2> 3558015 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64581}
[junit4] 2> 3558015 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3558015 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-003/node1'
[junit4] 2> 3558016 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3558016 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3558017 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3558017 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3558019 INFO (zkCallback-9783-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@351afb32 name:ZooKeeperConnection Watcher:127.0.0.1:64584/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3558019 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3558019 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3558020 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3558024 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-003/node1
[junit4] 2> 3558024 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 1851711879
[junit4] 2> 3558024 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-003/node1]
[junit4] 2> 3558024 WARN (jetty-launcher-9781-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-003/node1/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-003/node1/lib
[junit4] 2> 3558024 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 3558027 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
[junit4] 2> 3558028 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3558028 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3558028 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64584/solr
[junit4] 2> 3558028 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 3558028 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3558029 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3558030 INFO (zkCallback-9786-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@28b9928b name:ZooKeeperConnection Watcher:127.0.0.1:64584 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3558031 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3558031 INFO (jetty-launcher-9781-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3558032 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$83/616840286@30e9cdcc
[junit4] 2> 3558033 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3558034 INFO (zkCallback-9787-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16b4a4ed name:ZooKeeperConnection Watcher:127.0.0.1:64584/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3558034 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3558036 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 3558039 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 3558044 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 3558047 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 3558051 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 3558054 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 3558057 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 3558059 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 3558061 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 3558063 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 3558065 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3558066 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 3558067 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 3558072 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 3558074 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 3558076 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3558077 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95927880942551043-localhost:64581_solr-n_0000000000
[junit4] 2> 3558078 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader localhost:64581_solr
[junit4] 2> 3558078 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 3558080 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.Overseer Overseer (id=95927880942551043-localhost:64581_solr-n_0000000000) starting
[junit4] 2> 3558084 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 3558094 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 3558095 INFO (OverseerCollectionConfigSetProcessor-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 3558096 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 3558096 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/localhost:64581_solr
[junit4] 2> 3558099 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/localhost:64581_solr
[junit4] 2> 3558100 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 3558101 INFO (zkCallback-9787-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_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> 3558102 INFO (zkCallback-9787-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3558126 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 3558126 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 3558127 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-003/node1
[junit4] 2> 3558127 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 3558128 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 3558128 INFO (jetty-launcher-9781-thread-1) [n:localhost:64581_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 3558129 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3558129 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3558131 INFO (zkCallback-9789-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3b9ca07c name:ZooKeeperConnection Watcher:127.0.0.1:64584 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3558131 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3558132 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3558134 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:64584/solr
[junit4] 2> 3558175 INFO (qtp79212848-44735) [n:localhost:64581_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=37
[junit4] 2> 3558178 INFO (qtp79212848-44736) [n:localhost:64581_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 3558180 INFO (qtp79212848-44736) [n:localhost:64581_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 3558180 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:64584/solr
[junit4] 2> 3558181 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3558182 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3558183 INFO (zkCallback-9793-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1935741e name:ZooKeeperConnection Watcher:127.0.0.1:64584/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3558184 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3558184 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3558184 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3558185 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 3558187 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3558192 INFO (qtp79212848-44740) [n:localhost:64581_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 3558192 INFO (qtp79212848-44740) [n:localhost:64581_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 3558218 INFO (qtp79212848-44736) [n:localhost:64581_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=23
[junit4] 2> 3558221 INFO (qtp79212848-44737) [n:localhost:64581_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 3558222 INFO (qtp79212848-44737) [n:localhost:64581_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 3558223 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:64584/solr
[junit4] 2> 3558224 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3558225 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3558227 INFO (zkCallback-9796-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@42259ca8 name:ZooKeeperConnection Watcher:127.0.0.1:64584/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3558227 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3558227 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3558227 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3558229 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 3558230 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3558235 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/currency.xml
[junit4] 2> 3558239 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/elevate.xml
[junit4] 2> 3558243 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ca.txt
[junit4] 2> 3558249 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_fr.txt
[junit4] 2> 3558253 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ga.txt
[junit4] 2> 3558257 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_it.txt
[junit4] 2> 3558262 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/hyphenations_ga.txt
[junit4] 2> 3558267 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stemdict_nl.txt
[junit4] 2> 3558271 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stoptags_ja.txt
[junit4] 2> 3558275 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ar.txt
[junit4] 2> 3558280 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_bg.txt
[junit4] 2> 3558283 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ca.txt
[junit4] 2> 3558287 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_cz.txt
[junit4] 2> 3558291 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_da.txt
[junit4] 2> 3558295 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_de.txt
[junit4] 2> 3558299 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_el.txt
[junit4] 2> 3558303 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_en.txt
[junit4] 2> 3558308 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_es.txt
[junit4] 2> 3558312 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_eu.txt
[junit4] 2> 3558316 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fa.txt
[junit4] 2> 3558320 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fi.txt
[junit4] 2> 3558324 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fr.txt
[junit4] 2> 3558327 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ga.txt
[junit4] 2> 3558331 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_gl.txt
[junit4] 2> 3558335 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hi.txt
[junit4] 2> 3558339 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hu.txt
[junit4] 2> 3558344 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hy.txt
[junit4] 2> 3558348 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_id.txt
[junit4] 2> 3558351 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_it.txt
[junit4] 2> 3558355 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ja.txt
[junit4] 2> 3558360 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_lv.txt
[junit4] 2> 3558364 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_nl.txt
[junit4] 2> 3558368 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_no.txt
[junit4] 2> 3558372 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_pt.txt
[junit4] 2> 3558376 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ro.txt
[junit4] 2> 3558380 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ru.txt
[junit4] 2> 3558385 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_sv.txt
[junit4] 2> 3558389 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_th.txt
[junit4] 2> 3558393 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_tr.txt
[junit4] 2> 3558397 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/userdict_ja.txt
[junit4] 2> 3558401 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 3558405 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/params.json
[junit4] 2> 3558409 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/protwords.txt
[junit4] 2> 3558412 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/solrconfig.xml
[junit4] 2> 3558416 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/stopwords.txt
[junit4] 2> 3558419 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E1DE3034EF6792C3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/synonyms.txt
[junit4] 2> 3558425 INFO (qtp79212848-44735) [n:localhost:64581_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 3558425 INFO (qtp79212848-44735) [n:localhost:64581_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 3558428 INFO (qtp79212848-44737) [n:localhost:64581_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&maxShardsPerNode=4&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt&action=CREATE&numShards=2&wt=json and sendToOCPQueue=true
[junit4] 2> 3558433 INFO (OverseerCollectionConfigSetProcessor-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 3558433 INFO (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 3558433 INFO (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.OverseerCollectionMessageHandler Create collection testCloudExamplePrompt
[junit4] 2> 3558434 WARN (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.OverseerCollectionMessageHandler Specified replicationFactor of 2 on collection testCloudExamplePrompt is higher than or equal to the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 3558434 INFO (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/testCloudExamplePrompt
[junit4] 2> 3558435 INFO (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/testCloudExamplePrompt
[junit4] 2> 3558437 INFO (zkCallback-9796-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 3558437 INFO (zkCallback-9787-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 3558443 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 3558443 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testCloudExamplePrompt
[junit4] 2> 3558445 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testCloudExamplePrompt/state.json
[junit4] 2> 3558544 INFO (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating SolrCores for new collection testCloudExamplePrompt, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 3558544 INFO (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testCloudExamplePrompt_shard2_replica1 as part of shard shard2 of collection testCloudExamplePrompt on localhost:64581_solr
[junit4] 2> 3558545 INFO (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testCloudExamplePrompt_shard1_replica2 as part of shard shard1 of collection testCloudExamplePrompt on localhost:64581_solr
[junit4] 2> 3558545 INFO (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testCloudExamplePrompt_shard2_replica2 as part of shard shard2 of collection testCloudExamplePrompt on localhost:64581_solr
[junit4] 2> 3558545 INFO (OverseerThreadFactory-11532-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testCloudExamplePrompt_shard1_replica1 as part of shard shard1 of collection testCloudExamplePrompt on localhost:64581_solr
[junit4] 2> 3558547 INFO (qtp79212848-44739) [n:localhost:64581_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 3558548 INFO (qtp79212848-44740) [n:localhost:64581_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 3558548 INFO (qtp79212848-44738) [n:localhost:64581_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 3558548 INFO (qtp79212848-44739) [n:localhost:64581_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica1, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 3558549 INFO (qtp79212848-44740) [n:localhost:64581_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica2, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 3558549 INFO (qtp79212848-44735) [n:localhost:64581_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 3558549 INFO (qtp79212848-44738) [n:localhost:64581_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica2, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 3558550 INFO (qtp79212848-44735) [n:localhost:64581_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica1, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 3558550 INFO (qtp79212848-44740) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3558550 INFO (qtp79212848-44739) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3558551 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3558551 INFO (qtp79212848-44735) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3558553 INFO (qtp79212848-44740) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 3558553 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 3558553 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3558553 INFO (qtp79212848-44739) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 3558554 INFO (qtp79212848-44739) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3558554 INFO (qtp79212848-44735) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 3558554 INFO (qtp79212848-44735) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3558554 INFO (qtp79212848-44740) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader Add data for [testCloudExamplePrompt] ver [0]
[junit4] 2> 3558555 INFO (qtp79212848-44740) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3558555 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.Overseer processMessage: queueSize: 4, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:64581/solr",
[junit4] 2> "node_name":"localhost:64581_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3558555 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:64581/solr",
[junit4] 2> "node_name":"localhost:64581_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 3558558 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.Overseer processMessage: queueSize: 4, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:64581/solr",
[junit4] 2> "node_name":"localhost:64581_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3558558 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:64581/solr",
[junit4] 2> "node_name":"localhost:64581_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 3558561 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.Overseer processMessage: queueSize: 4, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:64581/solr",
[junit4] 2> "node_name":"localhost:64581_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3558561 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:64581/solr",
[junit4] 2> "node_name":"localhost:64581_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 3558564 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.Overseer processMessage: queueSize: 4, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:64581/solr",
[junit4] 2> "node_name":"localhost:64581_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3558565 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:64581/solr",
[junit4] 2> "node_name":"localhost:64581_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 3558674 INFO (OverseerStateUpdate-95927880942551043-localhost:64581_solr-n_0000000000) [n:localhost:64581_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCloudExamplePrompt/state.json version: 0
[junit4] 2> 3558676 INFO (zkCallback-9787-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 3558677 INFO (zkCallback-9787-thread-1-processing-n:localhost:64581_solr) [n:localhost:64581_solr ] o.a.s.c.c.ZkStateReader Updating data for [testCloudExamplePrompt] from [0] to [1]
[junit4] 2> 3559555 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica2
[junit4] 2> 3559555 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 3559555 INFO (qtp79212848-44740) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica2
[junit4] 2> 3559555 INFO (qtp79212848-44740) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 3559555 INFO (qtp79212848-44739) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica1
[junit4] 2> 3559556 INFO (qtp79212848-44739) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 3559556 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 3559556 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 3559556 INFO (qtp79212848-44740) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 3559556 INFO (qtp79212848-44740) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 3559556 INFO (qtp79212848-44739) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 3559556 INFO (qtp79212848-44739) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 3559557 INFO (qtp79212848-44735) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica1
[junit4] 2> 3559557 INFO (qtp79212848-44735) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 3559557 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 3559557 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E1DE3034EF6792C3-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2'
[junit4] 2> 3559558 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3559558 INFO (qtp79212848-44738) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3559558 INFO (qtp79212848-44740) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloud
[...truncated too long message...]
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$5.execute(SolrZkClient.java:314)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:311)
[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:311)
[junit4] 2> at org.apache.solr.cloud.ZkController.pathExists(ZkController.java:796)
[junit4] 2> at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:89)
[junit4] 2> ... 13 more
[junit4] 2> 3583132 INFO (Thread-7482) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 r:core_node4 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore config update listener called for core testCloudExamplePrompt_shard2_replica1
[junit4] 2> 3583132 INFO (Thread-7482) [n:localhost:64581_solr c:testCloudExamplePrompt s:shard1 r:core_node4 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore config update listener called for core testCloudExamplePrompt_shard2_replica2
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62), sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=it-IT, timezone=Egypt
[junit4] 2> NOTE: Mac OS X 10.11.4 x86_64/Oracle Corporation 1.8.0_72 (64-bit)/cpus=3,threads=1,free=168315712,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [HdfsRecoveryZkTest, TestCollapseQParserPlugin, TestDynamicFieldCollectionResource, LoggingHandlerTest, FacetPivotSmallTest, LukeRequestHandlerTest, SimpleMLTQParserTest, BlockJoinFacetDistribTest, BlockCacheTest, BufferStoreTest, TestReplicationHandlerBackup, SolrInfoMBeanTest, CopyFieldTest, TestExclusionRuleCollectionAccess, SparseHLLTest, TestDistributedGrouping, TestSubQueryTransformer, EnumFieldTest, CacheHeaderTest, DateRangeFieldTest, TestSolrJ, BasicZkTest, TestReloadAndDeleteDocs, TestTrie, LeaderInitiatedRecoveryOnShardRestartTest, HdfsChaosMonkeySafeLeaderTest, WordBreakSolrSpellCheckerTest, TestAtomicUpdateErrorCases, TestCustomDocTransformer, TestSearcherReuse, TestPerFieldSimilarityWithDefaultOverride, BlobRepositoryCloudTest, TestMiniSolrCloudClusterBase, CreateCollectionCleanupTest, SynonymTokenizerTest, UpdateRequestProcessorFactoryTest, TestBinaryResponseWriter, DateFieldTest, TestSolr4Spatial, VMParamsZkACLAndCredentialsProvidersTest, CollectionReloadTest, CollectionTooManyReplicasTest, DataDrivenBlockJoinTest, TestBadConfig, AnalyticsMergeStrategyTest, DistributedQueryComponentOptimizationTest, TestScoreJoinQPNoScore, QueryParsingTest, TestSubQueryTransformerDistrib, TestRecoveryHdfs, SolrIndexSplitterTest, TestValueSourceCache, TestCustomSort, TestQueryWrapperFilter, IgnoreCommitOptimizeUpdateProcessorFactoryTest, DistanceUnitsTest, BadCopyFieldTest, TestGraphMLResponseWriter, TestRecovery, SSLMigrationTest, HdfsBasicDistributedZkTest, SearchHandlerTest, AnalysisErrorHandlingTest, ExplicitHLLTest, TestRealTimeGet, SolrPluginUtilsTest, TermVectorComponentDistributedTest, SharedFSAutoReplicaFailoverUtilsTest, CachingDirectoryFactoryTest, TestCoreDiscovery, ConnectionReuseTest, TestSolrCloudWithKerberosAlt, TestQueryUtils, TestManagedResourceStorage, OverseerRolesTest, ForceLeaderTest, FullSolrCloudDistribCmdsTest, TestSortByMinMaxFunction, TestFastOutputStream, TestFilteredDocIdSet, OpenExchangeRatesOrgProviderTest, TestUtils, RemoteQueryErrorTest, TestRestoreCore, TestBlendedInfixSuggestions, TestCollationField, TestFastWriter, DistribDocExpirationUpdateProcessorTest, BadIndexSchemaTest, ExitableDirectoryReaderTest, CurrencyFieldOpenExchangeTest, SuggestComponentContextFilterQueryTest, SolrCmdDistributorTest, TestStressRecovery, TestManagedStopFilterFactory, FieldAnalysisRequestHandlerTest, BigEndianAscendingWordSerializerTest, TestTestInjection, AsyncCallRequestStatusResponseTest, PKIAuthenticationIntegrationTest, TestLeaderInitiatedRecoveryThread, SolrCloudExampleTest, TestRTGBase, TestIntervalFaceting, ZkSolrClientTest, LeaderFailoverAfterPartitionTest, DateMathParserTest, TestSolrFieldCacheMBean, ChaosMonkeySafeLeaderTest, JavabinLoaderTest, OutputWriterTest, NotRequiredUniqueKeyTest, TestSuggestSpellingConverter, TestSolrDeletionPolicy2, TestPKIAuthenticationPlugin, TestSolrQueryParserResource, TestCharFilters, TestRandomFaceting, TestReversedWildcardFilterFactory, TestConfigSets, TestJoin, TestCollationFieldDocValues, AutoCommitTest, HdfsLockFactoryTest, TestCloudDeleteByQuery, ConvertedLegacyTest, ZkStateWriterTest, OverseerTaskQueueTest, PreAnalyzedUpdateProcessorTest, SuggesterWFSTTest, ConfigSetsAPITest, MultiThreadedOCPTest, FileBasedSpellCheckerTest, CoreMergeIndexesAdminHandlerTest, TestCopyFieldCollectionResource, TestZkChroot, DeleteReplicaTest, SortByFunctionTest, TestFieldTypeCollectionResource, TestAddFieldRealTimeGet, DistributedTermsComponentTest, DistributedQueueTest, DistributedDebugComponentTest, SpellCheckCollatorTest, FileUtilsTest, TestRangeQuery, BlockJoinFacetSimpleTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, ShardRoutingTest, TestRandomDVFaceting, ShardRoutingCustomTest, ZkControllerTest, SpellCheckComponentTest, QueryElevationComponentTest, PeerSyncTest, SoftAutoCommitTest, SolrRequestParserTest, TestFoldingMultitermQuery, SuggesterTSTTest, TestStressLucene, SpatialFilterTest, PolyFieldTest, SchemaVersionSpecificBehaviorTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, TestSurroundQueryParser, TestQueryTypes, TestOmitPositions, XmlUpdateRequestHandlerTest, DocumentBuilderTest, TermVectorComponentTest, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, TestCSVResponseWriter, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName, SOLR749Test, TestSolrIndexConfig, ResponseLogComponentTest, TestMergePolicyConfig, TestBinaryField, TestElisionMultitermQuery, DirectSolrConnectionTest, TestLuceneMatchVersion, EchoParamsTest, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TimeZoneUtilsTest, ScriptEngineTest, PluginInfoTest, TestSystemIdResolver, SpellingQueryConverterTest, TestDocumentBuilder, CircularListTest, DistributedMLTComponentTest, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestSimpleTrackingShardHandler, AssignTest, BaseCdcrDistributedZkTest, CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, CdcrVersionReplicationTest, CleanupOldIndexTest, CollectionsAPISolrJTest, DeleteShardTest, DistributedVersionInfoTest, LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, ReplicationFactorTest, RestartWhileUpdatingTest, RollingRestartTest, SharedFSAutoReplicaFailoverTest, SimpleCollectionCreateDeleteTest, SolrXmlInZkTest, TestAuthenticationFramework, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestMiniSolrCloudCluster, TestSSLRandomization, TestShortCircuitedRequests, TestStressLiveNodes, HdfsUnloadDistributedZkTest, DirectoryFactoryTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, TestConfigOverlay, TestConfigSetImmutable, TestConfigSetProperties, TestImplicitCoreProperties, TestInitParams, TestNRTOpen, TestShardHandlerFactory, TestSolrConfigHandler, TestSolrDynamicMBean, TestSolrXml, RequestLoggingTest, TestBlobHandler, TestConfigReload, TestReqParamsAPI, TestSQLHandler, TestSolrConfigHandlerCloud, DistributedFacetPivotWhiteBoxTest, TestDistributedStatsComponentCardinality, TestDynamicFieldResource, TestFieldCollectionResource, TestFieldResource, TestFieldTypeResource, TestSchemaNameResource, TestSchemaSimilarityResource, TestSerializedLuceneMatchVersion, TestManagedSynonymFilterFactory, TestCloudSchemaless, CursorMarkTest, MergeStrategyTest, TestInitQParser, TestNoOpRegenerator, TestOverriddenPrefixQueryForCustomFieldType, TestRandomCollapseQParserPlugin, TestDFISimilarityFactory, TestNonDefinedSimilarityFactory, TestExactSharedStatsCache, TestAuthorizationFramework, TolerantUpdateProcessorTest, TestRTimerTree, TestSolrCLIRunExample]
[junit4] Completed [597/597 (1!)] on J1 in 28.32s, 3 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 10 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:740: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:684: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build.xml:233: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/common-build.xml:529: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1458: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1015: There were test failures: 597 suites (10 ignored), 2428 tests, 1 failure, 121 ignored (71 assumptions) [seed: E1DE3034EF6792C3]
Total time: 81 minutes 7 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