You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/05/06 23:19:07 UTC
[JENKINS] Lucene-Solr-Tests-5.x-Java7 - Build # 3073 - Still
Failing
Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/3073/
1 tests failed.
REGRESSION: org.apache.solr.schema.TestCloudManagedSchemaConcurrent.test
Error Message:
Captured an uncaught exception in thread: Thread[id=3730, name=Thread-962, state=RUNNABLE, group=TGRP-TestCloudManagedSchemaConcurrent]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=3730, name=Thread-962, state=RUNNABLE, group=TGRP-TestCloudManagedSchemaConcurrent]
Caused by: java.lang.AssertionError: QUERY FAILED: xpath=/response/lst[@name='responseHeader']/int[@name='status'][.='0'] request=/schema/fieldtypes/newfieldtypePutThread3?wt=xml response=<?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">500</int>
<int name="QTime">111199</int>
</lst>
<lst name="error">
<str name="msg">java.io.IOException: Error opening /configs/conf1/protwords.txt</str>
<str name="trace">org.apache.solr.common.SolrException: java.io.IOException: Error opening /configs/conf1/protwords.txt
at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsInChain(ManagedIndexSchema.java:1298)
at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsForFieldType(ManagedIndexSchema.java:1146)
at org.apache.solr.schema.ManagedIndexSchema.postReadInform(ManagedIndexSchema.java:1131)
at org.apache.solr.schema.ManagedIndexSchema.addFieldTypes(ManagedIndexSchema.java:935)
at org.apache.solr.rest.schema.BaseFieldTypeResource.addNewFieldTypes(BaseFieldTypeResource.java:73)
at org.apache.solr.rest.schema.FieldTypeResource.addOrUpdateFieldType(FieldTypeResource.java:167)
at org.apache.solr.rest.schema.FieldTypeResource.put(FieldTypeResource.java:154)
at org.restlet.resource.ServerResource.doHandle(ServerResource.java:447)
at org.restlet.resource.ServerResource.doConditionalHandle(ServerResource.java:359)
at org.restlet.resource.ServerResource.handle(ServerResource.java:1044)
at org.restlet.resource.Finder.handle(Finder.java:236)
at org.restlet.routing.Filter.doHandle(Filter.java:150)
at org.restlet.routing.Filter.handle(Filter.java:197)
at org.restlet.routing.Router.doHandle(Router.java:422)
at org.restlet.routing.Router.handle(Router.java:639)
at org.restlet.routing.Filter.doHandle(Filter.java:150)
at org.restlet.routing.Filter.handle(Filter.java:197)
at org.restlet.routing.Filter.doHandle(Filter.java:150)
at org.restlet.routing.Filter.handle(Filter.java:197)
at org.restlet.routing.Filter.doHandle(Filter.java:150)
at org.restlet.engine.application.StatusFilter.doHandle(StatusFilter.java:140)
at org.restlet.routing.Filter.handle(Filter.java:197)
at org.restlet.routing.Filter.doHandle(Filter.java:150)
at org.restlet.routing.Filter.handle(Filter.java:197)
at org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202)
at org.restlet.engine.application.ApplicationHelper.handle(ApplicationHelper.java:75)
at org.restlet.Application.handle(Application.java:385)
at org.restlet.routing.Filter.doHandle(Filter.java:150)
at org.restlet.routing.Filter.handle(Filter.java:197)
at org.restlet.routing.Router.doHandle(Router.java:422)
at org.restlet.routing.Router.handle(Router.java:639)
at org.restlet.routing.Filter.doHandle(Filter.java:150)
at org.restlet.routing.Filter.handle(Filter.java:197)
at org.restlet.routing.Router.doHandle(Router.java:422)
at org.restlet.routing.Router.handle(Router.java:639)
at org.restlet.routing.Filter.doHandle(Filter.java:150)
at org.restlet.routing.Filter.handle(Filter.java:197)
at org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202)
at org.restlet.Component.handle(Component.java:408)
at org.restlet.Server.handle(Server.java:507)
at org.restlet.engine.connector.ServerHelper.handle(ServerHelper.java:63)
at org.restlet.engine.adapter.HttpServerHelper.handle(HttpServerHelper.java:143)
at org.restlet.ext.servlet.ServerServlet.service(ServerServlet.java:1117)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:808)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:587)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:191)
at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:72)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:188)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:172)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
at org.eclipse.jetty.server.Server.handle(Server.java:497)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.io.IOException: Error opening /configs/conf1/protwords.txt
at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:87)
at org.apache.lucene.analysis.util.AbstractAnalysisFactory.getLines(AbstractAnalysisFactory.java:252)
at org.apache.lucene.analysis.util.AbstractAnalysisFactory.getWordSet(AbstractAnalysisFactory.java:241)
at org.apache.lucene.analysis.miscellaneous.WordDelimiterFilterFactory.inform(WordDelimiterFilterFactory.java:105)
at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsInChain(ManagedIndexSchema.java:1296)
... 76 more
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /configs/conf1/protwords.txt
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:311)
at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:308)
at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:308)
at org.apache.solr.cloud.ZkController.pathExists(ZkController.java:807)
at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:81)
... 80 more
</str>
<int name="code">500</int>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([5D853F9AD37B3380]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.schema.TestCloudManagedSchemaConcurrent.verifySuccess(TestCloudManagedSchemaConcurrent.java:107)
at org.apache.solr.schema.TestCloudManagedSchemaConcurrent.addFieldTypePut(TestCloudManagedSchemaConcurrent.java:162)
at org.apache.solr.schema.TestCloudManagedSchemaConcurrent.access$600(TestCloudManagedSchemaConcurrent.java:45)
at org.apache.solr.schema.TestCloudManagedSchemaConcurrent$PutFieldTypeThread.run(TestCloudManagedSchemaConcurrent.java:563)
Build Log:
[...truncated 9933 lines...]
[junit4] Suite: org.apache.solr.schema.TestCloudManagedSchemaConcurrent
[junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/init-core-data-001
[junit4] 2> 399169 T1786 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 399169 T1786 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /b/
[junit4] 2> 399174 T1786 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 399175 T1787 oasc.ZkTestServer$2$1.setClientPort client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 399175 T1787 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 399275 T1786 oasc.ZkTestServer.run start zk server on port:43349
[junit4] 2> 399276 T1786 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 399277 T1786 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 399281 T1794 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6aa8db94 name:ZooKeeperConnection Watcher:127.0.0.1:43349 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 399281 T1786 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 399281 T1786 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 399282 T1786 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 399284 T1786 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 399285 T1786 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 399286 T1797 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@193e5eb7 name:ZooKeeperConnection Watcher:127.0.0.1:43349/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 399286 T1786 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 399286 T1786 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 399287 T1786 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 399288 T1786 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 399289 T1786 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 399290 T1786 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 399291 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig-managed-schema.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 399292 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 399294 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 399295 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 399296 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 399297 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 399298 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 399299 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 399300 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 399300 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 399302 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 399302 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 399304 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 399304 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 399306 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 399307 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 399308 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 399309 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 399310 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 399311 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 399312 T1786 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 399313 T1786 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 400942 T1786 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1
[junit4] 2> 400946 T1786 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 400948 T1786 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@2238c491{/b,null,AVAILABLE}
[junit4] 2> 400951 T1786 oejs.AbstractConnector.doStart Started ServerConnector@79b045bd{HTTP/1.1}{127.0.0.1:56061}
[junit4] 2> 400952 T1786 oejs.Server.doStart Started @406377ms
[junit4] 2> 400952 T1786 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=56061, solr.data.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/tempDir-001/control/data, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores, hostContext=/b}
[junit4] 2> 400953 T1786 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@3972aa3f
[junit4] 2> 400953 T1786 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/'
[junit4] 2> 401001 T1786 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/solr.xml
[junit4] 2> 401060 T1786 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores
[junit4] 2> 401060 T1786 oasc.CoreContainer.<init> New CoreContainer 1730628185
[junit4] 2> 401060 T1786 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/]
[junit4] 2> 401060 T1786 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/lib
[junit4] 2> 401061 T1786 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/lib).
[junit4] 2> 401072 T1786 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 401080 T1786 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 401080 T1786 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 401081 T1786 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 401081 T1786 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 401081 T1786 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:43349/solr
[junit4] 2> 401081 T1786 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 401081 T1786 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 401082 T1786 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 401084 T1811 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3185e0d2 name:ZooKeeperConnection Watcher:127.0.0.1:43349 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 401084 T1786 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 401085 T1786 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 401087 T1786 n:127.0.0.1:56061_b oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 401088 T1814 n:127.0.0.1:56061_b oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@61d26c72 name:ZooKeeperConnection Watcher:127.0.0.1:43349/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 401088 T1786 n:127.0.0.1:56061_b oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 401089 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 401091 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 401093 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 401095 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 401097 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 401099 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 401100 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 401102 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 401103 T1786 n:127.0.0.1:56061_b oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56061_b
[junit4] 2> 401104 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56061_b
[junit4] 2> 401105 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 401106 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 401108 T1786 n:127.0.0.1:56061_b oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 401109 T1786 n:127.0.0.1:56061_b oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:56061_b
[junit4] 2> 401109 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 401111 T1786 n:127.0.0.1:56061_b oasc.Overseer.start Overseer (id=93778313289007107-127.0.0.1:56061_b-n_0000000000) starting
[junit4] 2> 401112 T1786 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 401118 T1786 n:127.0.0.1:56061_b oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 401119 T1816 n:127.0.0.1:56061_b oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 401119 T1786 n:127.0.0.1:56061_b oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 401119 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 401123 T1786 n:127.0.0.1:56061_b oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores
[junit4] 2> 401125 T1786 n:127.0.0.1:56061_b oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {coreNodeName=, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1, collection=control_collection, shard=, loadOnStartup=true, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/, schema=schema.xml, transient=false, dataDir=data/, config=solrconfig.xml, name=collection1}
[junit4] 2> 401126 T1786 n:127.0.0.1:56061_b oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/
[junit4] 2> 401126 T1786 n:127.0.0.1:56061_b oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 401127 T1818 n:127.0.0.1:56061_b c:control_collection x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 401127 T1818 n:127.0.0.1:56061_b c:control_collection x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 401128 T1818 n:127.0.0.1:56061_b oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 401128 T1814 n:127.0.0.1:56061_b oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 401129 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:56061/b",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:56061_b",
[junit4] 2> "numShards":"1",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"} current state version: 0
[junit4] 2> 401130 T1815 n:127.0.0.1:56061_b oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:56061/b",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:56061_b",
[junit4] 2> "numShards":"1",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 401130 T1815 n:127.0.0.1:56061_b oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 401130 T1815 n:127.0.0.1:56061_b oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 401131 T1814 n:127.0.0.1:56061_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 402128 T1818 n:127.0.0.1:56061_b oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 402129 T1818 n:127.0.0.1:56061_b oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 402129 T1818 n:127.0.0.1:56061_b oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 402129 T1818 n:127.0.0.1:56061_b oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 402130 T1818 n:127.0.0.1:56061_b oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 402130 T1818 n:127.0.0.1:56061_b oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/'
[junit4] 2> 402146 T1818 n:127.0.0.1:56061_b oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 402155 T1818 n:127.0.0.1:56061_b oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 402174 T1818 n:127.0.0.1:56061_b oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 402213 T1818 n:127.0.0.1:56061_b oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 402214 T1818 n:127.0.0.1:56061_b oass.ManagedIndexSchemaFactory.create The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 402214 T1818 n:127.0.0.1:56061_b oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 402223 T1818 n:127.0.0.1:56061_b oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 402554 T1818 n:127.0.0.1:56061_b oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 402568 T1818 n:127.0.0.1:56061_b oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 402570 T1818 n:127.0.0.1:56061_b oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 402585 T1818 n:127.0.0.1:56061_b oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 402589 T1818 n:127.0.0.1:56061_b oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 402592 T1818 n:127.0.0.1:56061_b oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 402593 T1818 n:127.0.0.1:56061_b oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 402593 T1818 n:127.0.0.1:56061_b oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 402593 T1818 n:127.0.0.1:56061_b oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 402594 T1818 n:127.0.0.1:56061_b oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 402594 T1818 n:127.0.0.1:56061_b oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 402597 T1818 n:127.0.0.1:56061_b oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 402598 T1818 n:127.0.0.1:56061_b oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak
[junit4] 2> 402600 T1818 n:127.0.0.1:56061_b oass.ManagedIndexSchemaFactory.zkUgradeToManagedSchema After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak
[junit4] 2> 402600 T1818 n:127.0.0.1:56061_b oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 402601 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 402601 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/], dataDir=[null]
[junit4] 2> 402601 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 402602 T1818 n:127.0.0.1:56061_b x:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/data
[junit4] 2> 402603 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/data/index/
[junit4] 2> 402604 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 402605 T1818 n:127.0.0.1:56061_b x:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/data/index
[junit4] 2> 402605 T1818 n:127.0.0.1:56061_b x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=51.3134765625, floorSegmentMB=1.37890625, forceMergeDeletesPctAllowed=24.161693006434582, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 402616 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@203028ea; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 402616 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 402619 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 402619 T1818 n:127.0.0.1:56061_b x:collection1 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 402644 T1818 n:127.0.0.1:56061_b x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 402646 T1818 n:127.0.0.1:56061_b x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 402649 T1818 n:127.0.0.1:56061_b x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 402651 T1818 n:127.0.0.1:56061_b x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 402654 T1818 n:127.0.0.1:56061_b x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/ping,/admin/properties,/get,/admin/luke,/admin/system,/admin/plugins,/config,/update,/admin/threads,/admin/file,/schema,standard,/dump,/admin/segments,/admin/mbeans,/update/json/docs,/admin/logging,/replication,/update/csv,/update/json
[junit4] 2> 402656 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 402664 T1818 n:127.0.0.1:56061_b x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 402665 T1818 n:127.0.0.1:56061_b x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 402666 T1818 n:127.0.0.1:56061_b x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 402667 T1818 n:127.0.0.1:56061_b x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 402667 T1818 n:127.0.0.1:56061_b x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 402669 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/control-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@203028ea; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 402669 T1818 n:127.0.0.1:56061_b x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 402670 T1818 n:127.0.0.1:56061_b x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@5103515c[collection1] main
[junit4] 2> 402670 T1818 n:127.0.0.1:56061_b x:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 402671 T1818 n:127.0.0.1:56061_b x:collection1 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 402671 T1818 n:127.0.0.1:56061_b x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 402671 T1818 n:127.0.0.1:56061_b x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 402672 T1818 n:127.0.0.1:56061_b x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 402672 T1818 n:127.0.0.1:56061_b x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 402672 T1818 n:127.0.0.1:56061_b x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 402673 T1818 n:127.0.0.1:56061_b x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 402673 T1818 n:127.0.0.1:56061_b x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 402673 T1818 n:127.0.0.1:56061_b x:collection1 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 402674 T1818 n:127.0.0.1:56061_b x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 402674 T1819 n:127.0.0.1:56061_b x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5103515c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 402674 T1818 n:127.0.0.1:56061_b x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
[junit4] 2> 402675 T1818 n:127.0.0.1:56061_b x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 402675 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:56061/b collection:control_collection shard:shard1
[junit4] 2> 402676 T1786 n:127.0.0.1:56061_b oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3
[junit4] 2> 402676 T1786 n:127.0.0.1:56061_b oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 402676 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 402678 T1786 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 402679 T1786 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 402680 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 402681 T1825 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3e4cd7b6 name:ZooKeeperConnection Watcher:127.0.0.1:43349/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 402681 T1786 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 402681 T1786 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 402682 T1786 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 402682 T1814 n:127.0.0.1:56061_b oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 402682 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 402682 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1704 name=collection1 org.apache.solr.core.SolrCore@543fa9c0 url=http://127.0.0.1:56061/b/collection1 node=127.0.0.1:56061_b C1704_STATE=coll:control_collection core:collection1 props:{base_url=http://127.0.0.1:56061/b, state=down, node_name=127.0.0.1:56061_b, core=collection1}
[junit4] 2> 402682 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 C1704 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:56061/b/collection1/
[junit4] 2> 402683 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 402683 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 C1704 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 402683 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 C1704 oasc.SyncStrategy.syncToMe http://127.0.0.1:56061/b/collection1/ has no replicas
[junit4] 2> 402683 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:56061/b/collection1/ shard1
[junit4] 2> 402684 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 402684 T1786 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 402684 T1814 n:127.0.0.1:56061_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 402684 T1825 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 402688 T1814 n:127.0.0.1:56061_b oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 402688 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:56061/b",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 402791 T1825 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 402791 T1814 n:127.0.0.1:56061_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 402840 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ZkController.register We are http://127.0.0.1:56061/b/collection1/ and leader is http://127.0.0.1:56061/b/collection1/
[junit4] 2> 402841 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56061/b
[junit4] 2> 402841 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 402841 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 402841 T1822 n:127.0.0.1:56061_b c:control_collection s:shard1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 402842 T1814 n:127.0.0.1:56061_b oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 402843 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:56061/b",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1:56061_b",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"} current state version: 3
[junit4] 2> 402844 T1815 n:127.0.0.1:56061_b oasco.ReplicaMutator.updateState Update state numShards=4 message={
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:56061/b",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1:56061_b",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"}
[junit4] 2> 402946 T1825 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 402946 T1814 n:127.0.0.1:56061_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 404242 T1786 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1
[junit4] 2> 404246 T1786 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001
[junit4] 2> 404247 T1786 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 404248 T1786 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@2543d919{/b,null,AVAILABLE}
[junit4] 2> 404249 T1786 oejs.AbstractConnector.doStart Started ServerConnector@3dbe444{HTTP/1.1}{127.0.0.1:23508}
[junit4] 2> 404249 T1786 oejs.Server.doStart Started @409674ms
[junit4] 2> 404249 T1786 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=23508, solr.data.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores, hostContext=/b}
[junit4] 2> 404250 T1786 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@3972aa3f
[junit4] 2> 404250 T1786 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/'
[junit4] 2> 404296 T1786 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/solr.xml
[junit4] 2> 404351 T1786 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores
[junit4] 2> 404352 T1786 oasc.CoreContainer.<init> New CoreContainer 1091778753
[junit4] 2> 404352 T1786 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/]
[junit4] 2> 404352 T1786 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/lib
[junit4] 2> 404353 T1786 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/lib).
[junit4] 2> 404364 T1786 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 404371 T1786 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 404372 T1786 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 404373 T1786 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 404373 T1786 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 404373 T1786 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:43349/solr
[junit4] 2> 404373 T1786 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 404373 T1786 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 404374 T1786 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 404375 T1839 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@55288cef name:ZooKeeperConnection Watcher:127.0.0.1:43349 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 404376 T1786 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 404376 T1786 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 404378 T1786 n:127.0.0.1:23508_b oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 404379 T1842 n:127.0.0.1:23508_b oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@122290e6 name:ZooKeeperConnection Watcher:127.0.0.1:43349/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 404379 T1786 n:127.0.0.1:23508_b oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 404381 T1786 n:127.0.0.1:23508_b oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 405385 T1786 n:127.0.0.1:23508_b oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:23508_b
[junit4] 2> 405386 T1786 n:127.0.0.1:23508_b oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:23508_b
[junit4] 2> 405388 T1786 n:127.0.0.1:23508_b oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 405391 T1786 n:127.0.0.1:23508_b oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores
[junit4] 2> 405397 T1786 n:127.0.0.1:23508_b oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {loadOnStartup=true, transient=false, shard=, coreNodeName=, collection=collection1, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1, dataDir=data/, name=collection1, config=solrconfig.xml, schema=schema.xml, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/}
[junit4] 2> 405398 T1786 n:127.0.0.1:23508_b oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/
[junit4] 2> 405398 T1786 n:127.0.0.1:23508_b oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 405399 T1843 n:127.0.0.1:23508_b c:collection1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 405399 T1843 n:127.0.0.1:23508_b c:collection1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 405400 T1843 n:127.0.0.1:23508_b oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 405400 T1814 n:127.0.0.1:56061_b oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 405401 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:23508/b",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:23508_b",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"} current state version: 4
[junit4] 2> 405402 T1815 n:127.0.0.1:56061_b oasco.ReplicaMutator.updateState Update state numShards=4 message={
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:23508/b",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:23508_b",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 405402 T1815 n:127.0.0.1:56061_b oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 405402 T1815 n:127.0.0.1:56061_b oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 405403 T1825 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 405403 T1814 n:127.0.0.1:56061_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 405404 T1842 n:127.0.0.1:23508_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 406400 T1843 n:127.0.0.1:23508_b oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 406401 T1843 n:127.0.0.1:23508_b oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 406402 T1843 n:127.0.0.1:23508_b oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 406402 T1843 n:127.0.0.1:23508_b oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 406403 T1843 n:127.0.0.1:23508_b oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 406403 T1843 n:127.0.0.1:23508_b oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/'
[junit4] 2> 406430 T1843 n:127.0.0.1:23508_b oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 406449 T1843 n:127.0.0.1:23508_b oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 406480 T1843 n:127.0.0.1:23508_b oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 406562 T1843 n:127.0.0.1:23508_b oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 406565 T1843 n:127.0.0.1:23508_b oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/managed-schema
[junit4] 2> 406604 T1843 n:127.0.0.1:23508_b oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 407734 T1843 n:127.0.0.1:23508_b oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 407863 T1843 n:127.0.0.1:23508_b oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 407908 T1843 n:127.0.0.1:23508_b oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 407964 T1843 n:127.0.0.1:23508_b oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 407989 T1843 n:127.0.0.1:23508_b oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 408048 T1843 n:127.0.0.1:23508_b oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 408054 T1843 n:127.0.0.1:23508_b oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 408070 T1843 n:127.0.0.1:23508_b oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 408072 T1843 n:127.0.0.1:23508_b oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 408074 T1843 n:127.0.0.1:23508_b oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 408075 T1843 n:127.0.0.1:23508_b oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 408079 T1843 n:127.0.0.1:23508_b oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 408079 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 408080 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/], dataDir=[null]
[junit4] 2> 408081 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 408102 T1843 n:127.0.0.1:23508_b x:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/data
[junit4] 2> 408133 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 408164 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 408182 T1843 n:127.0.0.1:23508_b x:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/data/index
[junit4] 2> 408183 T1843 n:127.0.0.1:23508_b x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=51.3134765625, floorSegmentMB=1.37890625, forceMergeDeletesPctAllowed=24.161693006434582, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 408228 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c10dbdd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 408229 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 408243 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 408248 T1843 n:127.0.0.1:23508_b x:collection1 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 408634 T1843 n:127.0.0.1:23508_b x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 408662 T1843 n:127.0.0.1:23508_b x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 408665 T1843 n:127.0.0.1:23508_b x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 408667 T1843 n:127.0.0.1:23508_b x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 408685 T1843 n:127.0.0.1:23508_b x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/ping,/admin/properties,/get,/admin/luke,/admin/system,/admin/plugins,/config,/update,/admin/threads,/admin/file,/schema,standard,/dump,/admin/segments,/admin/mbeans,/update/json/docs,/admin/logging,/replication,/update/csv,/update/json
[junit4] 2> 408729 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 408736 T1843 n:127.0.0.1:23508_b x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 408736 T1843 n:127.0.0.1:23508_b x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 408751 T1843 n:127.0.0.1:23508_b x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 408751 T1843 n:127.0.0.1:23508_b x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 408752 T1843 n:127.0.0.1:23508_b x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 408754 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-1-001/cores/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c10dbdd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 408754 T1843 n:127.0.0.1:23508_b x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 408755 T1843 n:127.0.0.1:23508_b x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@12b50622[collection1] main
[junit4] 2> 408755 T1843 n:127.0.0.1:23508_b x:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 408756 T1843 n:127.0.0.1:23508_b x:collection1 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 408756 T1843 n:127.0.0.1:23508_b x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 408756 T1843 n:127.0.0.1:23508_b x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 408757 T1843 n:127.0.0.1:23508_b x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 408757 T1843 n:127.0.0.1:23508_b x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 408757 T1843 n:127.0.0.1:23508_b x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 408758 T1843 n:127.0.0.1:23508_b x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 408758 T1843 n:127.0.0.1:23508_b x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 408758 T1843 n:127.0.0.1:23508_b x:collection1 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 408759 T1843 n:127.0.0.1:23508_b x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 408759 T1844 n:127.0.0.1:23508_b x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12b50622[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 408759 T1843 n:127.0.0.1:23508_b x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
[junit4] 2> 408765 T1843 n:127.0.0.1:23508_b x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 408768 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:23508/b collection:collection1 shard:shard1
[junit4] 2> 408770 T1786 n:127.0.0.1:23508_b oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3
[junit4] 2> 408771 T1786 n:127.0.0.1:23508_b oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 408772 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 408801 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 408820 T1814 n:127.0.0.1:56061_b oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 408824 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 408827 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 408830 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1705 name=collection1 org.apache.solr.core.SolrCore@41cfa47e url=http://127.0.0.1:23508/b/collection1 node=127.0.0.1:23508_b C1705_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:23508/b, state=down, node_name=127.0.0.1:23508_b, core=collection1}
[junit4] 2> 408830 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 C1705 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:23508/b/collection1/
[junit4] 2> 408831 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 C1705 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 408831 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 C1705 oasc.SyncStrategy.syncToMe http://127.0.0.1:23508/b/collection1/ has no replicas
[junit4] 2> 408831 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:23508/b/collection1/ shard1
[junit4] 2> 408833 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 408835 T1825 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 408835 T1814 n:127.0.0.1:56061_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 408837 T1842 n:127.0.0.1:23508_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 408845 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:23508/b",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 6
[junit4] 2> 408848 T1814 n:127.0.0.1:56061_b oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 408851 T1825 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 408851 T1814 n:127.0.0.1:56061_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 408851 T1842 n:127.0.0.1:23508_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 408898 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ZkController.register We are http://127.0.0.1:23508/b/collection1/ and leader is http://127.0.0.1:23508/b/collection1/
[junit4] 2> 408898 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:23508/b
[junit4] 2> 408899 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 408901 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 408903 T1847 n:127.0.0.1:23508_b c:collection1 s:shard1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 408908 T1814 n:127.0.0.1:56061_b oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 408913 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:23508/b",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1:23508_b",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"} current state version: 7
[junit4] 2> 408915 T1815 n:127.0.0.1:56061_b oasco.ReplicaMutator.updateState Update state numShards=4 message={
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:23508/b",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1:23508_b",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"}
[junit4] 2> 409022 T1825 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 409022 T1814 n:127.0.0.1:56061_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 409022 T1842 n:127.0.0.1:23508_b oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 410226 T1786 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/cores/collection1
[junit4] 2> 410229 T1786 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 in directory /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001
[junit4] 2> 410230 T1786 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 410232 T1786 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@6fe5950e{/b,null,AVAILABLE}
[junit4] 2> 410233 T1786 oejs.AbstractConnector.doStart Started ServerConnector@6352782b{HTTP/1.1}{127.0.0.1:59116}
[junit4] 2> 410233 T1786 oejs.Server.doStart Started @415658ms
[junit4] 2> 410233 T1786 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solrconfig=solrconfig.xml, hostPort=59116, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/cores, solr.data.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/tempDir-001/jetty2, hostContext=/b}
[junit4] 2> 410234 T1786 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@3972aa3f
[junit4] 2> 410234 T1786 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/'
[junit4] 2> 410268 T1786 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/solr.xml
[junit4] 2> 410308 T1786 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/cores
[junit4] 2> 410308 T1786 oasc.CoreContainer.<init> New CoreContainer 384894435
[junit4] 2> 410309 T1786 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/]
[junit4] 2> 410309 T1786 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/lib
[junit4] 2> 410310 T1786 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/lib).
[junit4] 2> 410319 T1786 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 410324 T1786 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 410325 T1786 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 410325 T1786 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 410326 T1786 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 410326 T1786 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:43349/solr
[junit4] 2> 410326 T1786 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 410326 T1786 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 410327 T1786 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 410329 T1861 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@62f2a22c name:ZooKeeperConnection Watcher:127.0.0.1:43349 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410329 T1786 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 410330 T1786 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 410332 T1786 n:127.0.0.1:59116_b oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 410333 T1864 n:127.0.0.1:59116_b oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8ea8a9c name:ZooKeeperConnection Watcher:127.0.0.1:43349/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410333 T1786 n:127.0.0.1:59116_b oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 410337 T1786 n:127.0.0.1:59116_b oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 411341 T1786 n:127.0.0.1:59116_b oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59116_b
[junit4] 2> 411342 T1786 n:127.0.0.1:59116_b oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59116_b
[junit4] 2> 411345 T1786 n:127.0.0.1:59116_b oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 411347 T1786 n:127.0.0.1:59116_b oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/cores
[junit4] 2> 411351 T1786 n:127.0.0.1:59116_b oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/cores/collection1, config=solrconfig.xml, loadOnStartup=true, dataDir=data/, schema=schema.xml, coreNodeName=, name=collection1, collection=collection1, shard=, transient=false, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/cores/collection1/}
[junit4] 2> 411352 T1786 n:127.0.0.1:59116_b oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001/shard-2-001/cores/collection1/
[junit4] 2> 411352 T1786 n:127.0.0.1:59116_b oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 411353 T1865 n:127.0.0.1:59116_b c:collection1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 411353 T1865 n:127.0.0.1:59116_b c:collection1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 411354 T1865 n:127.0.0.1:59116_b oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 411354 T1814 n:127.0.0.1:56061_b oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 411355 T1815 n:127.0.0.1:56061_b oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:59116/b",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:59116_b",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"} current state version: 8
[junit4] 2> 411356 T1815 n:127.0.0.1:56061_b oasco.ReplicaMutator.updateState Update state numShards=4
[...truncated too long message...]
handle(Filter.java:197)
[junit4] > at org.restlet.routing.Router.doHandle(Router.java:422)
[junit4] > at org.restlet.routing.Router.handle(Router.java:639)
[junit4] > at org.restlet.routing.Filter.doHandle(Filter.java:150)
[junit4] > at org.restlet.routing.Filter.handle(Filter.java:197)
[junit4] > at org.restlet.routing.Router.doHandle(Router.java:422)
[junit4] > at org.restlet.routing.Router.handle(Router.java:639)
[junit4] > at org.restlet.routing.Filter.doHandle(Filter.java:150)
[junit4] > at org.restlet.routing.Filter.handle(Filter.java:197)
[junit4] > at org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202)
[junit4] > at org.restlet.Component.handle(Component.java:408)
[junit4] > at org.restlet.Server.handle(Server.java:507)
[junit4] > at org.restlet.engine.connector.ServerHelper.handle(ServerHelper.java:63)
[junit4] > at org.restlet.engine.adapter.HttpServerHelper.handle(HttpServerHelper.java:143)
[junit4] > at org.restlet.ext.servlet.ServerServlet.service(ServerServlet.java:1117)
[junit4] > at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
[junit4] > at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:808)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:587)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:191)
[junit4] > at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:72)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:188)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:172)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
[junit4] > at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:497)
[junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
[junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
[junit4] > at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.io.IOException: Error opening /configs/conf1/protwords.txt
[junit4] > at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:87)
[junit4] > at org.apache.lucene.analysis.util.AbstractAnalysisFactory.getLines(AbstractAnalysisFactory.java:252)
[junit4] > at org.apache.lucene.analysis.util.AbstractAnalysisFactory.getWordSet(AbstractAnalysisFactory.java:241)
[junit4] > at org.apache.lucene.analysis.miscellaneous.WordDelimiterFilterFactory.inform(WordDelimiterFilterFactory.java:105)
[junit4] > at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsInChain(ManagedIndexSchema.java:1296)
[junit4] > ... 76 more
[junit4] > Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /configs/conf1/protwords.txt
[junit4] > at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] > at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] > at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
[junit4] > at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:311)
[junit4] > at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:308)
[junit4] > at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] > at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:308)
[junit4] > at org.apache.solr.cloud.ZkController.pathExists(ZkController.java:807)
[junit4] > at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:81)
[junit4] > ... 80 more
[junit4] > </str>
[junit4] > <int name="code">500</int>
[junit4] > </lst>
[junit4] > </response>
[junit4] > at __randomizedtesting.SeedInfo.seed([5D853F9AD37B3380]:0)
[junit4] > at org.apache.solr.schema.TestCloudManagedSchemaConcurrent.verifySuccess(TestCloudManagedSchemaConcurrent.java:107)
[junit4] > at org.apache.solr.schema.TestCloudManagedSchemaConcurrent.addFieldTypePut(TestCloudManagedSchemaConcurrent.java:162)
[junit4] > at org.apache.solr.schema.TestCloudManagedSchemaConcurrent.access$600(TestCloudManagedSchemaConcurrent.java:45)
[junit4] > at org.apache.solr.schema.TestCloudManagedSchemaConcurrent$PutFieldTypeThread.run(TestCloudManagedSchemaConcurrent.java:563)
[junit4] 2> 855435 T1786 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.schema.TestCloudManagedSchemaConcurrent 5D853F9AD37B3380-001
[junit4] 2> 456271 T1785 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene50, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=pt_PT, timezone=Antarctica/Vostok
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=110462488,total=385875968
[junit4] 2> NOTE: All tests run in this JVM: [AsyncMigrateRouteKeyTest, PrimUtilsTest, TestFieldResource, TestIndexingPerformance, CopyFieldTest, PingRequestHandlerTest, ExternalFileFieldSortTest, TestMiniSolrCloudCluster, TestOmitPositions, TestSolr4Spatial2, TestWordDelimiterFilterFactory, TestDocBasedVersionConstraints, TestSweetSpotSimilarityFactory, TestCSVResponseWriter, TestSolrConfigHandler, TestSimpleTrackingShardHandler, TestSolr4Spatial, TestCloudSchemaless, TriLevelCompositeIdRoutingTest, DeleteReplicaTest, TestPseudoReturnFields, TestSolrQueryParserResource, TestCloudManagedSchemaConcurrent]
[junit4] Completed [167/487] on J3 in 457.06s, 1 test, 1 error <<< FAILURES!
[...truncated 1024 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:536: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:484: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:61: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/extra-targets.xml:39: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:229: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:511: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1433: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:991: There were test failures: 487 suites, 1954 tests, 1 error, 71 ignored (26 assumptions)
Total time: 54 minutes 59 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-5.x-Java7 #3070
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 218645264 bytes
Compression is 0.0%
Took 46 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure