Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11549/ Java: 64bit/jdk1.8.0_25 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed. FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.testDistribSearch Error Message: Error from server at https://127.0.0.1:44277: CLUSTERSTATUS the collection time out:180s Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:44277: CLUSTERSTATUS the collection time out:180s at __randomizedtesting.SeedInfo.seed([348F48E0FC0297BF:B569C6F88B5DF783]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210) at org.apache.solr.cloud.MultiThreadedOCPTest.testLongAndShortRunningParallelApiCalls(MultiThreadedOCPTest.java:249) at org.apache.solr.cloud.MultiThreadedOCPTest.doTest(MultiThreadedOCPTest.java:75) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9155 lines...] [junit4] Suite: org.apache.solr.cloud.MultiThreadedOCPTest [junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/init-core-data-001 [junit4] 2> 516270 T3005 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 516270 T3005 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 516281 T3005 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 516282 T3005 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 516282 T3006 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 516382 T3005 oasc.ZkTestServer.run start zk server on port:56919 [junit4] 2> 516383 T3005 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 516384 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 516387 T3013 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b0b6224 name:ZooKeeperConnection Watcher:127.0.0.1:56919 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 516387 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 516388 T3005 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 516388 T3005 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 516391 T3005 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 516392 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 516393 T3016 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@541074b0 name:ZooKeeperConnection Watcher:127.0.0.1:56919/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 516393 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 516393 T3005 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 516394 T3005 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 516395 T3005 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 516396 T3005 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 516397 T3005 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 516398 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 516399 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 516401 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 516401 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 516403 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 516403 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 516404 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 516405 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 516406 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 516407 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 516408 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 516408 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 516410 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 516410 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 516412 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 516412 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 516414 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 516414 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 516415 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 516416 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 516417 T3005 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 516418 T3005 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 516420 T3005 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 516427 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 516427 T3019 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7647ebd5 name:ZooKeeperConnection Watcher:127.0.0.1:56919/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 516428 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 516428 T3005 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 516837 T3005 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 516853 T3005 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@953de04{/,null,AVAILABLE} [junit4] 2> 516867 T3005 oejs.AbstractConnector.doStart Started ServerConnector@2f221533{SSL-http/1.1}{127.0.0.1:43170} [junit4] 2> 516867 T3005 oejs.Server.doStart Started @518329ms [junit4] 2> 516869 T3005 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 516870 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 516870 T3005 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002 [junit4] 2> 516871 T3005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/' [junit4] 2> 516934 T3005 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/solr.xml [junit4] 2> 516948 T3005 oasc.CoreContainer.<init> New CoreContainer 536157303 [junit4] 2> 516948 T3005 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/] [junit4] 2> 516949 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 516949 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 516950 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 516950 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 516950 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 516951 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 516951 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 516951 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 516952 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 516952 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 516953 T3005 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 516955 T3005 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 516955 T3005 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 516955 T3005 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 516956 T3005 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56919/solr [junit4] 2> 516956 T3005 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 516956 T3005 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 516958 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 516959 T3033 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d3bd0f5 name:ZooKeeperConnection Watcher:127.0.0.1:56919 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 516959 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 516959 T3005 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 516969 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 516970 T3036 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@56f4c2f7 name:ZooKeeperConnection Watcher:127.0.0.1:56919/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 516970 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 516971 T3005 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 516973 T3005 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 516975 T3005 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 516981 T3005 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 516983 T3005 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 516985 T3005 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 516986 T3005 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43170_ [junit4] 2> 516989 T3005 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43170_ [junit4] 2> 516991 T3005 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 516992 T3005 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 516993 T3005 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 516995 T3005 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:43170_ [junit4] 2> 516995 T3005 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 516996 T3005 oasc.Overseer.start Overseer (id=93112576879034372-127.0.0.1:43170_-n_0000000000) starting [junit4] 2> 516998 T3005 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 517003 T3005 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 517021 T3038 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 517022 T3037 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 517026 T3005 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 517027 T3005 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 517028 T3005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 517032 T3040 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 517033 T3040 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 517034 T3036 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 517035 T3037 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:43170";, [junit4] 2> "node_name":"127.0.0.1:43170_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 517036 T3037 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:43170";, [junit4] 2> "node_name":"127.0.0.1:43170_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 517036 T3037 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 517036 T3037 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 517037 T3036 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> 517039 T3040 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 518040 T3040 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 518040 T3040 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 518040 T3040 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 518041 T3040 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 518041 T3040 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 518041 T3040 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/collection1/' [junit4] 2> 518042 T3040 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest%20348F48E0FC0297BF-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 518042 T3040 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest%20348F48E0FC0297BF-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 518043 T3040 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest%20348F48E0FC0297BF-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 518053 T3040 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 518055 T3040 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 518060 T3040 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 518073 T3040 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 518081 T3040 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 518081 T3040 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 518086 T3040 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 518221 T3040 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 518225 T3040 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 518227 T3040 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 518233 T3040 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 518236 T3040 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 518237 T3040 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 518238 T3040 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 518238 T3040 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 518238 T3040 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 518239 T3040 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 518240 T3040 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 518240 T3040 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 518240 T3040 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 518240 T3040 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/collection1/data/ [junit4] 2> 518241 T3040 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@76eb4992 [junit4] 2> 518241 T3040 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/collection1/data [junit4] 2> 518241 T3040 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/collection1/data/index/ [junit4] 2> 518242 T3040 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 518242 T3040 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-002/collection1/data/index [junit4] 2> 518242 T3040 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=64.3984375, floorSegmentMB=1.7841796875, forceMergeDeletesPctAllowed=11.538031929360503, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 518243 T3040 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@698cb628 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@529929da),segFN=segments_1,generation=1} [junit4] 2> 518243 T3040 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 518245 T3040 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 518246 T3040 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 518246 T3040 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 518246 T3040 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 518246 T3040 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 518246 T3040 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 518247 T3040 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 518247 T3040 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 518247 T3040 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 518248 T3040 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 518248 T3040 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 518248 T3040 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 518248 T3040 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 518249 T3040 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 518249 T3040 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 518249 T3040 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 518250 T3040 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 518250 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 518250 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 518251 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 518251 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 518252 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 518252 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 518252 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 518253 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 518253 T3040 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 518253 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 518256 T3040 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 518257 T3040 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 518258 T3040 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 518259 T3040 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 518259 T3040 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 518260 T3040 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 518261 T3040 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 518261 T3040 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 518262 T3040 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@698cb628 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@529929da),segFN=segments_1,generation=1} [junit4] 2> 518262 T3040 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 518262 T3040 oass.SolrIndexSearcher.<init> Opening Searcher@7e2d1c49[collection1] main [junit4] 2> 518263 T3040 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 518263 T3040 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 518263 T3040 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 518264 T3040 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 518264 T3040 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 518264 T3040 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 518265 T3040 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 518265 T3040 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 518265 T3040 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 518265 T3040 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 518266 T3040 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 518266 T3041 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e2d1c49[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 518267 T3005 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 [junit4] 2> 518267 T3005 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 518267 T3044 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:43170 collection:control_collection shard:shard1 [junit4] 2> 518269 T3005 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 518270 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 518270 T3044 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 518271 T3047 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13ca87fa name:ZooKeeperConnection Watcher:127.0.0.1:56919/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 518271 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 518272 T3005 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 518273 T3005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 518278 T3005 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 518281 T3044 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 518283 T3036 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 518284 T3037 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 518286 T3044 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 518286 T3044 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C642 name=collection1 org.apache.solr.core.SolrCore@346bebc3 url=https://127.0.0.1:43170/collection1 node=127.0.0.1:43170_ C642_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:43170, node_name=127.0.0.1:43170_, state=down} [junit4] 2> 518286 T3044 C642 P43170 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:43170/collection1/ [junit4] 2> 518286 T3044 C642 P43170 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 518287 T3044 C642 P43170 oasc.SyncStrategy.syncToMe https://127.0.0.1:43170/collection1/ has no replicas [junit4] 2> 518287 T3044 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:43170/collection1/ shard1 [junit4] 2> 518287 T3044 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 518290 T3036 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 518290 T3037 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"https://127.0.0.1:43170";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 518394 T3047 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> 518395 T3036 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> 518445 T3044 oasc.ZkController.register We are https://127.0.0.1:43170/collection1/ and leader is https://127.0.0.1:43170/collection1/ [junit4] 2> 518445 T3044 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:43170 [junit4] 2> 518446 T3044 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 518446 T3044 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 518446 T3044 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 518448 T3036 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 518451 T3037 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:43170";, [junit4] 2> "node_name":"127.0.0.1:43170_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 518452 T3037 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:43170";, [junit4] 2> "node_name":"127.0.0.1:43170_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 518555 T3047 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> 518555 T3036 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> 518620 T3005 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 518621 T3005 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 518622 T3005 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@40aedab9{/,null,AVAILABLE} [junit4] 2> 518624 T3005 oejs.AbstractConnector.doStart Started ServerConnector@4855a82a{SSL-http/1.1}{127.0.0.1:44277} [junit4] 2> 518624 T3005 oejs.Server.doStart Started @520087ms [junit4] 2> 518626 T3005 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 518626 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 518626 T3005 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003 [junit4] 2> 518626 T3005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/' [junit4] 2> 518649 T3005 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/solr.xml [junit4] 2> 518658 T3005 oasc.CoreContainer.<init> New CoreContainer 1978043693 [junit4] 2> 518659 T3005 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/] [junit4] 2> 518659 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 518660 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 518660 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 518660 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 518661 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 518661 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 518661 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 518661 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 518662 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 518662 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 518664 T3005 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 518665 T3005 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 518665 T3005 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 518666 T3005 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 518666 T3005 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56919/solr [junit4] 2> 518666 T3005 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 518667 T3005 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 518669 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 518693 T3061 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2bf65bd name:ZooKeeperConnection Watcher:127.0.0.1:56919 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 518693 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 518694 T3005 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 518696 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 518697 T3064 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@46c4a907 name:ZooKeeperConnection Watcher:127.0.0.1:56919/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 518697 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 518701 T3005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 519703 T3005 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44277_ [junit4] 2> 519704 T3005 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44277_ [junit4] 2> 519707 T3005 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 519709 T3065 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 519709 T3065 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 519709 T3036 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 519709 T3065 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 519710 T3037 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:44277";, [junit4] 2> "node_name":"127.0.0.1:44277_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 519710 T3037 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:44277";, [junit4] 2> "node_name":"127.0.0.1:44277_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 519711 T3037 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 519711 T3037 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 519812 T3047 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> 519812 T3064 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> 519812 T3036 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> 520710 T3065 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 520710 T3065 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 520711 T3065 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 520711 T3065 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 520712 T3065 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 520712 T3065 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/collection1/' [junit4] 2> 520712 T3065 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest%20348F48E0FC0297BF-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 520713 T3065 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest%20348F48E0FC0297BF-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 520713 T3065 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest%20348F48E0FC0297BF-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 520722 T3065 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 520723 T3065 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 520727 T3065 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 520736 T3065 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 520743 T3065 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 520744 T3065 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 520748 T3065 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 520863 T3065 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 520866 T3065 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 520867 T3065 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 520873 T3065 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 520875 T3065 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 520876 T3065 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 520877 T3065 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 520877 T3065 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 520877 T3065 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 520877 T3065 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 520878 T3065 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 520878 T3065 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 520878 T3065 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 520878 T3065 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/collection1/data/ [junit4] 2> 520878 T3065 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@76eb4992 [junit4] 2> 520879 T3065 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/collection1/data [junit4] 2> 520879 T3065 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/collection1/data/index/ [junit4] 2> 520879 T3065 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 520879 T3065 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-003/collection1/data/index [junit4] 2> 520879 T3065 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=64.3984375, floorSegmentMB=1.7841796875, forceMergeDeletesPctAllowed=11.538031929360503, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 520880 T3065 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d90ed50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ba0c85c),segFN=segments_1,generation=1} [junit4] 2> 520880 T3065 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 520882 T3065 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 520882 T3065 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 520882 T3065 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 520882 T3065 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 520883 T3065 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 520883 T3065 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 520883 T3065 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 520883 T3065 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 520883 T3065 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 520884 T3065 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 520884 T3065 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 520884 T3065 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 520884 T3065 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 520885 T3065 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 520885 T3065 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 520885 T3065 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 520886 T3065 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 520886 T3065 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 520886 T3065 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 520886 T3065 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 520887 T3065 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 520887 T3065 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 520887 T3065 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 520888 T3065 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 520888 T3065 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 520888 T3065 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 520888 T3065 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 520890 T3065 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 520890 T3065 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 520891 T3065 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 520892 T3065 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 520892 T3065 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 520893 T3065 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 520893 T3065 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 520893 T3065 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 520894 T3065 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d90ed50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ba0c85c),segFN=segments_1,generation=1} [junit4] 2> 520894 T3065 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 520894 T3065 oass.SolrIndexSearcher.<init> Opening Searcher@67fb4e47[collection1] main [junit4] 2> 520894 T3065 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 520895 T3065 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 520895 T3065 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 520895 T3065 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 520895 T3065 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 520896 T3065 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 520896 T3065 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 520896 T3065 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 520896 T3065 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 520896 T3065 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 520897 T3066 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67fb4e47[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 520897 T3065 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 520898 T3069 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:44277 collection:collection1 shard:shard2 [junit4] 2> 520898 T3005 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 [junit4] 2> 520898 T3005 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 520898 T3069 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 520901 T3069 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 520902 T3036 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 520902 T3069 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 520902 T3069 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C643 name=collection1 org.apache.solr.core.SolrCore@36c3d75b url=https://127.0.0.1:44277/collection1 node=127.0.0.1:44277_ C643_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:44277, node_name=127.0.0.1:44277_, state=down} [junit4] 2> 520902 T3069 C643 P44277 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:44277/collection1/ [junit4] 2> 520903 T3069 C643 P44277 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 520903 T3037 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> 520903 T3069 C643 P44277 oasc.SyncStrategy.syncToMe https://127.0.0.1:44277/collection1/ has no replicas [junit4] 2> 520903 T3069 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:44277/collection1/ shard2 [junit4] 2> 520903 T3069 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 520905 T3036 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 520906 T3037 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"https://127.0.0.1:44277";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 521008 T3047 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> 521008 T3036 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> 521008 T3064 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> 521056 T3069 oasc.ZkController.register We are https://127.0.0.1:44277/collection1/ and leader is https://127.0.0.1:44277/collection1/ [junit4] 2> 521056 T3069 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:44277 [junit4] 2> 521057 T3069 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 521057 T3069 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 521057 T3069 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 521058 T3036 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 521059 T3037 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:44277";, [junit4] 2> "node_name":"127.0.0.1:44277_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 521059 T3037 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:44277";, [junit4] 2> "node_name":"127.0.0.1:44277_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 521150 T3005 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 521151 T3005 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 521152 T3005 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@7ca0703a{/,null,AVAILABLE} [junit4] 2> 521154 T3005 oejs.AbstractConnector.doStart Started ServerConnector@7f36141e{SSL-http/1.1}{127.0.0.1:41633} [junit4] 2> 521155 T3005 oejs.Server.doStart Started @522617ms [junit4] 2> 521156 T3005 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 521156 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 521156 T3005 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004 [junit4] 2> 521156 T3005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/' [junit4] 2> 521161 T3047 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> 521161 T3036 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> 521161 T3064 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> 521188 T3005 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/solr.xml [junit4] 2> 521197 T3005 oasc.CoreContainer.<init> New CoreContainer 2108412659 [junit4] 2> 521198 T3005 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/] [junit4] 2> 521198 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 521198 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 521199 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 521199 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 521199 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 521200 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 521200 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 521200 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 521201 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 521201 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 521203 T3005 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 521204 T3005 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 521204 T3005 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 521204 T3005 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 521205 T3005 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56919/solr [junit4] 2> 521205 T3005 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 521205 T3005 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 521206 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 521207 T3083 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@78d0a5c4 name:ZooKeeperConnection Watcher:127.0.0.1:56919 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 521207 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 521208 T3005 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 521210 T3005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 521211 T3086 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@41618cff name:ZooKeeperConnection Watcher:127.0.0.1:56919/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 521211 T3005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 521214 T3005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 522217 T3005 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41633_ [junit4] 2> 522218 T3005 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41633_ [junit4] 2> 522221 T3005 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 522224 T3087 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 522224 T3087 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 522225 T3036 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 522225 T3087 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 522225 T3037 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:41633";, [junit4] 2> "node_name":"127.0.0.1:41633_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 522226 T3037 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:41633";, [junit4] 2> "node_name":"127.0.0.1:41633_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 522226 T3037 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 522226 T3037 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 522328 T3047 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> 522328 T3086 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> 522328 T3064 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> 522328 T3036 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> 523225 T3087 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 523225 T3087 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 523226 T3087 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 523226 T3087 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 523227 T3087 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 523227 T3087 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/collection1/' [junit4] 2> 523227 T3087 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest%20348F48E0FC0297BF-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 523227 T3087 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest%20348F48E0FC0297BF-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 523228 T3087 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest%20348F48E0FC0297BF-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 523238 T3087 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 523239 T3087 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 523242 T3087 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 523257 T3087 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 523265 T3087 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 523266 T3087 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 523270 T3087 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 523385 T3087 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 523388 T3087 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 523389 T3087 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 523395 T3087 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 523396 T3087 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 523398 T3087 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 523398 T3087 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 523399 T3087 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 523399 T3087 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 523399 T3087 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 523399 T3087 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 523399 T3087 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 523400 T3087 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 523400 T3087 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/collection1/data/ [junit4] 2> 523400 T3087 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@76eb4992 [junit4] 2> 523401 T3087 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/collection1/data [junit4] 2> 523401 T3087 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/collection1/data/index/ [junit4] 2> 523401 T3087 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 523402 T3087 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-004/collection1/data/index [junit4] 2> 523402 T3087 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=64.3984375, floorSegmentMB=1.7841796875, forceMergeDeletesPctAllowed=11.538031929360503, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 523402 T3087 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3ce8d339 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8902a0),segFN=segments_1,generation=1} [junit4] 2> 523403 T3087 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 523404 T3087 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 523405 T3087 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 523405 T3087 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 523405 T3087 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 523405 T3087 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 523405 T3087 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 523405 T3087 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 523406 T3087 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 523406 T3087 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 523406 T3087 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 523406 T3087 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 523406 T3087 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 523407 T3087 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 523407 T3087 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 523407 T3087 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 523408 T3087 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 523408 T3087 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 523408 T3087 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 523409 T3087 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 523409 T3087 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 523409 T3087 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 523409 T3087 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 523410 T3087 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 523410 T3087 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 523410 T3087 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 523411 T3087 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 523411 T3087 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 523412 T3087 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 523413 T3087 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 523413 T3087 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 523414 T3087 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 523414 T3087 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 523415 T3087 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 523415 T3087 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 523416 T3087 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 523416 T3087 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3ce8d339 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8902a0),segFN=segments_1,generation=1} [junit4] 2> 523416 T3087 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 523416 T3087 oass.SolrIndexSearcher.<init> Opening Searcher@56a289a1[collection1] main [junit4] 2> 523417 T3087 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 523417 T3087 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 523417 T3087 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 523418 T3087 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 523418 T3087 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 523418 T3087 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 523418 T3087 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 523418 T3087 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 523418 T3087 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 523419 T3087 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 523419 T3088 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@56a289a1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 523419 T3087 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 523420 T3091 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:41633 collection:collection1 shard:shard1 [junit4] 2> 523420 T3005 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 [junit4] 2> 523421 T3005 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 52 [...truncated too long message...] .solr.core.SolrCore@c415e28 [junit4] 2> 760851 T3005 oasc.ZkController.unRegisterConfListener a listener was removed because of core close [junit4] 2> 760851 T3005 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0} [junit4] 2> 760851 T3005 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState [junit4] 2> 760852 T3005 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 760852 T3005 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser [junit4] 2> 760852 T3005 oasc.SolrCore.closeSearcher [ocptest_shardsplit2_shard2_1_replica1] Closing main searcher on request. [junit4] 2> 760867 T3005 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 760868 T3005 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-006/ocptest_shardsplit2_shard2_1_replica1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-006/ocptest_shardsplit2_shard2_1_replica1/data/index;done=false>>] [junit4] 2> 760868 T3005 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-006/ocptest_shardsplit2_shard2_1_replica1/data/index [junit4] 2> 760869 T3005 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-006/ocptest_shardsplit2_shard2_1_replica1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-006/ocptest_shardsplit2_shard2_1_replica1/data;done=false>>] [junit4] 2> 760869 T3005 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001/tempDir-006/ocptest_shardsplit2_shard2_1_replica1/data [junit4] 2> 760871 T3005 oasc.Overseer.close Overseer (id=93112576879034381-127.0.0.1:60981_-n_0000000004) closing [junit4] 2> 760871 T3402 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:60981_ [junit4] 2> 762382 T3395 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 762383 T3005 oejs.AbstractConnector.doStop Stopped ServerConnector@47aae8eb{SSL-http/1.1}{127.0.0.1:0} [junit4] 2> 762384 T3005 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@1109d796{/,null,UNAVAILABLE} [junit4] 2> 762384 T3005 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch [junit4] 2> 762386 T3005 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:56919 56919 [junit4] 2> 763082 T3006 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:56919 56919 [junit4] 2> 763084 T3006 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 6 /solr/live_nodes [junit4] 2> 5 /solr/overseer/queue [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MultiThreadedOCPTest -Dtests.method=testDistribSearch -Dtests.seed=348F48E0FC0297BF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr_BA_#Latn -Dtests.timezone=Africa/Ndjamena -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 247s J1 | MultiThreadedOCPTest.testDistribSearch <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:44277: CLUSTERSTATUS the collection time out:180s [junit4] > at __randomizedtesting.SeedInfo.seed([348F48E0FC0297BF:B569C6F88B5DF783]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210) [junit4] > at org.apache.solr.cloud.MultiThreadedOCPTest.testLongAndShortRunningParallelApiCalls(MultiThreadedOCPTest.java:249) [junit4] > at org.apache.solr.cloud.MultiThreadedOCPTest.doTest(MultiThreadedOCPTest.java:75) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 763101 T3005 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest 348F48E0FC0297BF-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {_version_=BlockTreeOrds(blocksize=128), multiDefault=PostingsFormat(name=MockRandom), intDefault=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=Asserting), range_facet_l=BlockTreeOrds(blocksize=128), timestamp=BlockTreeOrds(blocksize=128)}, docValues:{timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=sr_BA_#Latn, timezone=Africa/Ndjamena [junit4] 2> NOTE: Linux 3.13.0-43-generic amd64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=8,threads=1,free=56888968,total=217374720 [junit4] 2> NOTE: All tests run in this JVM: [RemoteQueryErrorTest, PrimitiveFieldTypeTest, TestDynamicFieldResource, ZkSolrClientTest, SolrCmdDistributorTest, TermsComponentTest, TestSearchPerf, ReturnFieldsTest, CoreMergeIndexesAdminHandlerTest, LeaderFailoverAfterPartitionTest, CollectionsAPIDistributedZkTest, TestLMJelinekMercerSimilarityFactory, TestConfig, TestSolrQueryParser, TestJmxMonitoredMap, FastVectorHighlighterTest, ResponseHeaderTest, TestSolrDeletionPolicy1, TestSolrIndexConfig, EnumFieldTest, TestConfigOverlay, ZkNodePropsTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestDefaultSearchFieldResource, CurrencyFieldOpenExchangeTest, StandardRequestHandlerTest, TestRequestStatusCollectionAPI, TestZkChroot, LeaderElectionIntegrationTest, TestSchemaSimilarityResource, IndexBasedSpellCheckerTest, DateRangeFieldTest, TestFileDictionaryLookup, ChaosMonkeySafeLeaderTest, SpatialRPTFieldTypeTest, RAMDirectoryFactoryTest, TestSolrXml, TestFoldingMultitermQuery, TestSuggestSpellingConverter, TestSolrDynamicMBean, TestImplicitCoreProperties, DistribDocExpirationUpdateProcessorTest, SpatialFilterTest, TestBadConfig, URLClassifyProcessorTest, XsltUpdateRequestHandlerTest, TestExactSharedStatsCache, TestBulkSchemaAPI, AddBlockUpdateTest, TestPostingsSolrHighlighter, TestBM25SimilarityFactory, TestSimpleQParserPlugin, SolrRequestParserTest, CustomCollectionTest, MultiThreadedOCPTest] [junit4] Completed on J1 in 246.87s, 1 test, 1 error <<< FAILURES! [...truncated 941 lines...] BUILD FAILED /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:187: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1348: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:956: There were test failures: 460 suites, 1868 tests, 1 error, 47 ignored (22 assumptions) Total time: 62 minutes 47 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0_25 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
