You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/07/17 15:01:33 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1717
- Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1717/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.schema.TestCloudSchemaless.testDistribSearch
Error Message:
Timeout occured while waiting response from server at: https://127.0.0.1:53609/_uuu/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:53609/_uuu/collection1
at __randomizedtesting.SeedInfo.seed([B2B1CF2F05007AD9:33574137725F1AE5]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:561)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54)
at org.apache.solr.schema.TestCloudSchemaless.doTest(TestCloudSchemaless.java:138)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:863)
at sun.reflect.GeneratedMethodAccessor35.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:150)
at java.net.SocketInputStream.read(SocketInputStream.java:121)
at sun.security.ssl.InputRecord.readFully(InputRecord.java:465)
at sun.security.ssl.InputRecord.read(InputRecord.java:503)
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:954)
at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:911)
at sun.security.ssl.AppInputStream.read(AppInputStream.java:105)
at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:449)
... 46 more
Build Log:
[...truncated 11937 lines...]
[junit4] Suite: org.apache.solr.schema.TestCloudSchemaless
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/init-core-data-001
[junit4] 2> 6330544 T14997 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 6330544 T14997 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /_uuu/
[junit4] 2> 6330548 T14997 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 6330550 T14997 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 6330551 T14998 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 6330652 T14997 oasc.ZkTestServer.run start zk server on port:53578
[junit4] 2> 6330654 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6330661 T15004 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@155ec9b8 name:ZooKeeperConnection Watcher:127.0.0.1:53578 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6330661 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6330662 T14997 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 6330671 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6330674 T15006 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1433b9a2 name:ZooKeeperConnection Watcher:127.0.0.1:53578/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6330674 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6330675 T14997 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 6330682 T14997 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 6330688 T14997 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 6330692 T14997 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 6330699 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-schemaless.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 6330700 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 6330708 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema-add-schema-fields-update-processor.xml to /configs/conf1/schema.xml
[junit4] 2> 6330709 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 6330714 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 6330715 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 6330722 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 6330723 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 6330729 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 6330730 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 6330736 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 6330736 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 6330743 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 6330744 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 6330749 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 6330750 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 6330756 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 6330757 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 6330761 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 6330763 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 6330769 T14997 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 6330770 T14997 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 6330778 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6330781 T15008 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@697c1dd7 name:ZooKeeperConnection Watcher:127.0.0.1:53578/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6330782 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6332402 T14997 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 6332408 T14997 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 6332414 T14997 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53582
[junit4] 2> 6332416 T14997 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 6332416 T14997 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6332417 T14997 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002
[junit4] 2> 6332417 T14997 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/'
[junit4] 2> 6332458 T14997 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/solr.xml
[junit4] 2> 6332477 T14997 oasc.CoreContainer.<init> New CoreContainer 1842186689
[junit4] 2> 6332478 T14997 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/]
[junit4] 2> 6332478 T14997 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 6332478 T14997 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 6332479 T14997 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 6332479 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6332480 T14997 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6332480 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6332480 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6332480 T14997 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6332481 T14997 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 6332486 T14997 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 6332486 T14997 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 6332486 T14997 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 6332487 T14997 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53578/solr
[junit4] 2> 6332487 T14997 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 6332489 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6332492 T15019 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@24927fca name:ZooKeeperConnection Watcher:127.0.0.1:53578 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6332492 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6332497 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6332500 T15021 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3c8e57d9 name:ZooKeeperConnection Watcher:127.0.0.1:53578/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6332500 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6332502 T14997 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 6332509 T14997 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 6332518 T14997 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 6332524 T14997 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 6332533 T14997 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 6332543 T14997 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 6332547 T14997 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53582__uuu
[junit4] 2> 6332549 T14997 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53582__uuu
[junit4] 2> 6332555 T14997 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 6332560 T14997 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 6332568 T14997 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53582__uuu
[junit4] 2> 6332568 T14997 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 6332571 T14997 oasc.Overseer.start Overseer (id=92117515944525828-127.0.0.1:53582__uuu-n_0000000000) starting
[junit4] 2> 6332577 T14997 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 6332600 T15022 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 6332602 T15023 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 6332604 T14997 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 6332609 T14997 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 6332611 T14997 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6332622 T15024 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 6332623 T15024 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6332624 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6332627 T15024 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 6332628 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6332630 T15022 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:53582/_uuu",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:53582__uuu",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 6332630 T15022 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 6332631 T15022 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 6332635 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6332638 T15021 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> 6333629 T15024 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 6333629 T15024 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 6333631 T15024 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 6333632 T15024 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 6333634 T15024 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6333634 T15024 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/'
[junit4] 2> 6333636 T15024 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 6333637 T15024 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 6333638 T15024 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 6333688 T15024 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 6333713 T15024 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 6333715 T15024 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> 6333719 T15024 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 6333722 T15024 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 6333735 T15024 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 6333738 T15024 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 6333742 T15024 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak
[junit4] 2> 6333749 T15024 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> 6333750 T15024 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 6333750 T15024 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 6333751 T15024 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/data/
[junit4] 2> 6333751 T15024 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 6333752 T15024 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/data
[junit4] 2> 6333753 T15024 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/data/index/
[junit4] 2> 6333754 T15024 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 6333755 T15024 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/data/index
[junit4] 2> 6333755 T15024 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=12.736328125, floorSegmentMB=2.1748046875, forceMergeDeletesPctAllowed=22.08225331833807, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7570818112558045
[junit4] 2> 6333762 T15024 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6612f753; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6333762 T15024 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6333764 T15024 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 6333765 T15024 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 6333765 T15024 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 6333766 T15024 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 6333766 T15024 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 6333766 T15024 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 6333766 T15024 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 6333767 T15024 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 6333776 T15024 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6333778 T15024 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 6333778 T15024 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 6333779 T15024 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=87.041015625, floorSegmentMB=1.42578125, forceMergeDeletesPctAllowed=2.342926416652833, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8452076608840537
[junit4] 2> 6333781 T15024 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6612f753; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6333781 T15024 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6333781 T15024 oass.SolrIndexSearcher.<init> Opening Searcher@4d858e44[collection1] main
[junit4] 2> 6333782 T15024 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 6333785 T15024 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6333785 T15024 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 6333787 T15024 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 6333787 T15024 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 6333788 T15024 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6333789 T15024 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 6333789 T15024 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6333791 T15024 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json
[junit4] 2> 6333798 T15024 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json
[junit4] 2> 6333798 T15024 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6333799 T15024 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 6333799 T15024 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 6333803 T15024 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 6333803 T15025 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4d858e44[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 6333804 T15028 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:53582/_uuu collection:control_collection shard:shard1
[junit4] 2> 6333805 T14997 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 6333806 T14997 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 6333806 T15028 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 6333813 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6333816 T15030 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4124efa7 name:ZooKeeperConnection Watcher:127.0.0.1:53578/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6333816 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6333819 T14997 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6333821 T15028 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 6333825 T14997 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 6333827 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6333829 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6333831 T15028 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 6333832 T15028 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4295 name=collection1 org.apache.solr.core.SolrCore@7ab55390 url=https://127.0.0.1:53582/_uuu/collection1 node=127.0.0.1:53582__uuu C4295_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:53582/_uuu, core=collection1, node_name=127.0.0.1:53582__uuu}
[junit4] 2> 6333832 T15028 C4295 P53582 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:53582/_uuu/collection1/
[junit4] 2> 6333832 T15028 C4295 P53582 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 6333833 T15028 C4295 P53582 oasc.SyncStrategy.syncToMe https://127.0.0.1:53582/_uuu/collection1/ has no replicas
[junit4] 2> 6333833 T15028 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:53582/_uuu/collection1/ shard1
[junit4] 2> 6333833 T15028 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 6333838 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6333847 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6333855 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6333960 T15021 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> 6333964 T15030 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> 6334009 T15028 oasc.ZkController.register We are https://127.0.0.1:53582/_uuu/collection1/ and leader is https://127.0.0.1:53582/_uuu/collection1/
[junit4] 2> 6334010 T15028 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:53582/_uuu
[junit4] 2> 6334010 T15028 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 6334010 T15028 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 6334011 T15028 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6334018 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6334018 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6334018 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6334019 T15028 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6334024 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6334025 T15022 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:53582/_uuu",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:53582__uuu",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 6334033 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6334139 T15021 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> 6334139 T15030 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> 6335728 T14997 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 6335730 T14997 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 6335737 T14997 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 6335738 T14997 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53586
[junit4] 2> 6335743 T14997 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 6335743 T14997 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6335744 T14997 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003
[junit4] 2> 6335744 T14997 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/'
[junit4] 2> 6335806 T14997 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/solr.xml
[junit4] 2> 6335823 T14997 oasc.CoreContainer.<init> New CoreContainer 1244691440
[junit4] 2> 6335824 T14997 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/]
[junit4] 2> 6335824 T14997 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 6335825 T14997 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 6335826 T14997 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 6335827 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6335827 T14997 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6335828 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6335828 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6335829 T14997 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6335829 T14997 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 6335833 T14997 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 6335833 T14997 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 6335834 T14997 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 6335834 T14997 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53578/solr
[junit4] 2> 6335834 T14997 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 6335837 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6335840 T15041 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@768749c2 name:ZooKeeperConnection Watcher:127.0.0.1:53578 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6335840 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6335845 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6335848 T15043 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4bdb5c9c name:ZooKeeperConnection Watcher:127.0.0.1:53578/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6335849 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6335869 T14997 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6336881 T14997 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53586__uuu
[junit4] 2> 6336884 T14997 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53586__uuu
[junit4] 2> 6336907 T15044 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 6336907 T15044 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6336910 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6336910 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6336911 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6336910 T15044 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 6336915 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6336917 T15022 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:53586/_uuu",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:53586__uuu",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 6336917 T15022 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4]
[junit4] 2> 6336918 T15022 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 6336925 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6336926 T15021 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> 6336928 T15030 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> 6336926 T15043 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> 6337914 T15044 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 6337914 T15044 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 6337916 T15044 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 6337916 T15044 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 6337920 T15044 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6337920 T15044 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/'
[junit4] 2> 6337921 T15044 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 6337921 T15044 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 6337922 T15044 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 6337965 T15044 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 6337976 T15044 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 6337980 T15044 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 6337984 T15044 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 6338011 T15044 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 6338012 T15044 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 6338012 T15044 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 6338013 T15044 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/data/
[junit4] 2> 6338013 T15044 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 6338014 T15044 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/data
[junit4] 2> 6338015 T15044 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/data/index/
[junit4] 2> 6338016 T15044 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 6338016 T15044 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/data/index
[junit4] 2> 6338017 T15044 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=12.736328125, floorSegmentMB=2.1748046875, forceMergeDeletesPctAllowed=22.08225331833807, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7570818112558045
[junit4] 2> 6338023 T15044 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2e9b6b7b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6338024 T15044 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6338025 T15044 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 6338027 T15044 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 6338028 T15044 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 6338028 T15044 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 6338028 T15044 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 6338029 T15044 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 6338029 T15044 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 6338029 T15044 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 6338038 T15044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6338041 T15044 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 6338041 T15044 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 6338042 T15044 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=87.041015625, floorSegmentMB=1.42578125, forceMergeDeletesPctAllowed=2.342926416652833, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8452076608840537
[junit4] 2> 6338044 T15044 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2e9b6b7b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6338044 T15044 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6338044 T15044 oass.SolrIndexSearcher.<init> Opening Searcher@2deb4a9d[collection1] main
[junit4] 2> 6338044 T15044 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 6338047 T15044 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6338048 T15044 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 6338050 T15044 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 6338050 T15044 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 6338050 T15044 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6338055 T15044 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json
[junit4] 2> 6338055 T15044 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6338055 T15044 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 6338055 T15044 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 6338055 T15044 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 6338060 T15044 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 6338060 T15045 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2deb4a9d[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 6338062 T15048 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:53586/_uuu collection:collection1 shard:shard2
[junit4] 2> 6338063 T14997 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 6338064 T14997 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 6338064 T15048 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 6338117 T15048 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 6338123 T15048 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 6338123 T15048 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4296 name=collection1 org.apache.solr.core.SolrCore@6366e65c url=https://127.0.0.1:53586/_uuu/collection1 node=127.0.0.1:53586__uuu C4296_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:53586/_uuu, core=collection1, node_name=127.0.0.1:53586__uuu}
[junit4] 2> 6338124 T15048 C4296 P53586 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:53586/_uuu/collection1/
[junit4] 2> 6338124 T15048 C4296 P53586 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 6338124 T15048 C4296 P53586 oasc.SyncStrategy.syncToMe https://127.0.0.1:53586/_uuu/collection1/ has no replicas
[junit4] 2> 6338126 T15048 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:53586/_uuu/collection1/ shard2
[junit4] 2> 6338127 T15048 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 6338129 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6338143 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6338160 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6338171 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6338276 T15043 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> 6338276 T15030 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> 6338276 T15021 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> 6338321 T15048 oasc.ZkController.register We are https://127.0.0.1:53586/_uuu/collection1/ and leader is https://127.0.0.1:53586/_uuu/collection1/
[junit4] 2> 6338321 T15048 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:53586/_uuu
[junit4] 2> 6338321 T15048 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 6338322 T15048 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 6338322 T15048 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6338324 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6338325 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6338326 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6338328 T15048 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6338334 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6338335 T15022 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:53586/_uuu",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:53586__uuu",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 6338340 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6338445 T15043 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> 6338445 T15021 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> 6338445 T15030 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> 6339785 T14997 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 6339787 T14997 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 6339796 T14997 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 6339798 T14997 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53589
[junit4] 2> 6339803 T14997 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 6339804 T14997 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6339805 T14997 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004
[junit4] 2> 6339805 T14997 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/'
[junit4] 2> 6339864 T14997 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/solr.xml
[junit4] 2> 6339886 T14997 oasc.CoreContainer.<init> New CoreContainer 631729729
[junit4] 2> 6339886 T14997 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/]
[junit4] 2> 6339887 T14997 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 6339888 T14997 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 6339889 T14997 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 6339889 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6339890 T14997 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6339891 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6339891 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6339893 T14997 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6339893 T14997 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 6339900 T14997 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 6339901 T14997 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 6339901 T14997 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 6339902 T14997 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53578/solr
[junit4] 2> 6339902 T14997 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 6339905 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6339909 T15059 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f12542d name:ZooKeeperConnection Watcher:127.0.0.1:53578 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6339909 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6339916 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6339920 T15061 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@44f81cf name:ZooKeeperConnection Watcher:127.0.0.1:53578/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6339920 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6339945 T14997 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6340957 T14997 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53589__uuu
[junit4] 2> 6340960 T14997 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53589__uuu
[junit4] 2> 6340979 T15062 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 6340980 T15062 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6340982 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6340982 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6340982 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6340982 T15062 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 6340987 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6340988 T15022 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:53589/_uuu",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:53589__uuu",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 6340989 T15022 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4
[junit4] 2> 6340989 T15022 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
[junit4] 2> 6340995 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6340997 T15021 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6340997 T15043 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6340997 T15061 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6340997 T15030 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6341985 T15062 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 6341985 T15062 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 6341988 T15062 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 6341988 T15062 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 6341991 T15062 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6341992 T15062 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/'
[junit4] 2> 6341994 T15062 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 6341994 T15062 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 6341995 T15062 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 6342066 T15062 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 6342081 T15062 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 6342086 T15062 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 6342090 T15062 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 6342109 T15062 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 6342109 T15062 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 6342110 T15062 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 6342111 T15062 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/data/
[junit4] 2> 6342112 T15062 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 6342114 T15062 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/data
[junit4] 2> 6342115 T15062 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/data/index/
[junit4] 2> 6342117 T15062 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 6342118 T15062 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/data/index
[junit4] 2> 6342119 T15062 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=12.736328125, floorSegmentMB=2.1748046875, forceMergeDeletesPctAllowed=22.08225331833807, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7570818112558045
[junit4] 2> 6342125 T15062 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4da8d8d1; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6342126 T15062 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6342129 T15062 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 6342134 T15062 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 6342134 T15062 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 6342135 T15062 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 6342136 T15062 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 6342136 T15062 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 6342136 T15062 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 6342136 T15062 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 6342143 T15062 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6342146 T15062 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 6342146 T15062 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 6342148 T15062 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=87.041015625, floorSegmentMB=1.42578125, forceMergeDeletesPctAllowed=2.342926416652833, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8452076608840537
[junit4] 2> 6342149 T15062 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4da8d8d1; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6342150 T15062 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6342150 T15062 oass.SolrIndexSearcher.<init> Opening Searcher@60bfdb9f[collection1] main
[junit4] 2> 6342150 T15062 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 6342154 T15062 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6342155 T15062 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 6342157 T15062 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 6342157 T15062 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 6342158 T15062 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6342161 T15062 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json
[junit4] 2> 6342161 T15062 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6342161 T15062 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 6342162 T15062 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 6342162 T15062 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 6342166 T15062 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 6342166 T15063 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@60bfdb9f[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 6342168 T15066 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:53589/_uuu collection:collection1 shard:shard3
[junit4] 2> 6342169 T14997 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 6342169 T14997 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 6342170 T15066 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4] 2> 6342186 T15066 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3
[junit4] 2> 6342191 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6342193 T15066 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 6342194 T15066 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4297 name=collection1 org.apache.solr.core.SolrCore@2f011994 url=https://127.0.0.1:53589/_uuu/collection1 node=127.0.0.1:53589__uuu C4297_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:53589/_uuu, core=collection1, node_name=127.0.0.1:53589__uuu}
[junit4] 2> 6342194 T15066 C4297 P53589 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:53589/_uuu/collection1/
[junit4] 2> 6342194 T15066 C4297 P53589 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 6342194 T15066 C4297 P53589 oasc.SyncStrategy.syncToMe https://127.0.0.1:53589/_uuu/collection1/ has no replicas
[junit4] 2> 6342195 T15066 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:53589/_uuu/collection1/ shard3
[junit4] 2> 6342195 T15066 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4] 2> 6342199 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6342212 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6342220 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6342327 T15043 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6342328 T15061 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6342327 T15030 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6342327 T15021 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6342372 T15066 oasc.ZkController.register We are https://127.0.0.1:53589/_uuu/collection1/ and leader is https://127.0.0.1:53589/_uuu/collection1/
[junit4] 2> 6342373 T15066 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:53589/_uuu
[junit4] 2> 6342373 T15066 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 6342374 T15066 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 6342375 T15066 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6342377 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6342377 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6342378 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6342380 T15066 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6342383 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6342387 T15022 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:53589/_uuu",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:53589__uuu",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 6342395 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6342499 T15043 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6342500 T15030 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6342500 T15061 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6342499 T15021 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6344102 T14997 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 6344102 T14997 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 6344109 T14997 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 6344111 T14997 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53592
[junit4] 2> 6344114 T14997 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 6344115 T14997 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6344115 T14997 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-005
[junit4] 2> 6344116 T14997 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-005/'
[junit4] 2> 6344161 T14997 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-005/solr.xml
[junit4] 2> 6344182 T14997 oasc.CoreContainer.<init> New CoreContainer 1165405244
[junit4] 2> 6344182 T14997 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-B2B1CF2F05007AD9-001/tempDir-005/]
[junit4] 2> 6344184 T14997 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 6344184 T14997 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 6344184 T14997 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 6344185 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6344185 T14997 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6344185 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6344186 T14997 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6344186 T14997 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6344186 T14997 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 6344190 T14997 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 6344190 T14997 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 6344190 T14997 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 6344191 T14997 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53578/solr
[junit4] 2> 6344191 T14997 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 6344193 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6344196 T15077 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@35126280 name:ZooKeeperConnection Watcher:127.0.0.1:53578 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6344196 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6344202 T14997 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6344204 T15079 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7b202e5d name:ZooKeeperConnection Watcher:127.0.0.1:53578/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6344204 T14997 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6344221 T14997 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6345231 T14997 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53592__uuu
[junit4] 2> 6345234 T14997 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53592__uuu
[junit4] 2> 6345260 T15080 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 6345261 T15080 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6345263 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6345263 T15080 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 6345263 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6345264 T15021 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6345270 T15022 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 6345272 T15022 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:53592/_uuu",
[junit4] 2> "core":"collection1",
[...truncated too long message...]
nsAPIAsyncDistributedZkTest, TestDynamicFieldCollectionResource, CurrencyFieldOpenExchangeTest, JsonLoaderTest, TestSchemaVersionResource, SuggesterTSTTest, TestRangeQuery, DeleteShardTest, OverseerStatusTest, TestRecovery, FileUtilsTest, TestDistribDocBasedVersion, ZkSolrClientTest, TestCollationField, RankQueryTest, TestDynamicFieldResource, TestManagedResource, TestReversedWildcardFilterFactory, WordBreakSolrSpellCheckerTest, TestDefaultSimilarityFactory, TestManagedSynonymFilterFactory, LegacyHTMLStripCharFilterTest, CopyFieldTest, TestXIncludeConfig, TestBM25SimilarityFactory, TestCoreDiscovery, TestCloudManagedSchemaConcurrent, TestElisionMultitermQuery, TimeZoneUtilsTest, TestLRUCache, HighlighterConfigTest, XmlUpdateRequestHandlerTest, LukeRequestHandlerTest, TestCopyFieldCollectionResource, TestDocBasedVersionConstraints, TestLazyCores, StandardRequestHandlerTest, TestJoin, ChangedSchemaMergeTest, SpatialFilterTest, TestSolrQueryParserDefaultOperatorResource, TestSolrXmlPersistor, TestPostingsSolrHighlighter, AbstractAnalyticsStatsTest, TestSuggestSpellingConverter, AnalyticsMergeStrategyTest, TestSchemaSimilarityResource, TestSolrXMLSerializer, StatelessScriptUpdateProcessorFactoryTest, PathHierarchyTokenizerFactoryTest, LoggingHandlerTest, IndexSchemaRuntimeFieldTest, ZkNodePropsTest, TestSearchPerf, CircularListTest, TestPerFieldSimilarity, HdfsChaosMonkeySafeLeaderTest, DistributedExpandComponentTest, RemoteQueryErrorTest, TestFieldResource, UniqFieldsUpdateProcessorFactoryTest, TestZkChroot, TestCustomSort, QueryParsingTest, SOLR749Test, BadComponentTest, DirectSolrConnectionTest, SolrTestCaseJ4Test, TestMultiCoreConfBootstrap, TestStandardQParsers, TestReplicationHandler, SuggesterWFSTTest, HdfsLockFactoryTest, TestFunctionQuery, TestHashPartitioner, DebugComponentTest, TestSystemIdResolver, OutputWriterTest, HdfsCollectionsAPIDistributedZkTest, TestReloadAndDeleteDocs, PrimitiveFieldTypeTest, SolrCmdDistributorTest, IndexBasedSpellCheckerTest, TestQueryTypes, HdfsWriteToMultipleCollectionsTest, ConvertedLegacyTest, TriLevelCompositeIdRoutingTest, TestSolrXmlPersistence, TestSort, MBeansHandlerTest, TestExtendedDismaxParser, DistributedQueryElevationComponentTest, TestSolrCoreProperties, AnalysisErrorHandlingTest, TestMiniSolrCloudCluster, TestSolrDeletionPolicy1, TestStressUserVersions, TestHighlightDedupGrouping, ChaosMonkeyNothingIsSafeTest, ScriptEngineTest, PingRequestHandlerTest, TestCharFilters, CacheHeaderTest, TestBadConfig, BadCopyFieldTest, AssignTest, TestFieldTypeCollectionResource, LeaderElectionIntegrationTest, SpellCheckComponentTest, TestPHPSerializedResponseWriter, ZkCLITest, LeaderElectionTest, TestComponentsName, DistanceFunctionTest, SolrXmlInZkTest, FunctionTest, ResponseLogComponentTest, DeleteReplicaTest, AddBlockUpdateTest, MigrateRouteKeyTest, TestHighFrequencyDictionaryFactory, TestOverriddenPrefixQueryForCustomFieldType, MergeStrategyTest, AutoCommitTest, TestRandomDVFaceting, TermVectorComponentDistributedTest, SoftAutoCommitTest, ClusterStateUpdateTest, DateMathParserTest, TestWriterPerf, BasicFunctionalityTest, TestJmxIntegration, SampleTest, DirectUpdateHandlerTest, TestIBSimilarityFactory, FastVectorHighlighterTest, TestRecoveryHdfs, AnalysisAfterCoreReloadTest, TestLMDirichletSimilarityFactory, MoreLikeThisHandlerTest, TestDFRSimilarityFactory, TestFoldingMultitermQuery, CachingDirectoryFactoryTest, HdfsRecoveryZkTest, TestInitQParser, ClusterStateTest, TestShortCircuitedRequests, DistributedQueryComponentCustomSortTest, DocExpirationUpdateProcessorFactoryTest, TestStressVersions, MultiThreadedOCPTest, EchoParamsTest, TestSerializedLuceneMatchVersion, DocValuesTest, ReplicationFactorTest, TestCollationFieldDocValues, XsltUpdateRequestHandlerTest, TestClassNameShortening, TestPartialUpdateDeduplication, OpenCloseCoreStressTest, NotRequiredUniqueKeyTest, FieldAnalysisRequestHandlerTest, TestExceedMaxTermLength, TestManagedResourceStorage, BasicZkTest, HighlighterMaxOffsetTest, DistributedSuggestComponentTest, NoCacheHeaderTest, CoreAdminHandlerTest, TestManagedSchema, AlternateDirectoryTest, AddSchemaFieldsUpdateProcessorFactoryTest, SimplePostToolTest, HighlighterTest, RequestHandlersTest, TestNoOpRegenerator, DateFieldTest, HttpPartitionTest, TestAddFieldRealTimeGet, TestRequestStatusCollectionAPI, UnloadDistributedZkTest, TestTolerantSearch, TestBinaryResponseWriter, TestRandomFaceting, HdfsDirectoryTest, TestStressLucene, BinaryUpdateRequestHandlerTest, UpdateParamsTest, TestSolrJ, ShowFileRequestHandlerTest, TestLFUCache, TestReplicationHandlerBackup, PluginInfoTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, SyncSliceTest, OverseerTest, ShardRoutingTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ShardRoutingCustomTest, TestDistributedSearch, TestDistributedGrouping, TestFaceting, DistributedSpellCheckComponentTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestReload, HardAutoCommitTest, DistributedTermsComponentTest, TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, StatsComponentTest, TestGroupingSearch, QueryElevationComponentTest, PeerSyncTest, BadIndexSchemaTest, TestFiltering, OverseerCollectionProcessorTest, TestIndexSearcher, CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, SolrRequestParserTest, SuggesterTest, TestTrie, TestCSVLoader, PolyFieldTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, SolrInfoMBeanTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestOmitPositions, FileBasedSpellCheckerTest, TermsComponentTest, TestValueSourceCache, TermVectorComponentTest, TestIndexingPerformance, RequiredFieldsTest, TestSolrQueryParser, TestArbitraryIndexDir, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestCSVResponseWriter, TestAnalyzedSuggestions, CSVRequestHandlerTest, SearchHandlerTest, CoreContainerCoreInitFailuresTest, TestQuerySenderListener, TestSolrIndexConfig, TestQuerySenderNoQuery, SolrIndexConfigTest, TestStressRecovery, TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, TestDocSet, TestBinaryField, MinimalSchemaTest, TestConfig, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCodecSupport, SynonymTokenizerTest, TestSweetSpotSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestFastWriter, TestJmxMonitoredMap, ResourceLoaderTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, TestFastLRUCache, PreAnalyzedFieldTest, PrimUtilsTest, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, TestUtils, TestDocumentBuilder, SliceStateTest, SystemInfoHandlerTest, TestRTGBase, CursorPagingTest, TestDistributedMissingSort, NoFacetTest, ExpressionTest, AbstractAnalyticsFacetTest, FieldFacetExtrasTest, FieldFacetTest, QueryFacetTest, RangeFacetTest, AliasIntegrationTest, AsyncMigrateRouteKeyTest, ConnectionManagerTest, CustomCollectionTest, DeleteInactiveReplicaTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, OverseerRolesTest, RollingRestartTest, ShardSplitTest, TestCloudInspectUtil, TestCollectionAPI, TestLeaderElectionZkExpiry, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsSyncSliceTest, StressHdfsTest, QueryResultKeyTest, TestConfigSets, TestImplicitCoreProperties, TestInfoStreamLogging, TestNRTOpen, TestNonNRTOpen, TestShardHandlerFactory, TestSolrXml, CoreAdminCreateDiscoverTest, CoreAdminRequestStatusTest, CoreMergeIndexesAdminHandlerTest, InfoHandlerTest, DistributedDebugComponentTest, DistributedQueryComponentOptimizationTest, SuggestComponentTest, TestChildDocTransformer, TestRestManager, TestDefaultSearchFieldResource, TestFieldTypeResource, TestManagedSchemaFieldResource, TestSchemaNameResource, TestSchemaResource, TestSolrQueryParserResource, TestUniqueKeyFieldResource, TestManagedStopFilterFactory, DateRangeFieldTest, DocValuesMissingTest, EnumFieldTest, ModifyConfFileTest, TestCloudManagedSchema, TestCloudSchemaless]
[junit4] Completed in 275.32s, 1 test, 1 error <<< FAILURES!
[...truncated 81 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1298: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:922: There were test failures: 419 suites, 1712 tests, 1 error, 43 ignored (16 assumptions)
Total time: 152 minutes 36 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1718
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1718/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.TestLBHttpSolrServer.testReliability
Error Message:
No live SolrServers available to handle this request
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request
at __randomizedtesting.SeedInfo.seed([D5A2C9A1ECE0E2:C11D7F8F008A314B]:0)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:525)
at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at org.apache.solr.client.solrj.TestLBHttpSolrServer.testReliability(TestLBHttpSolrServer.java:222)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:53758/solr
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:561)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:478)
... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:150)
at java.net.SocketInputStream.read(SocketInputStream.java:121)
at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:449)
... 46 more
Build Log:
[...truncated 12350 lines...]
[junit4] Suite: org.apache.solr.client.solrj.TestLBHttpSolrServer
[junit4] 2> SLF4J: Class path contains multiple SLF4J bindings.
[junit4] 2> SLF4J: Found binding in [jar:file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/test-lib/slf4j-log4j12-1.7.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: Found binding in [jar:file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/slf4j-log4j12-1.7.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
[junit4] 2> SLF4J: Actual binding is of type [org.slf4j.impl.Log4jLoggerFactory]
[junit4] 2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout.
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/init-core-data-001
[junit4] 2> 4158 T12 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 4340 T12 oas.SolrTestCaseJ4.setUp ###Starting testReliability
[junit4] 2> 5638 T12 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 6000 T12 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53754
[junit4] 2> 6151 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 6311 T12 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6313 T12 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001
[junit4] 2> 6313 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/'
[junit4] 2> 6994 T12 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/solr.xml
[junit4] 2> 7394 T12 oasc.CoreContainer.<init> New CoreContainer 1984527425
[junit4] 2> 7403 T12 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/]
[junit4] 2> 7576 T12 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 7577 T12 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 7592 T12 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 7593 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 7594 T12 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 7597 T12 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 7597 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 7598 T12 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 7599 T12 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 8141 T12 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 8147 T12 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 8160 T12 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 8752 T24 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/'
[junit4] 2> 8958 T24 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 9431 T24 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 9499 T24 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 9538 T24 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 9866 T24 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 9883 T24 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/
[junit4] 2> 10109 T24 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockFSDirectoryFactory
[junit4] 2> 10162 T24 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/
[junit4] 2> 10163 T24 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 10313 T24 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data
[junit4] 2> 10335 T24 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index/
[junit4] 2> 10336 T24 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 10393 T24 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index
[junit4] 2> 10645 T24 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 10650 T24 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 11893 T24 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 12003 T24 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 12004 T24 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 12005 T24 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 12012 T24 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 12053 T24 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 12119 T24 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 12245 T24 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 12368 T24 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 12369 T24 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 12424 T24 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 12424 T24 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 12567 T24 oass.SolrIndexSearcher.<init> Opening Searcher@73fa506b[collection1] main
[junit4] 2> 12639 T24 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf
[junit4] 2> 12639 T24 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf}
[junit4] 2> 12770 T24 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf
[junit4] 2> 12772 T24 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 12785 T24 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf
[junit4] 2> 12786 T24 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 12922 T24 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 12923 T24 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 12934 T25 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@73fa506b[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 12943 T12 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
[junit4] 2> 12944 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@6fe1dd25
[junit4] 2> 15917 T20 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 1126
[junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@6fe1dd25
[junit4] 2> 16229 T19 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@6fe1dd25
[junit4] 2> 17855 T19 C2 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index),segFN=segments_2,generation=2}
[junit4] 2> 17857 T19 C2 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> ASYNC NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@6fe1dd25
[junit4] 2> 18113 T19 C3 oass.SolrIndexSearcher.<init> Opening Searcher@2da65cc8[collection1] main
[junit4] 2> 18121 T19 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 18132 T25 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2da65cc8[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C10))}
[junit4] 2> 18135 T19 C3 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 1906
[junit4] 2> 18152 T12 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 18159 T12 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53756
[junit4] 2> 18162 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 18163 T12 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 18163 T12 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001
[junit4] 2> 18164 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/'
[junit4] 2> 18240 T12 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/solr.xml
[junit4] 2> 18364 T12 oasc.CoreContainer.<init> New CoreContainer 27947974
[junit4] 2> 18367 T12 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/]
[junit4] 2> 18369 T12 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 18370 T12 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 18370 T12 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 18371 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 18372 T12 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 18374 T12 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 18374 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 18381 T12 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 18381 T12 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 18383 T12 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 18383 T12 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 18393 T12 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 19052 T36 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/'
[junit4] 2> 19161 T36 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 19362 T36 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 19363 T36 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 19382 T36 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 19446 T36 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 19447 T36 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/
[junit4] 2> 19449 T36 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockFSDirectoryFactory
[junit4] 2> 19449 T36 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/
[junit4] 2> 19450 T36 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 19457 T36 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data
[junit4] 2> 19458 T36 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index/
[junit4] 2> 19459 T36 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 19464 T36 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index
[junit4] 2> 19478 T36 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 19489 T36 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 19499 T36 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 19500 T36 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 19502 T36 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 19503 T36 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 19503 T36 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 19505 T36 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 19506 T36 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 19515 T36 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 19524 T36 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 19526 T36 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 19536 T36 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 19536 T36 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 19537 T36 oass.SolrIndexSearcher.<init> Opening Searcher@56dca0fd[collection1] main
[junit4] 2> 19538 T36 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/conf
[junit4] 2> 19541 T36 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/conf}
[junit4] 2> 19542 T36 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/conf
[junit4] 2> 19543 T36 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 19550 T36 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/conf
[junit4] 2> 19552 T36 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 19553 T36 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 19555 T37 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@56dca0fd[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 19567 T36 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 19586 T12 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
[junit4] 2> 19588 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C4 name=collection1 org.apache.solr.core.SolrCore@1837f6f6
[junit4] 2> 19607 T33 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 9
[junit4] 2> 19617 T34 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C5 name=collection1 org.apache.solr.core.SolrCore@1837f6f6
[junit4] 2> 19664 T34 C5 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index),segFN=segments_2,generation=2}
[junit4] 2> 19666 T34 C5 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 19687 T34 C5 oass.SolrIndexSearcher.<init> Opening Searcher@591474e4[collection1] main
[junit4] 2> 19689 T37 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@591474e4[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C10))}
[junit4] 2> 19689 T34 C5 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 19690 T34 C5 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 74
[junit4] 2> 19725 T12 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 19735 T12 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53758
[junit4] 2> 19737 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 19738 T12 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 19738 T12 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001
[junit4] 2> 19745 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/'
[junit4] 2> 19820 T12 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/solr.xml
[junit4] 2> 19933 T12 oasc.CoreContainer.<init> New CoreContainer 943255342
[junit4] 2> 19935 T12 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/]
[junit4] 2> 19937 T12 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 19938 T12 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 19940 T12 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 19943 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 19943 T12 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 19947 T12 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 19948 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 19949 T12 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 19953 T12 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 19956 T12 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 19957 T12 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 19958 T12 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 19971 T48 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/'
[junit4] 2> 20086 T48 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 20171 T48 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 20173 T48 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 20184 T48 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 20212 T48 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 20213 T48 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/
[junit4] 2> 20218 T48 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockFSDirectoryFactory
[junit4] 2> 20219 T48 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/
[junit4] 2> 20219 T48 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 20221 T48 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data
[junit4] 2> 20223 T48 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index/
[junit4] 2> 20223 T48 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 20225 T48 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index
[junit4] 2> 20232 T48 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 20234 T48 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 20240 T48 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 20243 T48 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 20243 T48 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 20244 T48 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 20245 T48 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 20246 T48 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 20246 T48 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 20253 T48 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 20262 T48 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 20262 T48 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 20272 T48 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 20272 T48 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 20273 T48 oass.SolrIndexSearcher.<init> Opening Searcher@3dd7feb7[collection1] main
[junit4] 2> 20274 T48 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/conf
[junit4] 2> 20275 T48 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/conf}
[junit4] 2> 20275 T48 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/conf
[junit4] 2> 20277 T48 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 20277 T48 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/conf
[junit4] 2> 20292 T48 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 20292 T48 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 20293 T48 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 20293 T49 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3dd7feb7[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 20297 T12 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
[junit4] 2> 20298 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C6 name=collection1 org.apache.solr.core.SolrCore@41304b17
[junit4] 2> 20312 T43 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 8
[junit4] 2> 20321 T44 C6 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C7 name=collection1 org.apache.solr.core.SolrCore@41304b17
[junit4] 2> 20412 T44 C7 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index),segFN=segments_2,generation=2}
[junit4] 2> 20414 T44 C7 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 20429 T44 C7 oass.SolrIndexSearcher.<init> Opening Searcher@67b37eb4[collection1] main
[junit4] 2> 20442 T44 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 20446 T49 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67b37eb4[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C10))}
[junit4] 2> 20449 T44 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 128
[junit4] 2> 20474 T12 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=27947974
[junit4] 2> 20477 T12 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1837f6f6
[junit4] 2> 20478 T12 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 20481 T12 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 20482 T12 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 20482 T12 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 20500 T12 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 20510 T12 oasc.CachingDirectoryFactory.close Closing MockFSDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 20513 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index;done=false>>]
[junit4] 2> 20514 T12 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index
[junit4] 2> 20708 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data;done=false>>]
[junit4] 2> 20709 T12 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data
[junit4] 2> 20711 T12 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 26062 T12 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1984527425
[junit4] 2> 26067 T12 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> ASYNC NEW_CORE C8 name=collection1 org.apache.solr.core.SolrCore@6fe1dd25
[junit4] 2> 26115 T20 C8 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=617
[junit4] 2> ASYNC NEW_CORE C9 name=collection1 org.apache.solr.core.SolrCore@41304b17
[junit4] 2> 26115 T45 C9 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=326
[junit4] 2> ASYNC NEW_CORE C10 name=collection1 org.apache.solr.core.SolrCore@6fe1dd25
[junit4] 2> 26175 T20 C10 oasc.SolrException.log ERROR null:org.eclipse.jetty.io.EofException
[junit4] 2> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:142)
[junit4] 2> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
[junit4] 2> at org.apache.solr.common.util.FastOutputStream.flush(FastOutputStream.java:214)
[junit4] 2> at org.apache.solr.common.util.FastOutputStream.flushBuffer(FastOutputStream.java:207)
[junit4] 2> at org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:98)
[junit4] 2> at org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:51)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:761)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:428)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:137)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 26180 T20 C10 oasc.SolrException.log ERROR null:org.eclipse.jetty.io.EofException
[junit4] 2> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:142)
[junit4] 2> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
[junit4] 2> at org.apache.solr.common.util.FastOutputStream.flush(FastOutputStream.java:214)
[junit4] 2> at org.apache.solr.common.util.FastOutputStream.flushBuffer(FastOutputStream.java:207)
[junit4] 2> at org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:98)
[junit4] 2> at org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:51)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:761)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:428)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:137)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> ASYNC NEW_CORE C11 name=collection1 org.apache.solr.core.SolrCore@6fe1dd25
[junit4] 2> 26210 T20 C11 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6fe1dd25
[junit4] 2> 26211 T20 C11 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 26214 T20 C11 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 26214 T20 C11 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 26216 T20 C11 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 26229 T20 C11 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 26236 T20 C11 oasc.CachingDirectoryFactory.close Closing MockFSDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 26236 T20 C11 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index;done=false>>]
[junit4] 2> 26237 T20 C11 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index
[junit4] 2> ASYNC NEW_CORE C12 name=collection1 org.apache.solr.core.SolrCore@6fe1dd25
[junit4] 2> 26264 T20 C12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data;done=false>>]
[junit4] 2> 26266 T20 C12 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data
[junit4] 2> ASYNC NEW_CORE C13 name=collection1 org.apache.solr.core.SolrCore@41304b17
[junit4] 2> 26448 T47 C13 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&distrib=false&sort=_docid_+asc&rows=0&wt=javabin&version=2} hits=10 status=0 QTime=164
[junit4] 2> 31097 T12 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=943255342
[junit4] 2> 31098 T12 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@41304b17
[junit4] 2> 31099 T12 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 31103 T12 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 31104 T12 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 31105 T12 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 31119 T12 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 31121 T12 oasc.CachingDirectoryFactory.close Closing MockFSDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 31122 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data;done=false>>]
[junit4] 2> 31122 T12 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data
[junit4] 2> 31124 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index;done=false>>]
[junit4] 2> 31125 T12 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index
[junit4] 2> 31164 T12 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 36106 T12 oas.SolrTestCaseJ4.tearDown ###Ending testReliability
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLBHttpSolrServer -Dtests.method=testReliability -Dtests.seed=D5A2C9A1ECE0E2 -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=Europe/Kiev -Dtests.file.encoding=UTF-8
[junit4] ERROR 32.1s | TestLBHttpSolrServer.testReliability <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request
[junit4] > at __randomizedtesting.SeedInfo.seed([D5A2C9A1ECE0E2:C11D7F8F008A314B]:0)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:525)
[junit4] > at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
[junit4] > at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
[junit4] > at org.apache.solr.client.solrj.TestLBHttpSolrServer.testReliability(TestLBHttpSolrServer.java:222)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:53758/solr
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:561)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:478)
[junit4] > ... 43 more
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:150)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:121)
[junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
[junit4] > at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
[junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
[junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
[junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
[junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:449)
[junit4] > ... 46 more
[junit4] 2> 36358 T12 oas.SolrTestCaseJ4.setUp ###Starting testSimple
[junit4] 2> 36385 T12 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 36397 T12 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53806
[junit4] 2> 36401 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 36402 T12 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 36402 T12 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001
[junit4] 2> 36402 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/'
[junit4] 2> 36485 T12 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/solr.xml
[junit4] 2> 36637 T12 oasc.CoreContainer.<init> New CoreContainer 900691983
[junit4] 2> 36637 T12 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/]
[junit4] 2> 36644 T12 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 36645 T12 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 36645 T12 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 36646 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 36646 T12 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 36647 T12 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 36647 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 36648 T12 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 36648 T12 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 36650 T12 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 36651 T12 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 36651 T12 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 36662 T61 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/'
[junit4] 2> 36752 T61 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 36825 T61 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 36826 T61 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 36841 T61 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 36871 T61 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 36876 T61 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/
[junit4] 2> 36877 T61 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockFSDirectoryFactory
[junit4] 2> 36877 T61 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/
[junit4] 2> 36878 T61 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 36879 T61 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data
[junit4] 2> 36881 T61 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index/
[junit4] 2> 36881 T61 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 36882 T61 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index
[junit4] 2> 36892 T61 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 36894 T61 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 36900 T61 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 36901 T61 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 36901 T61 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 36901 T61 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 36902 T61 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 36902 T61 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 36903 T61 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 36915 T61 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 36917 T61 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 36917 T61 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 36924 T61 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 36924 T61 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 36925 T61 oass.SolrIndexSearcher.<init> Opening Searcher@4685bebf[collection1] main
[junit4] 2> 36925 T61 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf
[junit4] 2> 36929 T61 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf}
[junit4] 2> 36930 T61 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf
[junit4] 2> 36931 T61 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 36931 T61 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf
[junit4] 2> 36933 T61 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 36933 T61 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 36934 T61 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 36934 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4685bebf[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 36938 T12 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
[junit4] 2> 36939 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C14 name=collection1 org.apache.solr.core.SolrCore@879825c
[junit4] 2> 36966 T57 C14 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 9
[junit4] 2> 36975 T58 C14 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 37119 T58 C14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index),segFN=segments_2,generation=2}
[junit4] 2> 37136 T58 C14 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 37164 T58 C14 oass.SolrIndexSearcher.<init> Opening Searcher@526a3808[collection1] main
[junit4] 2> 37167 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@526a3808[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C10))}
[junit4] 2> 37168 T58 C14 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 37169 T58 C14 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 193
[junit4] 2> 37218 T12 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 37233 T12 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53810
[junit4] 2> 37240 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 37241 T12 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 37243 T12 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001
[junit4] 2> 37243 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/'
[junit4] 2> 37387 T12 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/solr.xml
[junit4] 2> 37546 T12 oasc.CoreContainer.<init> New CoreContainer 1952848334
[junit4] 2> 37547 T12 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/]
[junit4] 2> 37555 T12 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 37556 T12 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 37556 T12 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 37557 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 37557 T12 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 37559 T12 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 37559 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 37561 T12 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 37561 T12 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 37562 T12 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 37563 T12 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 37564 T12 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 37582 T73 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/'
[junit4] 2> 37728 T73 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 37846 T73 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 37848 T73 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 37876 T73 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 37936 T73 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 37951 T73 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/
[junit4] 2> 37951 T73 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockFSDirectoryFactory
[junit4] 2> 37952 T73 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/
[junit4] 2> 37953 T73 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 37954 T73 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data
[junit4] 2> 37955 T73 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index/
[junit4] 2> 37957 T73 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 37958 T73 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index
[junit4] 2> 37970 T73 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 37971 T73 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 37976 T73 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 37977 T73 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 37981 T73 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 37982 T73 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 37982 T73 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 37983 T73 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 37984 T73 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 37999 T73 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 38002 T73 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 38003 T73 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 38008 T73 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> 38009 T73 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 38009 T73 oass.SolrIndexSearcher.<init> Opening Searcher@170af05b[collection1] main
[junit4] 2> 38011 T73 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/conf
[junit4] 2> 38016 T73 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/conf}
[junit4] 2> 38017 T73 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/conf
[junit4] 2> 38018 T73 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 38019 T73 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/conf
[junit4] 2> 38019 T73 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 38019 T73 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 38020 T73 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 38020 T74 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@170af05b[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 38022 T12 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
[junit4] 2> 38022 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C15 name=collection1 org.apache.solr.core.SolrCore@2efd927d
[junit4] 2> 38038 T68 C15 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 7
[junit4] 2> 38048 T70 C15 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 38146 T70 C15 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-1-001/collection1/data/index),segFN=segments_2,generation=2}
[junit4] 2> 38148 T70 C15 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 38169 T70 C15 oass.SolrIndexSearcher.<init> Opening Searcher@4cd7c858[collection1] main
[junit4] 2> 38171 T70 C15 oasu.Direc
[...truncated too long message...]
@4873c107[collection1] main
[junit4] 2> 73823 T147 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf
[junit4] 2> 73823 T147 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf}
[junit4] 2> 73823 T147 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf
[junit4] 2> 73825 T147 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/conf
[junit4] 2> 73825 T147 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 73826 T147 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 73826 T147 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 73827 T147 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 73827 T148 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4873c107[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C10))}
[junit4] 2> 73829 T12 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
[junit4] 2> 73829 T12 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C21 name=collection1 org.apache.solr.core.SolrCore@38c57be2
[junit4] 2> 74282 T144 C21 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&distrib=false&sort=_docid_+asc&rows=0&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 2> 75036 T140 C21 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 75054 T12 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1964399507
[junit4] 2> 75057 T12 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@38c57be2
[junit4] 2> 75057 T12 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 75058 T12 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 75058 T12 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 75059 T12 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 75074 T12 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 75079 T12 oasc.CachingDirectoryFactory.close Closing MockFSDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 75079 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index;done=false>>]
[junit4] 2> 75080 T12 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data/index
[junit4] 2> 75094 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data;done=false>>]
[junit4] 2> 75095 T12 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-0-001/collection1/data
[junit4] 2> 75096 T12 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 80089 T12 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=192484066
[junit4] 2> 80091 T12 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2eda5462
[junit4] 2> 80091 T12 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 80094 T12 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 80094 T12 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 80095 T12 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 80108 T12 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 80111 T12 oasc.CachingDirectoryFactory.close Closing MockFSDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 80112 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data;done=false>>]
[junit4] 2> 80112 T12 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data
[junit4] 2> 80114 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index;done=false>>]
[junit4] 2> 80114 T12 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001/instance-2-001/collection1/data/index
[junit4] 2> 80140 T12 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 85097 T12 oas.SolrTestCaseJ4.tearDown ###Ending testTwoServers
[junit4] 2> 85102 T12 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.TestLBHttpSolrServer-D5A2C9A1ECE0E2-001
[junit4] 2> NOTE: test params are: codec=Lucene46, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=de, timezone=Europe/Kiev
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=4,free=4135384,total=41811968
[junit4] 2> NOTE: All tests run in this JVM: [TestLBHttpSolrServer]
[junit4] Completed in 86.21s, 3 tests, 1 error <<< FAILURES!
[...truncated 159 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:202: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1298: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:922: There were test failures: 51 suites, 297 tests, 1 error
Total time: 152 minutes 14 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any