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/02/17 09:03:22 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 1338
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1338/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testDistribSearch
Error Message:
Timeout occured while waiting response from server at: https://127.0.0.1:56444/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:56444/collection1
at __randomizedtesting.SeedInfo.seed([DE00B232B0725299:5FE63C2AC72D32A5]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:503)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:199)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.index_specific(AbstractFullDistribZkTestBase.java:698)
at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingWithSuss(FullSolrCloudDistribCmdsTest.java:443)
at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.doTest(FullSolrCloudDistribCmdsTest.java:134)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:870)
at sun.reflect.GeneratedMethodAccessor44.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:744)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:152)
at java.net.SocketInputStream.read(SocketInputStream.java:122)
at sun.security.ssl.InputRecord.readFully(InputRecord.java:442)
at sun.security.ssl.InputRecord.read(InputRecord.java:480)
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:927)
at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:884)
at sun.security.ssl.AppInputStream.read(AppInputStream.java:102)
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.request(HttpSolrServer.java:395)
... 47 more
Build Log:
[...truncated 10551 lines...]
[junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest
[junit4] 2> 2424307 T7875 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 2424307 T7875 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 2424311 T7875 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-FullSolrCloudDistribCmdsTest-1392622839854
[junit4] 2> 2424313 T7875 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 2424314 T7876 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2424415 T7875 oasc.ZkTestServer.run start zk server on port:56440
[junit4] 2> 2424417 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2424425 T7882 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53adc0ab name:ZooKeeperConnection Watcher:127.0.0.1:56440 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2424426 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2424426 T7875 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2424438 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2424444 T7884 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1623dbe3 name:ZooKeeperConnection Watcher:127.0.0.1:56440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2424444 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2424445 T7875 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2424455 T7875 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2424465 T7875 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2424473 T7875 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2424484 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2424485 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2424499 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 2424500 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2424509 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2424510 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2424520 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2424521 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2424530 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2424531 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2424543 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2424544 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2424553 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2424554 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2424564 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2424566 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2424575 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2424576 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2424586 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2424587 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2424596 T7875 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2424597 T7875 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2424610 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2424615 T7886 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a446165 name:ZooKeeperConnection Watcher:127.0.0.1:56440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2424616 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2425017 T7875 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2425026 T7875 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2425032 T7875 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56444
[junit4] 2> 2425034 T7875 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2425035 T7875 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2425035 T7875 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1392622840164
[junit4] 2> 2425035 T7875 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1392622840164/'
[junit4] 2> 2425068 T7875 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1392622840164/solr.xml
[junit4] 2> 2425171 T7875 oasc.CoreContainer.<init> New CoreContainer 241043884
[junit4] 2> 2425172 T7875 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1392622840164/]
[junit4] 2> 2425174 T7875 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2425174 T7875 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2425174 T7875 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2425175 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2425175 T7875 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2425175 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2425176 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2425176 T7875 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2425176 T7875 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2425192 T7875 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2425193 T7875 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2425193 T7875 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2425194 T7875 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56440/solr
[junit4] 2> 2425194 T7875 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2425195 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2425201 T7897 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@163e6714 name:ZooKeeperConnection Watcher:127.0.0.1:56440 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2425201 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2425207 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2425212 T7899 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3f87b47a name:ZooKeeperConnection Watcher:127.0.0.1:56440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2425212 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2425217 T7875 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2425227 T7875 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2425241 T7875 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2425246 T7875 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56444_
[junit4] 2> 2425249 T7875 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56444_
[junit4] 2> 2425260 T7875 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 2425267 T7875 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2425281 T7875 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:56444_
[junit4] 2> 2425282 T7875 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2425289 T7875 oasc.Overseer.start Overseer (id=91266930433130500-127.0.0.1:56444_-n_0000000000) starting
[junit4] 2> 2425299 T7875 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2425311 T7901 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2425313 T7875 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2425314 T7901 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
[junit4] 2> 2425320 T7875 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2425326 T7875 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2425337 T7900 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2425348 T7902 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2425349 T7902 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2425352 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2425352 T7902 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2425357 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2425359 T7900 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:56444",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56444_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2425360 T7900 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 2425360 T7900 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2425368 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2425371 T7899 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> 2426354 T7902 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2426354 T7902 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1392622840164/collection1
[junit4] 2> 2426354 T7902 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 2426356 T7902 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2426356 T7902 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 2426360 T7902 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2426360 T7902 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1392622840164/collection1/'
[junit4] 2> 2426361 T7902 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1392622840164/collection1/lib/classes/' to classloader
[junit4] 2> 2426361 T7902 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1392622840164/collection1/lib/README' to classloader
[junit4] 2> 2426425 T7902 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2426500 T7902 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2426504 T7902 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2426512 T7902 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2427147 T7902 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2427150 T7902 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2427152 T7902 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2427159 T7902 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2427234 T7902 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2427235 T7902 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1392622840164/collection1/, dataDir=./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/control/data/
[junit4] 2> 2427235 T7902 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b3c9508
[junit4] 2> 2427236 T7902 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/control/data
[junit4] 2> 2427236 T7902 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/control/data/index/
[junit4] 2> 2427236 T7902 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 2427237 T7902 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/control/data/index
[junit4] 2> 2427237 T7902 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=6.4267578125, floorSegmentMB=2.1787109375, forceMergeDeletesPctAllowed=23.74805104221557, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5113875401813078
[junit4] 2> 2427238 T7902 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@18e20fd8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6079cec6),segFN=segments_1,generation=1}
[junit4] 2> 2427238 T7902 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2427242 T7902 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2427243 T7902 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2427244 T7902 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2427244 T7902 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2427244 T7902 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2427245 T7902 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2427246 T7902 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2427246 T7902 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2427246 T7902 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2427247 T7902 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2427247 T7902 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2427248 T7902 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2427248 T7902 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2427248 T7902 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2427249 T7902 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2427249 T7902 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2427250 T7902 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2427261 T7902 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2427269 T7902 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2427269 T7902 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2427270 T7902 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=5.56640625, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=21.36760106176001, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5879779165427231
[junit4] 2> 2427271 T7902 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@18e20fd8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6079cec6),segFN=segments_1,generation=1}
[junit4] 2> 2427271 T7902 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2427271 T7902 oass.SolrIndexSearcher.<init> Opening Searcher@74831e38[collection1] main
[junit4] 2> 2427275 T7903 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@74831e38[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2427277 T7902 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2427280 T7875 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2427280 T7875 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2427280 T7906 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56444 collection:control_collection shard:shard1
[junit4] 2> 2427284 T7906 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2427292 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2427300 T7908 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@146ad7a9 name:ZooKeeperConnection Watcher:127.0.0.1:56440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2427301 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2427306 T7875 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2427314 T7906 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2427315 T7875 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2427329 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2427335 T7906 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2427336 T7906 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C9619 name=collection1 org.apache.solr.core.SolrCore@179d6884 url=https://127.0.0.1:56444/collection1 node=127.0.0.1:56444_ C9619_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:56444, core=collection1, node_name=127.0.0.1:56444_}
[junit4] 2> 2427336 T7906 C9619 P56444 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56444/collection1/
[junit4] 2> 2427337 T7906 C9619 P56444 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2427337 T7906 C9619 P56444 oasc.SyncStrategy.syncToMe https://127.0.0.1:56444/collection1/ has no replicas
[junit4] 2> 2427338 T7906 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56444/collection1/ shard1
[junit4] 2> 2427340 T7906 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 2427346 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2427363 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2427369 T7899 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> 2427370 T7908 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> 2427393 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2427404 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2427512 T7908 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> 2427513 T7899 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> 2427539 T7906 oasc.ZkController.register We are https://127.0.0.1:56444/collection1/ and leader is https://127.0.0.1:56444/collection1/
[junit4] 2> 2427540 T7906 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56444
[junit4] 2> 2427540 T7906 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2427540 T7906 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2427541 T7906 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2427545 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2427545 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2427545 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2427548 T7906 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2427553 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2427555 T7900 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:56444",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56444_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2427571 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2427676 T7899 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> 2427684 T7908 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> 2427811 T7875 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 2427811 T7875 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2427819 T7875 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2427821 T7875 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56448
[junit4] 2> 2427826 T7875 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2427827 T7875 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2427827 T7875 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty1-1392622842858
[junit4] 2> 2427828 T7875 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty1-1392622842858/'
[junit4] 2> 2427874 T7875 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/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty1-1392622842858/solr.xml
[junit4] 2> 2427979 T7875 oasc.CoreContainer.<init> New CoreContainer 53928260
[junit4] 2> 2427980 T7875 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty1-1392622842858/]
[junit4] 2> 2427982 T7875 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2427982 T7875 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2427982 T7875 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2427982 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2427983 T7875 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2427983 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2427983 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2427984 T7875 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2427984 T7875 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2428001 T7875 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2428003 T7875 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2428003 T7875 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2428004 T7875 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56440/solr
[junit4] 2> 2428004 T7875 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2428006 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2428011 T7919 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ca067d2 name:ZooKeeperConnection Watcher:127.0.0.1:56440 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2428012 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2428019 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2428025 T7921 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e39c213 name:ZooKeeperConnection Watcher:127.0.0.1:56440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2428025 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2428041 T7875 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2429055 T7875 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56448_
[junit4] 2> 2429058 T7875 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56448_
[junit4] 2> 2429066 T7921 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2429066 T7899 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2429067 T7908 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2429085 T7922 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2429085 T7922 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2429089 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2429090 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2429090 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2429090 T7922 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2429096 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2429098 T7900 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:56448",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56448_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2429098 T7900 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3]
[junit4] 2> 2429098 T7900 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
[junit4] 2> 2429107 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2429213 T7921 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> 2429214 T7899 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> 2429214 T7908 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> 2430094 T7922 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2430094 T7922 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty1-1392622842858/collection1
[junit4] 2> 2430095 T7922 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2430097 T7922 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2430097 T7922 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2430101 T7922 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2430101 T7922 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty1-1392622842858/collection1/'
[junit4] 2> 2430102 T7922 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty1-1392622842858/collection1/lib/classes/' to classloader
[junit4] 2> 2430103 T7922 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty1-1392622842858/collection1/lib/README' to classloader
[junit4] 2> 2430175 T7922 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2430255 T7922 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2430259 T7922 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2430268 T7922 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2430808 T7922 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2430811 T7922 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2430813 T7922 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2430820 T7922 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2430885 T7922 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2430885 T7922 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty1-1392622842858/collection1/, dataDir=./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty1/
[junit4] 2> 2430886 T7922 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b3c9508
[junit4] 2> 2430886 T7922 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty1
[junit4] 2> 2430887 T7922 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty1/index/
[junit4] 2> 2430887 T7922 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 2430887 T7922 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty1/index
[junit4] 2> 2430888 T7922 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=6.4267578125, floorSegmentMB=2.1787109375, forceMergeDeletesPctAllowed=23.74805104221557, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5113875401813078
[junit4] 2> 2430889 T7922 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6bfeb911 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c034e38),segFN=segments_1,generation=1}
[junit4] 2> 2430889 T7922 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2430895 T7922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2430895 T7922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2430896 T7922 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2430896 T7922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2430896 T7922 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2430896 T7922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2430897 T7922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2430897 T7922 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2430897 T7922 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2430898 T7922 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2430899 T7922 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2430899 T7922 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2430899 T7922 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2430900 T7922 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2430900 T7922 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2430901 T7922 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2430902 T7922 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2430912 T7922 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2430918 T7922 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2430919 T7922 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2430920 T7922 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=5.56640625, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=21.36760106176001, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5879779165427231
[junit4] 2> 2430920 T7922 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6bfeb911 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c034e38),segFN=segments_1,generation=1}
[junit4] 2> 2430920 T7922 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2430921 T7922 oass.SolrIndexSearcher.<init> Opening Searcher@3d9328f0[collection1] main
[junit4] 2> 2430930 T7923 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3d9328f0[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2430933 T7922 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2430935 T7875 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2430935 T7875 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2430935 T7926 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56448 collection:collection1 shard:shard3
[junit4] 2> 2430938 T7926 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4] 2> 2430951 T7926 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3
[junit4] 2> 2430956 T7926 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2430957 T7926 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C9620 name=collection1 org.apache.solr.core.SolrCore@4e5225a1 url=https://127.0.0.1:56448/collection1 node=127.0.0.1:56448_ C9620_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:56448, core=collection1, node_name=127.0.0.1:56448_}
[junit4] 2> 2430957 T7926 C9620 P56448 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56448/collection1/
[junit4] 2> 2430957 T7926 C9620 P56448 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2430958 T7926 C9620 P56448 oasc.SyncStrategy.syncToMe https://127.0.0.1:56448/collection1/ has no replicas
[junit4] 2> 2430958 T7926 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56448/collection1/ shard3
[junit4] 2> 2430958 T7926 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4] 2> 2430958 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2430958 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2430959 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2430970 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2430980 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2430986 T7921 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> 2430986 T7899 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> 2430993 T7908 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> 2430997 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2431004 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2431110 T7921 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> 2431111 T7908 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> 2431111 T7899 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> 2431136 T7926 oasc.ZkController.register We are https://127.0.0.1:56448/collection1/ and leader is https://127.0.0.1:56448/collection1/
[junit4] 2> 2431137 T7926 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56448
[junit4] 2> 2431138 T7926 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2431138 T7926 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2431138 T7926 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2431140 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2431141 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2431141 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2431143 T7926 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2431147 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2431149 T7900 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:56448",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56448_",
[junit4] 2> "shard":"shard3",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2431155 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2431261 T7908 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> 2431262 T7921 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> 2431262 T7899 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> 2431332 T7875 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 2431333 T7875 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2431340 T7875 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2431343 T7875 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56451
[junit4] 2> 2431346 T7875 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2431346 T7875 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2431347 T7875 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty2-1392622846480
[junit4] 2> 2431347 T7875 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty2-1392622846480/'
[junit4] 2> 2431378 T7875 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/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty2-1392622846480/solr.xml
[junit4] 2> 2431474 T7875 oasc.CoreContainer.<init> New CoreContainer 2007640156
[junit4] 2> 2431474 T7875 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty2-1392622846480/]
[junit4] 2> 2431476 T7875 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2431477 T7875 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2431477 T7875 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2431477 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2431477 T7875 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2431478 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2431478 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2431479 T7875 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2431479 T7875 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2431493 T7875 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2431494 T7875 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2431494 T7875 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2431494 T7875 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56440/solr
[junit4] 2> 2431495 T7875 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2431496 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2431501 T7937 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@509f22db name:ZooKeeperConnection Watcher:127.0.0.1:56440 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2431501 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2431507 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2431511 T7939 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@cc076b5 name:ZooKeeperConnection Watcher:127.0.0.1:56440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2431511 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2431525 T7875 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2432538 T7875 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56451_
[junit4] 2> 2432542 T7875 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56451_
[junit4] 2> 2432550 T7921 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2432550 T7939 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2432550 T7899 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2432550 T7908 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2432569 T7940 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2432570 T7940 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2432573 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2432573 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2432574 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2432573 T7940 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2432578 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2432580 T7900 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:56451",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56451_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2432581 T7900 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
[junit4] 2> 2432581 T7900 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 2432589 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2432696 T7908 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> 2432696 T7921 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> 2432696 T7899 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> 2432697 T7939 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> 2433576 T7940 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2433576 T7940 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty2-1392622846480/collection1
[junit4] 2> 2433576 T7940 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2433578 T7940 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2433579 T7940 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2433581 T7940 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2433581 T7940 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty2-1392622846480/collection1/'
[junit4] 2> 2433583 T7940 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty2-1392622846480/collection1/lib/classes/' to classloader
[junit4] 2> 2433583 T7940 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty2-1392622846480/collection1/lib/README' to classloader
[junit4] 2> 2433645 T7940 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2433712 T7940 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2433716 T7940 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2433725 T7940 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2434309 T7940 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2434314 T7940 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2434316 T7940 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2434323 T7940 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2434391 T7940 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2434391 T7940 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty2-1392622846480/collection1/, dataDir=./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty2/
[junit4] 2> 2434391 T7940 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b3c9508
[junit4] 2> 2434392 T7940 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty2
[junit4] 2> 2434393 T7940 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty2/index/
[junit4] 2> 2434393 T7940 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 2434394 T7940 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-1392622839854/jetty2/index
[junit4] 2> 2434395 T7940 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=6.4267578125, floorSegmentMB=2.1787109375, forceMergeDeletesPctAllowed=23.74805104221557, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5113875401813078
[junit4] 2> 2434396 T7940 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@e062adf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5809eaef),segFN=segments_1,generation=1}
[junit4] 2> 2434396 T7940 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2434401 T7940 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2434402 T7940 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2434402 T7940 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2434402 T7940 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2434402 T7940 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2434403 T7940 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2434403 T7940 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2434404 T7940 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2434404 T7940 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2434405 T7940 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2434405 T7940 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2434405 T7940 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2434406 T7940 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2434406 T7940 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2434407 T7940 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2434407 T7940 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2434408 T7940 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2434420 T7940 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2434426 T7940 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2434426 T7940 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2434427 T7940 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=5.56640625, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=21.36760106176001, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5879779165427231
[junit4] 2> 2434427 T7940 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@e062adf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5809eaef),segFN=segments_1,generation=1}
[junit4] 2> 2434428 T7940 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2434428 T7940 oass.SolrIndexSearcher.<init> Opening Searcher@2455eed8[collection1] main
[junit4] 2> 2434433 T7941 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2455eed8[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2434436 T7940 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2434439 T7875 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2434439 T7875 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2434439 T7944 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56451 collection:collection1 shard:shard2
[junit4] 2> 2434443 T7944 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 2434459 T7944 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 2434465 T7944 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2434465 T7944 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C9621 name=collection1 org.apache.solr.core.SolrCore@15cbfa67 url=https://127.0.0.1:56451/collection1 node=127.0.0.1:56451_ C9621_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:56451, core=collection1, node_name=127.0.0.1:56451_}
[junit4] 2> 2434466 T7944 C9621 P56451 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56451/collection1/
[junit4] 2> 2434466 T7944 C9621 P56451 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2434466 T7944 C9621 P56451 oasc.SyncStrategy.syncToMe https://127.0.0.1:56451/collection1/ has no replicas
[junit4] 2> 2434466 T7944 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56451/collection1/ shard2
[junit4] 2> 2434467 T7944 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 2434472 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2434472 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2434473 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2434480 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2434487 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2434495 T7921 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> 2434495 T7899 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> 2434498 T7939 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> 2434502 T7908 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> 2434508 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2434517 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2434627 T7921 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> 2434628 T7908 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> 2434628 T7899 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> 2434634 T7939 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> 2434645 T7944 oasc.ZkController.register We are https://127.0.0.1:56451/collection1/ and leader is https://127.0.0.1:56451/collection1/
[junit4] 2> 2434645 T7944 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56451
[junit4] 2> 2434645 T7944 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2434646 T7944 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2434646 T7944 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2434653 T7944 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2434655 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2434655 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2434655 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2434660 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2434662 T7900 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:56451",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56451_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 2434667 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2434774 T7908 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> 2434774 T7899 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> 2434774 T7921 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> 2434774 T7939 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> 2434821 T7875 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 2434822 T7875 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2434828 T7875 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2434829 T7875 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56454
[junit4] 2> 2434834 T7875 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2434834 T7875 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2434834 T7875 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty3-1392622849986
[junit4] 2> 2434835 T7875 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty3-1392622849986/'
[junit4] 2> 2434864 T7875 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/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty3-1392622849986/solr.xml
[junit4] 2> 2434940 T7875 oasc.CoreContainer.<init> New CoreContainer 754820717
[junit4] 2> 2434940 T7875 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-jetty3-1392622849986/]
[junit4] 2> 2434942 T7875 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2434942 T7875 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2434943 T7875 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2434943 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2434943 T7875 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2434943 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2434944 T7875 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2434944 T7875 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2434944 T7875 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2434958 T7875 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2434959 T7875 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2434959 T7875 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2434959 T7875 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56440/solr
[junit4] 2> 2434961 T7875 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2434962 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2434967 T7955 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@119ba85e name:ZooKeeperConnection Watcher:127.0.0.1:56440 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2434967 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2434973 T7875 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2434990 T7957 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2d66723e name:ZooKeeperConnection Watcher:127.0.0.1:56440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2434990 T7875 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2435005 T7875 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2436015 T7875 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56454_
[junit4] 2> 2436018 T7875 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56454_
[junit4] 2> 2436027 T7939 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2436027 T7957 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2436029 T7921 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2436029 T7908 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2436030 T7899 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2436053 T7958 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2436053 T7958 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2436056 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2436056 T7958 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2436056 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2436057 T7899 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2436063 T7900 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2436065 T7900 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:56454",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:
[...truncated too long message...]
ingsFormat(name=MockSep), 11grand5_s=MockFixedIntBlock(blockSize=973), 7child3_s=PostingsFormat(name=MockSep), 6child1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 13grand11_s=PostingsFormat(name=FSTOrdPulsing41), 7grand4_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 11grand1_s=MockFixedIntBlock(blockSize=973), 5grand4_s=MockFixedIntBlock(blockSize=973), 7child1_s=PostingsFormat(name=FSTOrdPulsing41), 11grand2_s=PostingsFormat(name=MockSep), 5child0_s=MockFixedIntBlock(blockSize=973), 11grand3_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 6grand3_s=MockFixedIntBlock(blockSize=973), 4child1_s=MockFixedIntBlock(blockSize=973), 9grand6_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), type_s=PostingsFormat(name=FSTOrdPulsing41), 7parent_f2_s=PostingsFormat(name=FSTOrdPulsing41), 4child2_s=PostingsFormat(name=FSTOrdPulsing41), 7child5_s=PostingsFormat(name=FSTOrdPulsing41), 6child2_s=PostingsFormat(name=MockSep), 6child5_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 2grand0_s=PostingsFormat(name=FSTOrdPulsing41), 9grand0_s=MockFixedIntBlock(blockSize=973), 8parent_f2_s=MockFixedIntBlock(blockSize=973), id=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 11grand8_s=PostingsFormat(name=FSTOrdPulsing41), 3parent_f2_s=PostingsFormat(name=FSTOrdPulsing41), 3grand2_s=MockFixedIntBlock(blockSize=973), 9grand5_s=PostingsFormat(name=MockSep), 7parent_f1_s=MockFixedIntBlock(blockSize=973), 9grand4_s=MockFixedIntBlock(blockSize=973), 10grand7_s=PostingsFormat(name=FSTOrdPulsing41), 11grand0_s=PostingsFormat(name=FSTOrdPulsing41), 13grand6_s=PostingsFormat(name=FSTOrdPulsing41), 6grand2_s=PostingsFormat(name=FSTOrdPulsing41), 10grand2_s=MockFixedIntBlock(blockSize=973), 3parent_f1_s=MockFixedIntBlock(blockSize=973), 9grand2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 5grand2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 9grand3_s=PostingsFormat(name=FSTOrdPulsing41), 5child4_s=MockFixedIntBlock(blockSize=973), 10grand5_s=PostingsFormat(name=MockSep), 11grand6_s=PostingsFormat(name=MockSep), 10grand0_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 13grand10_s=MockFixedIntBlock(blockSize=973), 9grand1_s=PostingsFormat(name=MockSep), 13grand0_s=PostingsFormat(name=MockSep), 4grand1_s=MockFixedIntBlock(blockSize=973), 7grand1_s=PostingsFormat(name=MockSep), 7child0_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 10grand3_s=PostingsFormat(name=FSTOrdPulsing41), 1parent_f2_s=PostingsFormat(name=MockSep), 7grand6_s=MockFixedIntBlock(blockSize=973), 10grand6_s=MockFixedIntBlock(blockSize=973), 9grand8_s=MockFixedIntBlock(blockSize=973), 11grand7_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 8grand4_s=PostingsFormat(name=FSTOrdPulsing41), 6grand0_s=PostingsFormat(name=MockSep), 3child0_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 6parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 9parent_f2_s=PostingsFormat(name=MockSep), 12grand2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 0parent_f2_s=MockFixedIntBlock(blockSize=973), 6grand5_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 5grand3_s=PostingsFormat(name=MockSep), 5child3_s=PostingsFormat(name=FSTOrdPulsing41), 7grand5_s=PostingsFormat(name=MockSep), 7grand0_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 2parent_f1_s=PostingsFormat(name=FSTOrdPulsing41)}, docValues:{}, sim=DefaultSimilarity, locale=ar_AE, timezone=Europe/London
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_51 (64-bit)/cpus=2,threads=1,free=52457648,total=307425280
[junit4] 2> NOTE: All tests run in this JVM: [FieldFacetExtrasTest, ClusterStateUpdateTest, TestPHPSerializedResponseWriter, TestPseudoReturnFields, ChaosMonkeySafeLeaderTest, NoCacheHeaderTest, TestSimpleQParserPlugin, ShardSplitTest, TestModifyConfFiles, TestConfig, QueryEqualityTest, QueryElevationComponentTest, DistributedSpellCheckComponentTest, DocValuesTest, SolrCoreTest, TestUniqueKeyFieldResource, SolrPluginUtilsTest, ScriptEngineTest, TestStressVersions, CoreMergeIndexesAdminHandlerTest, HdfsDirectoryTest, TestImplicitCoreProperties, RequiredFieldsTest, TestQuerySenderNoQuery, TestDefaultSearchFieldResource, TestSolrDeletionPolicy1, TestDistributedMissingSort, ResponseLogComponentTest, LegacyHTMLStripCharFilterTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestQueryUtils, StandardRequestHandlerTest, OverseerCollectionProcessorTest, TestSuggestSpellingConverter, SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest, TestCursorMarkWithoutUniqueKey, DistributedTermsComponentTest, TestSystemIdResolver, SimpleFacetsTest, TestHashPartitioner, JsonLoaderTest, TestCodecSupport, DistributedQueryComponentCustomSortTest, MBeansHandlerTest, TestNumberUtils, CacheHeaderTest, CurrencyFieldOpenExchangeTest, SolrCoreCheckLockOnStartupTest, ShardRoutingCustomTest, PreAnalyzedFieldTest, TermVectorComponentTest, ExternalFileFieldSortTest, SliceStateTest, TestSearchPerf, SolrRequestParserTest, URLClassifyProcessorTest, SampleTest, CurrencyFieldXmlFileTest, CopyFieldTest, TestMaxScoreQueryParser, AssignTest, TestCloudManagedSchema, RangeFacetTest, TestElisionMultitermQuery, TestBinaryResponseWriter, ChaosMonkeyNothingIsSafeTest, TestReplicationHandler, FieldMutatingUpdateProcessorTest, TestUtils, ParsingFieldUpdateProcessorsTest, DirectUpdateHandlerTest, TestFieldCollectionResource, TestReload, BJQParserTest, ClusterStateTest, LukeRequestHandlerTest, DistributedQueryElevationComponentTest, TermsComponentTest, TestManagedSchemaFieldResource, SpellingQueryConverterTest, TestFuzzyAnalyzedSuggestions, TestStressUserVersions, TestPerFieldSimilarity, TestCharFilters, TestInitQParser, TestStressReorder, MultiTermTest, TestSweetSpotSimilarityFactory, StatelessScriptUpdateProcessorFactoryTest, TestJoin, TestNRTOpen, TestSolrXml, HdfsBasicDistributedZk2Test, TestMultiCoreConfBootstrap, SpellCheckCollatorTest, TestDistributedSearch, CursorMarkTest, TestNonNRTOpen, BadIndexSchemaTest, XsltUpdateRequestHandlerTest, ReturnFieldsTest, ConvertedLegacyTest, ZkCLITest, DirectSolrConnectionTest, TestWriterPerf, AbstractAnalyticsFacetTest, TestReloadAndDeleteDocs, TestBadConfig, EnumFieldTest, TestExtendedDismaxParser, JSONWriterTest, TestDocBasedVersionConstraints, OverseerTest, SolrXmlInZkTest, TestLMDirichletSimilarityFactory, TestStressRecovery, RemoteQueryErrorTest, ZkControllerTest, TestTrie, DistributedSuggestComponentTest, DistributedDebugComponentTest, NumericFieldsTest, TestFiltering, BasicDistributedZk2Test, UniqFieldsUpdateProcessorFactoryTest, TestRTGBase, DirectSolrSpellCheckerTest, TestArbitraryIndexDir, CollectionsAPIDistributedZkTest, TestMergePolicyConfig, ShardRoutingTest, PluginInfoTest, AliasIntegrationTest, UpdateRequestProcessorFactoryTest, TestFieldTypeResource, TestFreeTextSuggestions, HdfsSyncSliceTest, SolrIndexConfigTest, TestIndexingPerformance, TestRangeQuery, TestLMJelinekMercerSimilarityFactory, TestQuerySenderListener, SuggesterTSTTest, TestJmxIntegration, TestHighFrequencyDictionaryFactory, TestCoreDiscovery, SOLR749Test, TestRemoteStreaming, IndexSchemaTest, TestCopyFieldCollectionResource, DebugComponentTest, BasicZkTest, TestRandomMergePolicy, TestSolrQueryParser, TestDistribDocBasedVersion, SoftAutoCommitTest, AddBlockUpdateTest, TestFunctionQuery, ResourceLoaderTest, TestRecoveryHdfs, ChangedSchemaMergeTest, RequestHandlersTest, SpellPossibilityIteratorTest, ExpressionTest, TestLazyCores, TestSolrDeletionPolicy2, MoreLikeThisHandlerTest, QueryFacetTest, HdfsRecoveryZkTest, TestFoldingMultitermQuery, TestFieldTypeCollectionResource, TestLRUCache, IndexSchemaRuntimeFieldTest, BadComponentTest, TestPostingsSolrHighlighter, SuggesterTest, TestSolrQueryParserDefaultOperatorResource, SolrTestCaseJ4Test, AutoCommitTest, SuggesterWFSTTest, BasicDistributedZkTest, UnloadDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest]
[junit4] Completed in 98.46s, 1 test, 1 error <<< FAILURES!
[...truncated 555 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:453: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:433: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: 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:491: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1268: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:901: There were test failures: 372 suites, 1604 tests, 1 error, 40 ignored (6 assumptions)
Total time: 91 minutes 53 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 1339
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1339/
Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
6 tests failed.
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.multiplyTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='mr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">13</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='mr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">13</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([1860B9173DCA9C9C:73E1A76908C4AAF2]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:114)
at org.apache.solr.analytics.expression.ExpressionTest.multiplyTest(ExpressionTest.java:92)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.divideTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='dr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">13</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='dr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">13</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([1860B9173DCA9C9C:406D52E11908FE4A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:114)
at org.apache.solr.analytics.expression.ExpressionTest.divideTest(ExpressionTest.java:106)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.negateTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='nr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">13</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='nr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">13</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([1860B9173DCA9C9C:1068D1B299E7CC2C]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:114)
at org.apache.solr.analytics.expression.ExpressionTest.negateTest(ExpressionTest.java:132)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.powerTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='pr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">13</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='pr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">13</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([1860B9173DCA9C9C:76D20E14D5DDC24D]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:114)
at org.apache.solr.analytics.expression.ExpressionTest.powerTest(ExpressionTest.java:119)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.absoluteValueTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='avr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">13</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='avr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">13</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([1860B9173DCA9C9C:39CC9902F057C4FA]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:114)
at org.apache.solr.analytics.expression.ExpressionTest.absoluteValueTest(ExpressionTest.java:143)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.addTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='ar']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">13</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='ar']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">13</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([1860B9173DCA9C9C:EE4C14C97C059BDB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:114)
at org.apache.solr.analytics.expression.ExpressionTest.addTest(ExpressionTest.java:78)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 10865 lines...]
[junit4] Suite: org.apache.solr.analytics.expression.ExpressionTest
[junit4] 2> 3300209 T9358 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 3300211 T9358 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523
[junit4] 2> 3300212 T9358 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 3300213 T9358 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 3300213 T9358 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 3300298 T9358 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 3300363 T9358 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 3300364 T9358 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 3300372 T9358 oass.IndexSchema.readSchema [null] Schema name=schema-docValues
[junit4] 2> 3300426 T9358 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3300427 T9358 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3300428 T9358 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr
[junit4] 2> 3300428 T9358 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/'
[junit4] 2> 3300507 T9358 oasc.CoreContainer.<init> New CoreContainer 668212508
[junit4] 2> 3300507 T9358 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/]
[junit4] 2> 3300510 T9358 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 3300510 T9358 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 3300510 T9358 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 3300511 T9358 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3300511 T9358 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3300511 T9358 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3300512 T9358 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3300512 T9358 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3300513 T9358 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3300522 T9358 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3300523 T9358 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3300523 T9358 oasc.CoreContainer.load Host Name:
[junit4] 2> 3300528 T9359 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 3300529 T9359 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 3300530 T9359 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 3300530 T9359 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 3300586 T9359 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 3300642 T9359 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 3300643 T9359 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 3300659 T9359 oass.IndexSchema.readSchema [collection1] Schema name=schema-docValues
[junit4] 2> 3300703 T9359 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3300705 T9359 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3300705 T9359 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523/
[junit4] 2> 3300705 T9359 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 3300706 T9359 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523
[junit4] 2> 3300706 T9359 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523/index/
[junit4] 2> 3300707 T9359 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523/index' doesn't exist. Creating new index...
[junit4] 2> 3300707 T9359 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523/index
[junit4] 2> 3300707 T9359 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6685610967124567]
[junit4] 2> 3300708 T9359 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_1,generation=1}
[junit4] 2> 3300709 T9359 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3300710 T9359 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3300710 T9359 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3300710 T9359 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3300720 T9359 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3300724 T9359 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3300724 T9359 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3300724 T9359 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=71.76953125, floorSegmentMB=0.6484375, forceMergeDeletesPctAllowed=7.153997256380233, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 3300725 T9359 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_1,generation=1}
[junit4] 2> 3300726 T9359 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3300726 T9359 oass.SolrIndexSearcher.<init> Opening Searcher@1216b0ac[collection1] main
[junit4] 2> 3300726 T9359 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3300726 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1216b0ac[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 3300729 T9358 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> ASYNC NEW_CORE C4823 name=collection1 org.apache.solr.core.SolrCore@1cdcf35d
[junit4] 2> 3300729 T9358 C4823 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3300730 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 3300732 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10000]} 0 2
[junit4] 2> 3300735 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300739 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_2,generation=2}
[junit4] 2> 3300740 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 3300741 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@490f2646[collection1] main
[junit4] 2> 3300741 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300741 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@490f2646[collection1] main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):C1)}
[junit4] 2> 3300742 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 3300744 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10001]} 0 0
[junit4] 2> 3300746 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300748 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_3,generation=3}
[junit4] 2> 3300748 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 3300749 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@71e115ae[collection1] main
[junit4] 2> 3300750 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300750 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@71e115ae[collection1] main{StandardDirectoryReader(segments_3:6:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4] 2> 3300751 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 3300753 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10002]} 0 1
[junit4] 2> 3300754 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300756 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_4,generation=4}
[junit4] 2> 3300757 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 3300758 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@7f0e9604[collection1] main
[junit4] 2> 3300759 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300759 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7f0e9604[collection1] main{StandardDirectoryReader(segments_4:8:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4] 2> 3300760 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 3300763 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10003]} 0 1
[junit4] 2> 3300765 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300767 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_5,generation=5}
[junit4] 2> 3300767 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 3300768 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@560ed95b[collection1] main
[junit4] 2> 3300769 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300769 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@560ed95b[collection1] main{StandardDirectoryReader(segments_5:10:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4] 2> 3300770 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 3300772 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10004]} 0 1
[junit4] 2> 3300774 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300776 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_6,generation=6}
[junit4] 2> 3300776 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 3300777 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@3265976e[collection1] main
[junit4] 2> 3300778 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300778 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3265976e[collection1] main{StandardDirectoryReader(segments_6:12:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1)}
[junit4] 2> 3300779 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 3300781 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10005]} 0 1
[junit4] 2> 3300782 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300785 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_7,generation=7}
[junit4] 2> 3300786 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 3300787 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@7fe50f24[collection1] main
[junit4] 2> 3300787 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300788 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7fe50f24[collection1] main{StandardDirectoryReader(segments_7:14:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1)}
[junit4] 2> 3300788 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 3300791 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10006]} 0 1
[junit4] 2> 3300792 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300795 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_8,generation=8}
[junit4] 2> 3300795 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 3300796 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@bf0fac3[collection1] main
[junit4] 2> 3300797 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300797 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bf0fac3[collection1] main{StandardDirectoryReader(segments_8:16:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1)}
[junit4] 2> 3300798 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 3300800 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10007]} 0 0
[junit4] 2> 3300802 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300804 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_9,generation=9}
[junit4] 2> 3300805 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 3300806 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@7327df92[collection1] main
[junit4] 2> 3300807 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300807 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7327df92[collection1] main{StandardDirectoryReader(segments_9:18:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1)}
[junit4] 2> 3300808 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 3300810 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10008]} 0 1
[junit4] 2> 3300812 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10009]} 0 1
[junit4] 2> 3300813 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300816 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_a,generation=10}
[junit4] 2> 3300817 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 3300818 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@1dd7cace[collection1] main
[junit4] 2> 3300819 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300819 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1dd7cace[collection1] main{StandardDirectoryReader(segments_a:20:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C2)}
[junit4] 2> 3300819 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 3300822 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100010]} 0 1
[junit4] 2> 3300823 T9358 C4823 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300827 T9358 C4823 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_b,generation=11}
[junit4] 2> 3300827 T9358 C4823 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 3300828 T9358 C4823 oass.SolrIndexSearcher.<init> Opening Searcher@71615695[collection1] main
[junit4] 2> 3300828 T9358 C4823 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300829 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@71615695[collection1] main{StandardDirectoryReader(segments_b:22:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C2 _9(5.0):C1)}
[junit4] 2> 3300829 T9358 C4823 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> ASYNC NEW_CORE C4824 name=collection1 org.apache.solr.core.SolrCore@1cdcf35d
[junit4] 2> 3300842 T9358 C4824 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100011]} 0 11
[junit4] 2> 3300844 T9358 C4824 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300848 T9358 C4824 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_c,generation=12}
[junit4] 2> 3300848 T9358 C4824 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 3300849 T9358 C4824 oass.SolrIndexSearcher.<init> Opening Searcher@430a3348[collection1] main
[junit4] 2> 3300850 T9358 C4824 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300850 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@430a3348[collection1] main{StandardDirectoryReader(segments_c:24:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C2 _9(5.0):C1 _a(5.0):C1)}
[junit4] 2> 3300850 T9358 C4824 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 3300853 T9358 C4824 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100012]} 0 1
[junit4] 2> 3300855 T9358 C4824 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300858 T9358 C4824 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_c,generation=12}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_d,generation=13}
[junit4] 2> 3300858 T9358 C4824 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
[junit4] 2> 3300859 T9358 C4824 oass.SolrIndexSearcher.<init> Opening Searcher@e8fe1cb[collection1] main
[junit4] 2> 3300861 T9358 C4824 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300861 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e8fe1cb[collection1] main{StandardDirectoryReader(segments_d:26:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C2 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1)}
[junit4] 2> 3300861 T9358 C4824 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 3300864 T9358 C4824 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100013]} 0 1
[junit4] 2> 3300865 T9358 C4824 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300867 T9358 C4824 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_d,generation=13}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_e,generation=14}
[junit4] 2> 3300868 T9358 C4824 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
[junit4] 2> 3300869 T9358 C4824 oass.SolrIndexSearcher.<init> Opening Searcher@3fe9cfe5[collection1] main
[junit4] 2> 3300870 T9358 C4824 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300870 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3fe9cfe5[collection1] main{StandardDirectoryReader(segments_e:28:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C2 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1)}
[junit4] 2> 3300870 T9358 C4824 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 3300873 T9358 C4824 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100014]} 0 0
[junit4] 2> 3300875 T9358 C4824 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300878 T9358 C4824 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_e,generation=14}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_f,generation=15}
[junit4] 2> 3300878 T9358 C4824 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
[junit4] 2> 3300879 T9358 C4824 oass.SolrIndexSearcher.<init> Opening Searcher@81a3372[collection1] main
[junit4] 2> 3300880 T9358 C4824 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3300880 T9360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@81a3372[collection1] main{StandardDirectoryReader(segments_f:30:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C2 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1)}
[junit4] 2> 3300881 T9358 C4824 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 3300883 T9358 C4824 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100015]} 0 1
[junit4] 2> 3300884 T9358 C4824 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3300887 T9358 C4824 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_f,generation=15}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@48ad0e85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@586fbf2f),segFN=segments_g,generation=16}
[junit4] 2> 3300888 T9358 C4824 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
[junit4] 2> 3300889 T9358 C4824 oass.SolrIndexSearcher.<init> Opening Searcher@7d5b7758[collection1] main
[junit4] 2> 3300889 T9360 oasc.SolrCore.register
[...truncated too long message...]
54 T9358 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 3302754 T9358 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523;done=false>>]
[junit4] 2> 3302754 T9358 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523
[junit4] 2> 3302755 T9358 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523/index;done=false>>]
[junit4] 2> 3302755 T9358 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392646024523/index
[junit4] 2> NOTE: test params are: codec=Lucene45, sim=DefaultSimilarity, locale=en_IN, timezone=Australia/West
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_51 (64-bit)/cpus=2,threads=1,free=324814856,total=519438336
[junit4] 2> NOTE: All tests run in this JVM: [TestQuerySenderNoQuery, SyncSliceTest, TestDynamicFieldCollectionResource, ReturnFieldsTest, TestSolrDeletionPolicy2, EchoParamsTest, TestValueSourceCache, JsonLoaderTest, TestCSVLoader, TestManagedSchema, DistributedTermsComponentTest, BlockCacheTest, DebugComponentTest, StatsComponentTest, HardAutoCommitTest, TestStressReorder, JSONWriterTest, ZkCLITest, SpellCheckComponentTest, RegexBoostProcessorTest, AbstractAnalyticsStatsTest, DateFieldTest, SliceStateTest, DocumentBuilderTest, LoggingHandlerTest, BadComponentTest, TestMultiCoreConfBootstrap, HdfsRecoveryZkTest, SuggesterWFSTTest, TestRandomDVFaceting, TestConfig, TestModifyConfFiles, TestIndexSearcher, TestGroupingSearch, TestImplicitCoreProperties, TestSolr4Spatial, BasicDistributedZkTest, DocValuesMultiTest, InfoHandlerTest, SliceStateUpdateTest, TestHighFrequencyDictionaryFactory, TestDynamicFieldResource, DirectSolrSpellCheckerTest, CoreContainerCoreInitFailuresTest, TestSort, TestAtomicUpdateErrorCases, HdfsUnloadDistributedZkTest, TestOverriddenPrefixQueryForCustomFieldType, TestInfoStreamLogging, AutoCommitTest, HdfsBasicDistributedZk2Test, HdfsCollectionsAPIDistributedZkTest, SolrXmlInZkTest, TestCharFilters, TestPhraseSuggestions, PingRequestHandlerTest, HighlighterTest, TestFreeTextSuggestions, TestJmxIntegration, QueryEqualityTest, XsltUpdateRequestHandlerTest, DistributedDebugComponentTest, NotRequiredUniqueKeyTest, TestFaceting, TestBinaryResponseWriter, CollectionsAPIDistributedZkTest, CurrencyFieldOpenExchangeTest, TestFastWriter, TimeZoneUtilsTest, TestClassNameShortening, TestFileDictionaryLookup, TestBM25SimilarityFactory, QueryResultKeyTest, TestSolrXmlPersistor, SolrRequestParserTest, TestOmitPositions, TestDistributedGrouping, IndexSchemaTest, SystemInfoHandlerTest, SolrIndexSplitterTest, TestJoin, TestStandardQParsers, TestCollapseQParserPlugin, UniqFieldsUpdateProcessorFactoryTest, ShardRoutingTest, TestDistributedMissingSort, TestFieldTypeResource, SolrInfoMBeanTest, PluginInfoTest, ChaosMonkeyNothingIsSafeTest, FunctionTest, TestCursorMarkWithoutUniqueKey, CSVRequestHandlerTest, CopyFieldTest, TermsComponentTest, BJQParserTest, SolrIndexConfigTest, TestSolrXMLSerializer, TestReplicationHandler, TestUpdate, MBeansHandlerTest, TestBadConfig, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, BlockDirectoryTest, SortByFunctionTest, TestLazyCores, LeaderElectionIntegrationTest, SuggesterFSTTest, TestFieldCollectionResource, SampleTest, SynonymTokenizerTest, TestCustomSort, SpellCheckCollatorTest, TestSimpleQParserPlugin, QueryElevationComponentTest, TestFiltering, TestSolrXml, LukeRequestHandlerTest, TestPHPSerializedResponseWriter, NoFacetTest, OverseerRolesTest, BasicZkTest, SolrCoreTest, SimpleFacetsTest, DistributedQueryComponentCustomSortTest, TestRandomFaceting, TestDefaultSimilarityFactory, DistributedSuggestComponentTest, TestUniqueKeyFieldResource, TestCoreDiscovery, TestNonDefinedSimilarityFactory, PreAnalyzedUpdateProcessorTest, PreAnalyzedFieldTest, TestPostingsSolrHighlighter, TestDocBasedVersionConstraints, BadCopyFieldTest, TestRandomMergePolicy, OpenExchangeRatesOrgProviderTest, TestCollationField, DocumentAnalysisRequestHandlerTest, TestCloudManagedSchema, AbstractAnalyticsFacetTest, PathHierarchyTokenizerFactoryTest, TestMaxScoreQueryParser, TestSuggestSpellingConverter, RemoteQueryErrorTest, TestHighlightDedupGrouping, SearchHandlerTest, PrimitiveFieldTypeTest, DOMUtilTest, DeleteReplicaTest, DistanceFunctionTest, TestSolrXmlPersistence, CustomCollectionTest, TestSolrJ, TestSolrQueryParserResource, BinaryUpdateRequestHandlerTest, UnloadDistributedZkTest, TestDefaultSearchFieldResource, ShardRoutingCustomTest, SolrCoreCheckLockOnStartupTest, TermVectorComponentTest, TestJmxMonitoredMap, TestQuerySenderListener, DateMathParserTest, BadIndexSchemaTest, UUIDFieldTest, TestFastOutputStream, OpenCloseCoreStressTest, TestManagedSchemaFieldResource, TestAnalyzedSuggestions, DisMaxRequestHandlerTest, SuggestComponentTest, NoCacheHeaderTest, XmlUpdateRequestHandlerTest, SpatialFilterTest, TestCopyFieldCollectionResource, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, OverseerTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, TestDistributedSearch, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestReload, TestStressVersions, TestRangeQuery, TestCoreContainer, SolrCmdDistributorTest, PeerSyncTest, ConvertedLegacyTest, TestFunctionQuery, BasicFunctionalityTest, DirectUpdateHandlerTest, OverseerCollectionProcessorTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, CoreAdminHandlerTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, TestTrie, PolyFieldTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, TestSolrDeletionPolicy1, CacheHeaderTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, TestQueryUtils, StandardRequestHandlerTest, TestWriterPerf, TestQueryTypes, FileBasedSpellCheckerTest, RequestHandlersTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest, UpdateRequestProcessorFactoryTest, TestCSVResponseWriter, QueryParsingTest, TestPartialUpdateDeduplication, TestComponentsName, TestLFUCache, HighlighterConfigTest, SOLR749Test, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, ResponseLogComponentTest, TestStressRecovery, TestMergePolicyConfig, MultiTermTest, TestDocSet, TestBinaryField, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, DirectSolrConnectionTest, SpellPossibilityIteratorTest, TestCodecSupport, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestNumberUtils, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, LegacyHTMLStripCharFilterTest, ResourceLoaderTest, ScriptEngineTest, URLClassifyProcessorTest, TestFastLRUCache, TestSystemIdResolver, PrimUtilsTest, SpellingQueryConverterTest, RAMDirectoryFactoryTest, ClusterStateTest, TestLRUCache, TestUtils, TestDocumentBuilder, ZkNodePropsTest, FileUtilsTest, CircularListTest, TestRTGBase, CursorPagingTest, SolrTestCaseJ4Test, ExpressionTest]
[junit4] Completed in 2.58s, 12 tests, 6 failures <<< FAILURES!
[...truncated 174 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:453: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:433: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: 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:491: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1268: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:901: There were test failures: 372 suites, 1604 tests, 6 failures, 40 ignored (6 assumptions)
Total time: 97 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure