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/16 09:14:27 UTC
[JENKINS] Lucene-Solr-6.x-Linux (32bit/jdk1.8.0_92) - Build # 666 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/666/
Java: 32bit/jdk1.8.0_92 -client -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.schema.TestManagedSchemaAPI
Error Message:
ObjectTracker found 0 object(s) that were not released!!! [MockDirectoryWrapper]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 0 object(s) that were not released!!! [MockDirectoryWrapper]
at __randomizedtesting.SeedInfo.seed([C83D4B39550BE975]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:256)
at sun.reflect.GeneratedMethodAccessor21.invoke(Unknown Source)
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$7.evaluate(RandomizedRunner.java:834)
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 12276 lines...]
[junit4] Suite: org.apache.solr.schema.TestManagedSchemaAPI
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/init-core-data-001
[junit4] 2> 2612433 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
[junit4] 2> 2612436 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2612436 INFO (Thread-4630) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2612436 INFO (Thread-4630) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2612536 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:35508
[junit4] 2> 2612537 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2612537 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2612539 INFO (zkCallback-2528-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@75a63d name:ZooKeeperConnection Watcher:127.0.0.1:35508 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2612539 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2612539 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2612539 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 2612541 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/clusterprops.json
[junit4] 2> 2612546 INFO (jetty-launcher-2527-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 2612546 INFO (jetty-launcher-2527-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 2612547 INFO (jetty-launcher-2527-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f29586{/solr,null,AVAILABLE}
[junit4] 2> 2612547 INFO (jetty-launcher-2527-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5473e3{/solr,null,AVAILABLE}
[junit4] 2> 2612556 INFO (jetty-launcher-2527-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@1543448{SSL,[ssl, http/1.1]}{127.0.0.1:41113}
[junit4] 2> 2612556 INFO (jetty-launcher-2527-thread-2) [ ] o.e.j.s.Server Started @2613829ms
[junit4] 2> 2612556 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41113}
[junit4] 2> 2612556 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 2612556 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2'
[junit4] 2> 2612556 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2612557 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2612557 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2612557 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2612558 INFO (zkCallback-2529-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@167948e name:ZooKeeperConnection Watcher:127.0.0.1:35508/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2612558 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2612559 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2612559 INFO (jetty-launcher-2527-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@1f0851d{SSL,[ssl, http/1.1]}{127.0.0.1:34557}
[junit4] 2> 2612559 INFO (jetty-launcher-2527-thread-1) [ ] o.e.j.s.Server Started @2613832ms
[junit4] 2> 2612559 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2612559 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34557}
[junit4] 2> 2612559 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 2612559 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1'
[junit4] 2> 2612560 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2612560 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2612560 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2612562 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2612576 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/.
[junit4] 2> 2612577 INFO (zkCallback-2530-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d0fdb name:ZooKeeperConnection Watcher:127.0.0.1:35508/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2612577 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.CoreContainer New CoreContainer 3782026
[junit4] 2> 2612577 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2612577 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2]
[junit4] 2> 2612577 WARN (jetty-launcher-2527-thread-2) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/lib to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/lib
[junit4] 2> 2612577 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2612577 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2612578 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2612579 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2612579 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2612579 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2612579 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35508/solr
[junit4] 2> 2612579 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 2612580 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2612580 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2612582 INFO (zkCallback-2533-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@124a62e name:ZooKeeperConnection Watcher:127.0.0.1:35508 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2612582 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2612583 INFO (jetty-launcher-2527-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2612583 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$32/16272511@dd3ed3
[junit4] 2> 2612585 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2612586 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/.
[junit4] 2> 2612586 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1cbff5 name:ZooKeeperConnection Watcher:127.0.0.1:35508/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2612586 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 18509765
[junit4] 2> 2612586 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2612586 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1]
[junit4] 2> 2612586 WARN (jetty-launcher-2527-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/lib to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/lib
[junit4] 2> 2612586 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2612587 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 2612588 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 2612588 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2612588 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2612588 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2612588 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35508/solr
[junit4] 2> 2612589 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 2612589 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2612589 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2612598 INFO (zkCallback-2537-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e78d88 name:ZooKeeperConnection Watcher:127.0.0.1:35508 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2612598 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2612598 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 2612598 INFO (jetty-launcher-2527-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2612599 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$32/16272511@8d6bca
[junit4] 2> 2612600 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2612600 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 2612601 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@312ce8 name:ZooKeeperConnection Watcher:127.0.0.1:35508/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2612601 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2612604 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 2612605 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2612606 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2612606 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2612607 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2612607 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2612607 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2612608 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2612609 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2612609 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2612609 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2612609 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2612610 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https}
[junit4] 2> 2612610 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https}
[junit4] 2> 2612610 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2612610 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2612611 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 2612612 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 2612612 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 2612612 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 2612613 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2612613 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2612614 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95904723308969988-127.0.0.1:41113_solr-n_0000000000
[junit4] 2> 2612615 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41113_solr
[junit4] 2> 2612615 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95904723308969990-127.0.0.1:34557_solr-n_0000000001
[junit4] 2> 2612615 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 2612616 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95904723308969988-127.0.0.1:41113_solr-n_0000000000 to know if I could be the leader
[junit4] 2> 2612616 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.Overseer Overseer (id=95904723308969988-127.0.0.1:41113_solr-n_0000000000) starting
[junit4] 2> 2612617 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34557_solr
[junit4] 2> 2612617 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:34557_solr
[junit4] 2> 2612618 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_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> 2612618 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 2612619 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2612619 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_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> 2612622 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2612635 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 2612635 INFO (OverseerCollectionConfigSetProcessor-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 2612636 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 2612636 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:41113_solr as DOWN
[junit4] 2> 2612637 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 2612638 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:41113_solr"} current state version: 0
[junit4] 2> 2612638 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:41113_solr
[junit4] 2> 2612646 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 2612647 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 2612647 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/.
[junit4] 2> 2612647 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 2612648 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 2612648 INFO (jetty-launcher-2527-thread-1) [n:127.0.0.1:34557_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 2613637 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41113_solr
[junit4] 2> 2613638 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:41113_solr
[junit4] 2> 2613641 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_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> 2613641 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_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> 2613645 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2613645 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2613689 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 2613689 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 2613689 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/.
[junit4] 2> 2613690 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 2613690 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 2613690 INFO (jetty-launcher-2527-thread-2) [n:127.0.0.1:41113_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 2613691 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2613691 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2613692 INFO (zkCallback-2541-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ca4d89 name:ZooKeeperConnection Watcher:127.0.0.1:35508 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2613692 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2613692 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2613693 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:35508/solr
[junit4] 2> 2613693 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2613694 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2613695 INFO (zkCallback-2544-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e2d37 name:ZooKeeperConnection Watcher:127.0.0.1:35508/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2613695 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2613695 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2613695 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2613696 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https}
[junit4] 2> 2613696 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2613697 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2613698 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/managed-schema
[junit4] 2> 2613717 INFO (TEST-TestManagedSchemaAPI.test-seed#[C83D4B39550BE975]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2613805 INFO (qtp916467-13758) [n:127.0.0.1:41113_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> 2613806 INFO (OverseerCollectionConfigSetProcessor-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_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> 2613807 INFO (OverseerThreadFactory-6304-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_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> 2613807 INFO (OverseerThreadFactory-6304-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.OverseerCollectionMessageHandler Create collection testschemaapi
[junit4] 2> 2613808 INFO (OverseerThreadFactory-6304-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/testschemaapi
[junit4] 2> 2613809 INFO (OverseerThreadFactory-6304-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi
[junit4] 2> 2613810 INFO (zkCallback-2544-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2613810 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2613810 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2613812 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_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> 2613813 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testschemaapi
[junit4] 2> 2613814 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testschemaapi/state.json
[junit4] 2> 2613912 INFO (OverseerThreadFactory-6304-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating SolrCores for new collection testschemaapi, shardNames [shard1] , replicationFactor : 2
[junit4] 2> 2613913 INFO (OverseerThreadFactory-6304-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testschemaapi_shard1_replica1 as part of shard shard1 of collection testschemaapi on 127.0.0.1:41113_solr
[junit4] 2> 2613913 INFO (OverseerThreadFactory-6304-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testschemaapi_shard1_replica2 as part of shard shard1 of collection testschemaapi on 127.0.0.1:34557_solr
[junit4] 2> 2613927 INFO (qtp916467-13754) [n:127.0.0.1:41113_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> 2613928 INFO (qtp916467-13754) [n:127.0.0.1:41113_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> 2613929 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 2613930 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Registering watch for collection testschemaapi
[junit4] 2> 2613930 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testschemaapi_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41113/solr",
[junit4] 2> "node_name":"127.0.0.1:41113_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> 2613931 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_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":"https://127.0.0.1:41113/solr",
[junit4] 2> "node_name":"127.0.0.1:41113_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"}
[junit4] 2> 2613932 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader Add data for [testschemaapi] ver [0]
[junit4] 2> 2613933 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 2614006 INFO (qtp26589234-13763) [n:127.0.0.1:34557_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> 2614007 INFO (qtp26589234-13763) [n:127.0.0.1:34557_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> 2614007 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 2614008 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Registering watch for collection testschemaapi
[junit4] 2> 2614009 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:34557/solr",
[junit4] 2> "node_name":"127.0.0.1:34557_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> 2614009 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_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":"https://127.0.0.1:34557/solr",
[junit4] 2> "node_name":"127.0.0.1:34557_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"}
[junit4] 2> 2614009 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader Add data for [testschemaapi] ver [0]
[junit4] 2> 2614015 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 2614110 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 0
[junit4] 2> 2614111 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_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> 2614111 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_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> 2614112 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [0] to [1]
[junit4] 2> 2614112 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [0] to [1]
[junit4] 2> 2614933 INFO (qtp916467-13754) [n:127.0.0.1:41113_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> 2614933 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:testschemaapi
[junit4] 2> 2614933 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 2614933 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 2614933 INFO (qtp916467-13754) [n:127.0.0.1:41113_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> 2614934 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/testschemaapi_shard1_replica1'
[junit4] 2> 2614934 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2614934 INFO (qtp916467-13754) [n:127.0.0.1:41113_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> 2614935 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 2614941 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 2614950 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 2614964 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2614969 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSchema [testschemaapi_shard1_replica1] Schema name=minimal
[junit4] 2> 2614974 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 2614975 INFO (qtp916467-13754) [n:127.0.0.1:41113_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=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 2614975 INFO (qtp916467-13754) [n:127.0.0.1:41113_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> 2614975 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2614976 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [[testschemaapi_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/testschemaapi_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/]
[junit4] 2> 2614984 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_replica1
[junit4] 2> 2614985 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data
[junit4] 2> 2614985 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/index/
[junit4] 2> 2614986 WARN (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [testschemaapi_shard1_replica1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2614986 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/index
[junit4] 2> 2614987 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6feae5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a2b67d),segFN=segments_1,generation=1}
[junit4] 2> 2614987 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2614988 INFO (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica1) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core testschemaapi_shard1_replica1 in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node2/./testschemaapi_shard1_replica1/data/
[junit4] 2> 2614988 WARN (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica1) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-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> 2614990 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2614992 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2614993 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2614994 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2614995 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2614996 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 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> 2614996 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2614996 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2614997 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2615001 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2615001 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2615002 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6feae5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a2b67d),segFN=segments_1,generation=1}
[junit4] 2> 2615002 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2615002 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@efaf2a[testschemaapi_shard1_replica1] main]
[junit4] 2> 2615002 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 2615003 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 2615003 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2615003 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2615003 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 2615003 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2615003 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 2615004 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2615004 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 2615004 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 2615004 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Added new OnReconnect listener ZkIndexSchemaReader: /configs/conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica1:848105574890408
[junit4] 2> 2615004 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2615005 INFO (searcherExecutor-6309-thread-1-processing-n:127.0.0.1:41113_solr x:testschemaapi_shard1_replica1 s:shard1 c:testschemaapi r:core_node1) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [testschemaapi_shard1_replica1] Registered new searcher Searcher@efaf2a[testschemaapi_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2615005 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 2615005 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 2615005 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 2615005 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1534475575635214336
[junit4] 2> 2615007 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1534475575635214336
[junit4] 2> 2615007 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 2615007 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.CoreContainer registering core: testschemaapi_shard1_replica1
[junit4] 2> 2615007 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Register replica - core:testschemaapi_shard1_replica1 address:https://127.0.0.1:41113/solr collection:testschemaapi shard:shard1
[junit4] 2> 2615008 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi/leader_elect/shard1/election
[junit4] 2> 2615015 INFO (qtp26589234-13763) [n:127.0.0.1:34557_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> 2615015 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Check for collection zkNode:testschemaapi
[junit4] 2> 2615015 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 2615015 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 2615016 INFO (qtp26589234-13763) [n:127.0.0.1:34557_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> 2615016 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/testschemaapi_shard1_replica2'
[junit4] 2> 2615016 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2615016 INFO (qtp26589234-13763) [n:127.0.0.1:34557_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> 2615017 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/testschemaapi/leader_elect/shard1/election/95904723308969988-core_node1-n_0000000000
[junit4] 2> 2615017 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000
[junit4] 2> 2615018 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 2615018 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 2615018 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_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> 2615022 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 2615026 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 2615034 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2615039 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSchema [testschemaapi_shard1_replica2] Schema name=minimal
[junit4] 2> 2615042 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 2615043 INFO (qtp26589234-13763) [n:127.0.0.1:34557_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=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 2615043 INFO (qtp26589234-13763) [n:127.0.0.1:34557_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> 2615044 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2615044 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [[testschemaapi_shard1_replica2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/testschemaapi_shard1_replica2], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/]
[junit4] 2> 2615044 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_replica2
[junit4] 2> 2615046 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data
[junit4] 2> 2615046 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/index/
[junit4] 2> 2615046 WARN (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [testschemaapi_shard1_replica2] Solr index directory '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/index' doesn't exist. Creating new index...
[junit4] 2> 2615046 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/index
[junit4] 2> 2615047 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9a3ed8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e0c881),segFN=segments_1,generation=1}
[junit4] 2> 2615047 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2615047 INFO (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica2) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore Looking for old index directories to cleanup for core testschemaapi_shard1_replica2 in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/
[junit4] 2> 2615048 WARN (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica2) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-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> 2615049 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2615051 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2615051 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2615053 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2615054 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2615055 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 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> 2615055 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2615056 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2615056 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2615057 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2615058 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2615058 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9a3ed8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e0c881),segFN=segments_1,generation=1}
[junit4] 2> 2615058 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2615058 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a931c[testschemaapi_shard1_replica2] main]
[junit4] 2> 2615058 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 2615059 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 2615059 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2615059 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2615059 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 2615059 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2615059 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 2615059 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2615060 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 2615060 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 2615060 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Added new OnReconnect listener ZkIndexSchemaReader: /configs/conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica2:848105643119936
[junit4] 2> 2615060 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2615060 INFO (searcherExecutor-6314-thread-1-processing-n:127.0.0.1:34557_solr x:testschemaapi_shard1_replica2 s:shard1 c:testschemaapi r:core_node2) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [testschemaapi_shard1_replica2] Registered new searcher Searcher@7a931c[testschemaapi_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2615060 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 2615060 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 2615061 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 2615061 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1534475575693934592
[junit4] 2> 2615062 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1534475575693934592
[junit4] 2> 2615063 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 2615064 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.CoreContainer registering core: testschemaapi_shard1_replica2
[junit4] 2> 2615064 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Register replica - core:testschemaapi_shard1_replica2 address:https://127.0.0.1:34557/solr collection:testschemaapi shard:shard1
[junit4] 2> 2615065 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.LeaderElector Joined leadership election with path: /collections/testschemaapi/leader_elect/shard1/election/95904723308969990-core_node2-n_0000000001
[junit4] 2> 2615065 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.LeaderElector Watching path /collections/testschemaapi/leader_elect/shard1/election/95904723308969988-core_node1-n_0000000000 to know if I could be the leader
[junit4] 2> 2615120 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 1
[junit4] 2> 2615120 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_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> 2615121 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_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> 2615121 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [1] to [2]
[junit4] 2> 2615121 INFO (zkCallback-2534-thread-1-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [1] to [2]
[junit4] 2> 2615518 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2615519 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2615519 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:41113/solr/testschemaapi_shard1_replica1/
[junit4] 2> 2615519 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.PeerSync PeerSync: core=testschemaapi_shard1_replica1 url=https://127.0.0.1:41113/solr START replicas=[https://127.0.0.1:34557/solr/testschemaapi_shard1_replica2/] nUpdates=100
[junit4] 2> 2615519 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.PeerSync PeerSync: core=testschemaapi_shard1_replica1 url=https://127.0.0.1:41113/solr DONE. We have no versions. sync failed.
[junit4] 2> 2615519 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 2615519 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] 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> 2615520 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi/leaders/shard1
[junit4] 2> 2615527 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testschemaapi/leaders/shard1/leader after winning as /collections/testschemaapi/leader_elect/shard1/election/95904723308969988-core_node1-n_0000000000
[junit4] 2> 2615530 INFO (qtp26589234-13764) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.S.Request [testschemaapi_shard1_replica2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2615530 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:41113/solr/testschemaapi_shard1_replica1/ shard1
[junit4] 2> 2615531 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_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":"https://127.0.0.1:41113/solr",
[junit4] 2> "core":"testschemaapi_shard1_replica1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 2615633 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 2
[junit4] 2> 2615645 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_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> 2615645 INFO (zkCallback-2534-thread-2-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_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> 2615645 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [2] to [3]
[junit4] 2> 2615645 INFO (zkCallback-2534-thread-2-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [2] to [3]
[junit4] 2> 2615681 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController We are https://127.0.0.1:41113/solr/testschemaapi_shard1_replica1/ and leader is https://127.0.0.1:41113/solr/testschemaapi_shard1_replica1/
[junit4] 2> 2615681 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController No LogReplay needed for core=testschemaapi_shard1_replica1 baseURL=https://127.0.0.1:41113/solr
[junit4] 2> 2615681 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2615681 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 2615683 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testschemaapi_shard1_replica1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41113/solr",
[junit4] 2> "node_name":"127.0.0.1:41113_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> 2615683 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testschemaapi_shard1_replica1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41113/solr",
[junit4] 2> "node_name":"127.0.0.1:41113_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"}
[junit4] 2> 2615683 INFO (qtp916467-13754) [n:127.0.0.1:41113_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=testschemaapi_shard1_replica1&action=CREATE&numShards=1&collection=testschemaapi&shard=shard1&wt=javabin&version=2} status=0 QTime=1756
[junit4] 2> 2615784 INFO (OverseerStateUpdate-95904723308969988-127.0.0.1:41113_solr-n_0000000000) [n:127.0.0.1:41113_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 3
[junit4] 2> 2615785 INFO (zkCallback-2534-thread-2-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_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> 2615785 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_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> 2615785 INFO (zkCallback-2534-thread-2-processing-n:127.0.0.1:41113_solr) [n:127.0.0.1:41113_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [3] to [4]
[junit4] 2> 2615785 INFO (zkCallback-2538-thread-1-processing-n:127.0.0.1:34557_solr) [n:127.0.0.1:34557_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [3] to [4]
[junit4] 2> 2616066 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController We are https://127.0.0.1:34557/solr/testschemaapi_shard1_replica2/ and leader is https://127.0.0.1:41113/solr/testschemaapi_shard1_replica1/
[junit4] 2> 2616066 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController No LogReplay needed for core=testschemaapi_shard1_replica2 baseURL=https://127.0.0.1:34557/solr
[junit4] 2> 2616066 INFO (qtp26589234-13763) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Core needs to recover:testschemaapi_shard1_replica2
[junit4] 2> 2616067 INFO (updateExecutor-2535-thread-1-processing-n:127.0.0.1:34557_solr x:testschemaapi_shard1_replica2 s:shard1 c:testschemaapi r:core_node2) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 2616067 INFO (recoveryExecutor-2536-thread-1-processing-n
[...truncated too long message...]
node2 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data
[junit4] 2> 2640568 INFO (Thread-4665) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/index;done=false>>]
[junit4] 2> 2640568 INFO (SUITE-TestManagedSchemaAPI-seed#[C83D4B39550BE975]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2640569 INFO (Thread-4665) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 2640569 INFO (Thread-4665) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_C83D4B39550BE975-001/tempDir-001/node1/./testschemaapi_shard1_replica2/data/index
[junit4] 2> ObjectTracker found 0 object(s) that were not released!!! [MockDirectoryWrapper]
[junit4] 2> 2640569 WARN (Thread-4665) [n:127.0.0.1:34557_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController listener throws error
[junit4] 2> org.apache.solr.common.SolrException: Unable to reload core [testschemaapi_shard1_replica2]
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:921)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2490)
[junit4] 2> at org.apache.solr.cloud.ZkController$4.run(ZkController.java:2409)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Could not find config name for collection:testschemaapi
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:773)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:462)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:915)
[junit4] 2> ... 2 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Could not find config name for collection:testschemaapi
[junit4] 2> at org.apache.solr.rest.ManagedResourceStorage.newStorageIO(ManagedResourceStorage.java:101)
[junit4] 2> at org.apache.solr.core.SolrCore.initRestManager(SolrCore.java:2285)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:749)
[junit4] 2> ... 4 more
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {_version_=PostingsFormat(name=LuceneFixedGap), id=BlockTreeOrds(blocksize=128), myNewField1=BlockTreeOrds(blocksize=128), myNewField=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, maxPointsInLeafNode=1643, maxMBSortInHeap=7.506193079297235, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=lt-LT, timezone=Pacific/Fiji
[junit4] 2> NOTE: Linux 4.4.0-21-generic i386/Oracle Corporation 1.8.0_92 (32-bit)/cpus=12,threads=1,free=215436400,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestUpdate, TestReplicationHandlerBackup, TimeZoneUtilsTest, TestSolrCoreProperties, TestCollectionAPI, ConfigSetsAPITest, DistributedFacetPivotWhiteBoxTest, CloneFieldUpdateProcessorFactoryTest, DistributedDebugComponentTest, TestBulkSchemaConcurrent, SolrCmdDistributorTest, BitVectorTest, TestDocumentBuilder, TestStressVersions, TestSystemIdResolver, TestSchemaResource, SpellingQueryConverterTest, AliasIntegrationTest, TestLFUCache, TestJsonFacets, RequestLoggingTest, DistributedSuggestComponentTest, TestSolrConfigHandler, TolerantUpdateProcessorTest, TestExceedMaxTermLength, TestReRankQParserPlugin, TestQueryTypes, SchemaVersionSpecificBehaviorTest, DocValuesTest, DirectSolrSpellCheckerTest, TestConfigSetsAPIZkFailure, DistributedQueueTest, FileUtilsTest, BlockJoinFacetRandomTest, CollectionsAPIAsyncDistributedZkTest, HdfsRecoverLeaseTest, SolrInfoMBeanTest, TestLRUStatsCache, TestStressUserVersions, TestClusterStateMutator, SoftAutoCommitTest, CoreAdminCreateDiscoverTest, SpatialFilterTest, TestDownShardTolerantSearch, DistribCursorPagingTest, HdfsSyncSliceTest, TestUniqueKeyFieldResource, SolrXmlInZkTest, InfoHandlerTest, UniqFieldsUpdateProcessorFactoryTest, MoreLikeThisHandlerTest, TestDFRSimilarityFactory, DocumentBuilderTest, TestCursorMarkWithoutUniqueKey, TestPartialUpdateDeduplication, TestAnalyzeInfixSuggestions, TestSortByMinMaxFunction, DeleteReplicaTest, TestImplicitCoreProperties, TestSolr4Spatial2, TestRestManager, CollectionReloadTest, CloudMLTQParserTest, TestSearcherReuse, TestGeoJSONResponseWriter, ZkCLITest, TestFieldSortValues, TestIntervalFaceting, CdcrReplicationHandlerTest, IndexBasedSpellCheckerTest, TestCodecSupport, TestMissingGroups, TestFieldTypeCollectionResource, TestSolrDeletionPolicy2, QueryResultKeyTest, DistributedIntervalFacetingTest, BlockDirectoryTest, AddSchemaFieldsUpdateProcessorFactoryTest, TermsComponentTest, DOMUtilTest, SharedFSAutoReplicaFailoverUtilsTest, DistributedFacetPivotLongTailTest, ChaosMonkeyNothingIsSafeTest, LeaderInitiatedRecoveryOnShardRestartTest, TestHashPartitioner, TestSurroundQueryParser, TestExpandComponent, TestChildDocTransformer, TestDistribIDF, CSVRequestHandlerTest, TestRawTransformer, TestNRTOpen, TestBinaryResponseWriter, TestCoreContainer, HdfsChaosMonkeySafeLeaderTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, BasicZkTest, RecoveryZkTest, LeaderElectionTest, ZkControllerTest, TestRealTimeGet, TestRangeQuery, TestSolr4Spatial, StatsComponentTest, PeerSyncTest, BasicFunctionalityTest, DirectUpdateHandlerTest, TestBadConfig, HighlighterTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, AnalysisAfterCoreReloadTest, SuggesterFSTTest, SolrRequestParserTest, TestStressLucene, TestTrie, SuggesterWFSTTest, TestPseudoReturnFields, TestWordDelimiterFilterFactory, SortByFunctionTest, DocValuesMultiTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestReversedWildcardFilterFactory, RequestHandlersTest, PathHierarchyTokenizerFactoryTest, FastVectorHighlighterTest, SolrPluginUtilsTest, MBeansHandlerTest, TestAnalyzedSuggestions, TestComponentsName, SOLR749Test, TestQuerySenderListener, AlternateDirectoryTest, TestQuerySenderNoQuery, CopyFieldTest, BadComponentTest, TestStressRecovery, TestSuggestSpellingConverter, RAMDirectoryFactoryTest, TestUtils, SystemInfoHandlerTest, TestRTGBase, TestCrossCoreJoin, TestSimpleTrackingShardHandler, CdcrReplicationDistributedZkTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, CloudExitableDirectoryReaderTest, CustomCollectionTest, ForceLeaderTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest, SaslZkACLProviderTest, SolrCloudExampleTest, TestCloudDeleteByQuery, TestCloudInspectUtil, TestConfigSetsAPI, TestLeaderInitiatedRecoveryThread, TestMiniSolrCloudCluster, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, HdfsBasicDistributedZkTest, HdfsNNFailoverTest, HdfsUnloadDistributedZkTest, StressHdfsTest, RuleEngineTest, BlobRepositoryCloudTest, DirectoryFactoryTest, HdfsDirectoryFactoryTest, TestConfigSetProperties, TestConfigSets, TestDynamicLoading, DistributedQueryComponentCustomSortTest, TestTrackingShardHandlerFactory, TestManagedSchemaAPI]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestManagedSchemaAPI -Dtests.seed=C83D4B39550BE975 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt-LT -Dtests.timezone=Pacific/Fiji -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestManagedSchemaAPI (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 0 object(s) that were not released!!! [MockDirectoryWrapper]
[junit4] > at __randomizedtesting.SeedInfo.seed([C83D4B39550BE975]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:256)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [547/598 (1!)] on J1 in 28.16s, 1 test, 1 failure <<< FAILURES!
[...truncated 194 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:684: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-build.xml:529: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:1457: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:1014: There were test failures: 598 suites (10 ignored), 2420 tests, 1 suite-level error, 115 ignored (71 assumptions) [seed: C83D4B39550BE975]
Total time: 78 minutes 12 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-EA] Lucene-Solr-6.x-Linux (64bit/jdk-9-ea+118) - Build #
669 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/669/
Java: 64bit/jdk-9-ea+118 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
All tests passed
Build Log:
[...truncated 38440 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:117: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:347: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-build.xml:509: Check for forbidden API calls failed: java.lang.ClassNotFoundException: javax.xml.bind.DatatypeConverter
at de.thetaphi.forbiddenapis.Checker.getClassFromClassLoader(Checker.java:264)
at de.thetaphi.forbiddenapis.Checker.lookupRelatedClass(Checker.java:277)
at de.thetaphi.forbiddenapis.ClassScanner$2.checkMethodAccess(ClassScanner.java:349)
at de.thetaphi.forbiddenapis.ClassScanner$2.visitMethodInsn(ClassScanner.java:469)
at de.thetaphi.forbiddenapis.asm.ClassReader.a(Unknown Source)
at de.thetaphi.forbiddenapis.asm.ClassReader.b(Unknown Source)
at de.thetaphi.forbiddenapis.asm.ClassReader.accept(Unknown Source)
at de.thetaphi.forbiddenapis.asm.ClassReader.accept(Unknown Source)
at de.thetaphi.forbiddenapis.Checker.checkClass(Checker.java:528)
at de.thetaphi.forbiddenapis.Checker.run(Checker.java:545)
at de.thetaphi.forbiddenapis.ant.AntTask.execute(AntTask.java:189)
at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:531)
at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
at org.apache.tools.ant.Task.perform(Task.java:348)
at org.apache.tools.ant.Target.execute(Target.java:390)
at org.apache.tools.ant.Target.performTasks(Target.java:411)
at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
at org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleCheckExecutor.java:38)
at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:302)
at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:221)
at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:531)
at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
at org.apache.tools.ant.Task.perform(Task.java:348)
at org.apache.tools.ant.Target.execute(Target.java:390)
at org.apache.tools.ant.Target.performTasks(Target.java:411)
at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
at org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleCheckExecutor.java:38)
at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:302)
at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:221)
at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:531)
at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
at org.apache.tools.ant.Task.perform(Task.java:348)
at org.apache.tools.ant.Target.execute(Target.java:390)
at org.apache.tools.ant.Target.performTasks(Target.java:411)
at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
at org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleCheckExecutor.java:38)
at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
at org.apache.tools.ant.taskdefs.CallTarget.execute(CallTarget.java:105)
at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:531)
at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
at org.apache.tools.ant.Task.perform(Task.java:348)
at org.apache.tools.ant.Target.execute(Target.java:390)
at org.apache.tools.ant.Target.performTasks(Target.java:411)
at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
at org.apache.tools.ant.Project.executeTarget(Project.java:1368)
at org.apache.tools.ant.helper.DefaultExecutor.executeTargets(DefaultExecutor.java:41)
at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
at org.apache.tools.ant.Main.runBuild(Main.java:809)
at org.apache.tools.ant.Main.startAnt(Main.java:217)
at org.apache.tools.ant.launch.Launcher.run(Launcher.java:280)
at org.apache.tools.ant.launch.Launcher.main(Launcher.java:109)
Total time: 71 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
RE: [JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+118) - Build # 668 - Still Failing!
Posted by Uwe Schindler <uw...@thetaphi.de>.
Hi,
I sent mail to Oracle and Jigsaw-Dev ML about this bug. For now I disabled build 118 and reverted to build 116 on Policeman Jenkins.
Uwe
-----
Uwe Schindler
H.-H.-Meier-Allee 63, D-28213 Bremen
http://www.thetaphi.de
eMail: uwe@thetaphi.de
> -----Original Message-----
> From: Uwe Schindler [mailto:uwe@thetaphi.de]
> Sent: Monday, May 16, 2016 3:55 PM
> To: dev@lucene.apache.org
> Subject: RE: [JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+118) - Build
> # 668 - Still Failing!
>
> Hi,
>
> I am currently digging what causes this! The class is still part of JDK 9, but it
> seems that also loading deprecated classes prints lots of warnings. It looks
> like the CORBA and JAXB modules are hidden by Jigsaw.
>
> If needed I will open a bug report @ Oracle.
>
> Uwe
>
> -----
> Uwe Schindler
> H.-H.-Meier-Allee 63, D-28213 Bremen
> http://www.thetaphi.de
> eMail: uwe@thetaphi.de
>
> > -----Original Message-----
> > From: Policeman Jenkins Server [mailto:jenkins@thetaphi.de]
> > Sent: Monday, May 16, 2016 2:05 PM
> > To: dev@lucene.apache.org
> > Subject: [JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+118) - Build #
> > 668 - Still Failing!
> > Importance: Low
> >
> > Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/668/
> > Java: 32bit/jdk-9-ea+118 -client -XX:+UseConcMarkSweepGC
> >
> > All tests passed
> >
> > Build Log:
> > [...truncated 38436 lines...]
> > BUILD FAILED
> > /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The
> > following error occurred while executing this line:
> > /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:117: The
> > following error occurred while executing this line:
> > /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:347: The
> > following error occurred while executing this line:
> > /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-
> > build.xml:509: Check for forbidden API calls failed:
> > java.lang.ClassNotFoundException: javax.xml.bind.DatatypeConverter
> > at
> >
> de.thetaphi.forbiddenapis.Checker.getClassFromClassLoader(Checker.java:2
> > 64)
> > at
> > de.thetaphi.forbiddenapis.Checker.lookupRelatedClass(Checker.java:277)
> > at
> >
> de.thetaphi.forbiddenapis.ClassScanner$2.checkMethodAccess(ClassScanne
> > r.java:349)
> > at
> >
> de.thetaphi.forbiddenapis.ClassScanner$2.visitMethodInsn(ClassScanner.jav
> > a:469)
> > at de.thetaphi.forbiddenapis.asm.ClassReader.a(Unknown Source)
> > at de.thetaphi.forbiddenapis.asm.ClassReader.b(Unknown Source)
> > at de.thetaphi.forbiddenapis.asm.ClassReader.accept(Unknown
> > Source)
> > at de.thetaphi.forbiddenapis.asm.ClassReader.accept(Unknown
> > Source)
> > at de.thetaphi.forbiddenapis.Checker.checkClass(Checker.java:528)
> > at de.thetaphi.forbiddenapis.Checker.run(Checker.java:545)
> > at de.thetaphi.forbiddenapis.ant.AntTask.execute(AntTask.java:189)
> > at
> >
> org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
> > at
> > jdk.internal.reflect.GeneratedMethodAccessor561.invoke(Unknown
> Source)
> > at
> > jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-
> > ea/DelegatingMethodAccessorImpl.java:43)
> > at java.lang.reflect.Method.invoke(java.base@9-
> > ea/Method.java:531)
> > at
> >
> org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
> > at org.apache.tools.ant.Task.perform(Task.java:348)
> > at org.apache.tools.ant.Target.execute(Target.java:390)
> > at org.apache.tools.ant.Target.performTasks(Target.java:411)
> > at
> > org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
> > at
> >
> org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleChe
> > ckExecutor.java:38)
> > at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
> > at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
> > at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:302)
> > at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:221)
> > at
> >
> org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
> > at
> > jdk.internal.reflect.GeneratedMethodAccessor561.invoke(Unknown
> Source)
> > at
> > jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-
> > ea/DelegatingMethodAccessorImpl.java:43)
> > at java.lang.reflect.Method.invoke(java.base@9-
> > ea/Method.java:531)
> > at
> >
> org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
> > at org.apache.tools.ant.Task.perform(Task.java:348)
> > at org.apache.tools.ant.Target.execute(Target.java:390)
> > at org.apache.tools.ant.Target.performTasks(Target.java:411)
> > at
> > org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
> > at
> >
> org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleChe
> > ckExecutor.java:38)
> > at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
> > at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
> > at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:302)
> > at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:221)
> > at
> >
> org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
> > at
> > jdk.internal.reflect.GeneratedMethodAccessor561.invoke(Unknown
> Source)
> > at
> > jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-
> > ea/DelegatingMethodAccessorImpl.java:43)
> > at java.lang.reflect.Method.invoke(java.base@9-
> > ea/Method.java:531)
> > at
> >
> org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
> > at org.apache.tools.ant.Task.perform(Task.java:348)
> > at org.apache.tools.ant.Target.execute(Target.java:390)
> > at org.apache.tools.ant.Target.performTasks(Target.java:411)
> > at
> > org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
> > at
> >
> org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleChe
> > ckExecutor.java:38)
> > at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
> > at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
> > at
> > org.apache.tools.ant.taskdefs.CallTarget.execute(CallTarget.java:105)
> > at
> >
> org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
> > at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown
> > Source)
> > at
> > jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-
> > ea/DelegatingMethodAccessorImpl.java:43)
> > at java.lang.reflect.Method.invoke(java.base@9-
> > ea/Method.java:531)
> > at
> >
> org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
> > at org.apache.tools.ant.Task.perform(Task.java:348)
> > at org.apache.tools.ant.Target.execute(Target.java:390)
> > at org.apache.tools.ant.Target.performTasks(Target.java:411)
> > at
> > org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
> > at org.apache.tools.ant.Project.executeTarget(Project.java:1368)
> > at
> >
> org.apache.tools.ant.helper.DefaultExecutor.executeTargets(DefaultExecut
> > or.java:41)
> > at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
> > at org.apache.tools.ant.Main.runBuild(Main.java:809)
> > at org.apache.tools.ant.Main.startAnt(Main.java:217)
> > at org.apache.tools.ant.launch.Launcher.run(Launcher.java:280)
> > at org.apache.tools.ant.launch.Launcher.main(Launcher.java:109)
> >
> > Total time: 73 minutes 43 seconds
> > Build step 'Invoke Ant' marked build as failure
> > Archiving artifacts
> > [WARNINGS] Skipping publisher since build result is FAILURE
> > Recording test results
> > Email was triggered for: Failure - Any
> > Sending email for trigger: Failure - Any
> >
>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org
RE: [JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+118) - Build # 668 - Still Failing!
Posted by Uwe Schindler <uw...@thetaphi.de>.
Hi,
I am currently digging what causes this! The class is still part of JDK 9, but it seems that also loading deprecated classes prints lots of warnings. It looks like the CORBA and JAXB modules are hidden by Jigsaw.
If needed I will open a bug report @ Oracle.
Uwe
-----
Uwe Schindler
H.-H.-Meier-Allee 63, D-28213 Bremen
http://www.thetaphi.de
eMail: uwe@thetaphi.de
> -----Original Message-----
> From: Policeman Jenkins Server [mailto:jenkins@thetaphi.de]
> Sent: Monday, May 16, 2016 2:05 PM
> To: dev@lucene.apache.org
> Subject: [JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+118) - Build #
> 668 - Still Failing!
> Importance: Low
>
> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/668/
> Java: 32bit/jdk-9-ea+118 -client -XX:+UseConcMarkSweepGC
>
> All tests passed
>
> Build Log:
> [...truncated 38436 lines...]
> BUILD FAILED
> /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The
> following error occurred while executing this line:
> /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:117: The
> following error occurred while executing this line:
> /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:347: The
> following error occurred while executing this line:
> /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-
> build.xml:509: Check for forbidden API calls failed:
> java.lang.ClassNotFoundException: javax.xml.bind.DatatypeConverter
> at
> de.thetaphi.forbiddenapis.Checker.getClassFromClassLoader(Checker.java:2
> 64)
> at
> de.thetaphi.forbiddenapis.Checker.lookupRelatedClass(Checker.java:277)
> at
> de.thetaphi.forbiddenapis.ClassScanner$2.checkMethodAccess(ClassScanne
> r.java:349)
> at
> de.thetaphi.forbiddenapis.ClassScanner$2.visitMethodInsn(ClassScanner.jav
> a:469)
> at de.thetaphi.forbiddenapis.asm.ClassReader.a(Unknown Source)
> at de.thetaphi.forbiddenapis.asm.ClassReader.b(Unknown Source)
> at de.thetaphi.forbiddenapis.asm.ClassReader.accept(Unknown
> Source)
> at de.thetaphi.forbiddenapis.asm.ClassReader.accept(Unknown
> Source)
> at de.thetaphi.forbiddenapis.Checker.checkClass(Checker.java:528)
> at de.thetaphi.forbiddenapis.Checker.run(Checker.java:545)
> at de.thetaphi.forbiddenapis.ant.AntTask.execute(AntTask.java:189)
> at
> org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
> at
> jdk.internal.reflect.GeneratedMethodAccessor561.invoke(Unknown Source)
> at
> jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-
> ea/DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(java.base@9-
> ea/Method.java:531)
> at
> org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
> at org.apache.tools.ant.Task.perform(Task.java:348)
> at org.apache.tools.ant.Target.execute(Target.java:390)
> at org.apache.tools.ant.Target.performTasks(Target.java:411)
> at
> org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
> at
> org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleChe
> ckExecutor.java:38)
> at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
> at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
> at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:302)
> at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:221)
> at
> org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
> at
> jdk.internal.reflect.GeneratedMethodAccessor561.invoke(Unknown Source)
> at
> jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-
> ea/DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(java.base@9-
> ea/Method.java:531)
> at
> org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
> at org.apache.tools.ant.Task.perform(Task.java:348)
> at org.apache.tools.ant.Target.execute(Target.java:390)
> at org.apache.tools.ant.Target.performTasks(Target.java:411)
> at
> org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
> at
> org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleChe
> ckExecutor.java:38)
> at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
> at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
> at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:302)
> at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:221)
> at
> org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
> at
> jdk.internal.reflect.GeneratedMethodAccessor561.invoke(Unknown Source)
> at
> jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-
> ea/DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(java.base@9-
> ea/Method.java:531)
> at
> org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
> at org.apache.tools.ant.Task.perform(Task.java:348)
> at org.apache.tools.ant.Target.execute(Target.java:390)
> at org.apache.tools.ant.Target.performTasks(Target.java:411)
> at
> org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
> at
> org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleChe
> ckExecutor.java:38)
> at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
> at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
> at
> org.apache.tools.ant.taskdefs.CallTarget.execute(CallTarget.java:105)
> at
> org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
> at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown
> Source)
> at
> jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-
> ea/DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(java.base@9-
> ea/Method.java:531)
> at
> org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
> at org.apache.tools.ant.Task.perform(Task.java:348)
> at org.apache.tools.ant.Target.execute(Target.java:390)
> at org.apache.tools.ant.Target.performTasks(Target.java:411)
> at
> org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
> at org.apache.tools.ant.Project.executeTarget(Project.java:1368)
> at
> org.apache.tools.ant.helper.DefaultExecutor.executeTargets(DefaultExecut
> or.java:41)
> at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
> at org.apache.tools.ant.Main.runBuild(Main.java:809)
> at org.apache.tools.ant.Main.startAnt(Main.java:217)
> at org.apache.tools.ant.launch.Launcher.run(Launcher.java:280)
> at org.apache.tools.ant.launch.Launcher.main(Launcher.java:109)
>
> Total time: 73 minutes 43 seconds
> Build step 'Invoke Ant' marked build as failure
> Archiving artifacts
> [WARNINGS] Skipping publisher since build result is FAILURE
> Recording test results
> Email was triggered for: Failure - Any
> Sending email for trigger: Failure - Any
>
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org
[JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+118) - Build #
668 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/668/
Java: 32bit/jdk-9-ea+118 -client -XX:+UseConcMarkSweepGC
All tests passed
Build Log:
[...truncated 38436 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:117: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:347: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-build.xml:509: Check for forbidden API calls failed: java.lang.ClassNotFoundException: javax.xml.bind.DatatypeConverter
at de.thetaphi.forbiddenapis.Checker.getClassFromClassLoader(Checker.java:264)
at de.thetaphi.forbiddenapis.Checker.lookupRelatedClass(Checker.java:277)
at de.thetaphi.forbiddenapis.ClassScanner$2.checkMethodAccess(ClassScanner.java:349)
at de.thetaphi.forbiddenapis.ClassScanner$2.visitMethodInsn(ClassScanner.java:469)
at de.thetaphi.forbiddenapis.asm.ClassReader.a(Unknown Source)
at de.thetaphi.forbiddenapis.asm.ClassReader.b(Unknown Source)
at de.thetaphi.forbiddenapis.asm.ClassReader.accept(Unknown Source)
at de.thetaphi.forbiddenapis.asm.ClassReader.accept(Unknown Source)
at de.thetaphi.forbiddenapis.Checker.checkClass(Checker.java:528)
at de.thetaphi.forbiddenapis.Checker.run(Checker.java:545)
at de.thetaphi.forbiddenapis.ant.AntTask.execute(AntTask.java:189)
at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
at jdk.internal.reflect.GeneratedMethodAccessor561.invoke(Unknown Source)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:531)
at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
at org.apache.tools.ant.Task.perform(Task.java:348)
at org.apache.tools.ant.Target.execute(Target.java:390)
at org.apache.tools.ant.Target.performTasks(Target.java:411)
at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
at org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleCheckExecutor.java:38)
at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:302)
at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:221)
at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
at jdk.internal.reflect.GeneratedMethodAccessor561.invoke(Unknown Source)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:531)
at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
at org.apache.tools.ant.Task.perform(Task.java:348)
at org.apache.tools.ant.Target.execute(Target.java:390)
at org.apache.tools.ant.Target.performTasks(Target.java:411)
at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
at org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleCheckExecutor.java:38)
at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:302)
at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:221)
at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
at jdk.internal.reflect.GeneratedMethodAccessor561.invoke(Unknown Source)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:531)
at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
at org.apache.tools.ant.Task.perform(Task.java:348)
at org.apache.tools.ant.Target.execute(Target.java:390)
at org.apache.tools.ant.Target.performTasks(Target.java:411)
at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
at org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleCheckExecutor.java:38)
at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442)
at org.apache.tools.ant.taskdefs.CallTarget.execute(CallTarget.java:105)
at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291)
at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:531)
at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106)
at org.apache.tools.ant.Task.perform(Task.java:348)
at org.apache.tools.ant.Target.execute(Target.java:390)
at org.apache.tools.ant.Target.performTasks(Target.java:411)
at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399)
at org.apache.tools.ant.Project.executeTarget(Project.java:1368)
at org.apache.tools.ant.helper.DefaultExecutor.executeTargets(DefaultExecutor.java:41)
at org.apache.tools.ant.Project.executeTargets(Project.java:1251)
at org.apache.tools.ant.Main.runBuild(Main.java:809)
at org.apache.tools.ant.Main.startAnt(Main.java:217)
at org.apache.tools.ant.launch.Launcher.run(Launcher.java:280)
at org.apache.tools.ant.launch.Launcher.main(Launcher.java:109)
Total time: 73 minutes 43 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