lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.6.0_35) - Build # 786 - Failure!
Date Sat, 15 Sep 2012 17:00:29 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/786/
Java: 32bit/jdk1.6.0_35 -server -XX:+UseSerialGC

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.BasicZkTest

Error Message:
Clean up static fields (in @AfterClass?), your test seems to hang on to approximately 20,468,480 bytes (threshold is 10,485,760):   - 20,467,272 bytes, protected static org.apache.solr.cloud.ZkTestServer org.apache.solr.cloud.AbstractZkTestCase.zkServer   - 304 bytes, protected static java.lang.String org.apache.solr.cloud.AbstractZkTestCase.zkDir   - 256 bytes, public static org.junit.rules.TestRule org.apache.solr.SolrTestCaseJ4.solrClassRules   - 240 bytes, public static java.io.File org.apache.solr.cloud.AbstractZkTestCase.SOLRHOME   - 224 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.testSolrHome   - 120 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp   - 64 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName

Stack Trace:
junit.framework.AssertionFailedError: Clean up static fields (in @AfterClass?), your test seems to hang on to approximately 20,468,480 bytes (threshold is 10,485,760):
  - 20,467,272 bytes, protected static org.apache.solr.cloud.ZkTestServer org.apache.solr.cloud.AbstractZkTestCase.zkServer
  - 304 bytes, protected static java.lang.String org.apache.solr.cloud.AbstractZkTestCase.zkDir
  - 256 bytes, public static org.junit.rules.TestRule org.apache.solr.SolrTestCaseJ4.solrClassRules
  - 240 bytes, public static java.io.File org.apache.solr.cloud.AbstractZkTestCase.SOLRHOME
  - 224 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.testSolrHome
  - 120 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp
  - 64 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName
	at __randomizedtesting.SeedInfo.seed([FCA6EA40823DC876]:0)
	at com.carrotsearch.randomizedtesting.rules.StaticFieldsInvariantRule$1.afterAlways(StaticFieldsInvariantRule.java:119)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:662)




Build Log:
[...truncated 7232 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicZkTest
[junit4:junit4]   2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicZkTest-1347728125984
[junit4:junit4]   2> 1 T2778 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 5 T2779 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 6 T2779 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 7 T2779 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 92 T2778 oasc.ZkTestServer.run start zk server on port:54649
[junit4:junit4]   2> 92 T2778 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54649 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c95e6a
[junit4:junit4]   2> 95 T2778 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 95 T2784 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54649
[junit4:junit4]   2> 95 T2784 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54649, initiating session
[junit4:junit4]   2> 95 T2780 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54652
[junit4:junit4]   2> 95 T2780 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54652
[junit4:junit4]   2> 96 T2782 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 100 T2782 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cadb30280000 with negotiated timeout 10000 for client /127.0.0.1:54652
[junit4:junit4]   2> 100 T2784 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54649, sessionid = 0x139cadb30280000, negotiated timeout = 10000
[junit4:junit4]   2> 100 T2785 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c95e6a name:ZooKeeperConnection Watcher:127.0.0.1:54649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 101 T2778 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 102 T2778 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 105 T2783 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139cadb30280000
[junit4:junit4]   2> 107 T2778 oaz.ZooKeeper.close Session: 0x139cadb30280000 closed
[junit4:junit4]   2> 107 T2778 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54649/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@13157bb
[junit4:junit4]   2> 107 T2780 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139cadb30280000, likely client has closed socket
[junit4:junit4]   2> 108 T2785 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 108 T2780 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54652 which had sessionid 0x139cadb30280000
[junit4:junit4]   2> 108 T2778 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 109 T2786 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54649
[junit4:junit4]   2> 110 T2786 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54649, initiating session
[junit4:junit4]   2> 110 T2780 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54655
[junit4:junit4]   2> 111 T2780 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54655
[junit4:junit4]   2> 113 T2782 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cadb30280001 with negotiated timeout 10000 for client /127.0.0.1:54655
[junit4:junit4]   2> 113 T2786 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54649, sessionid = 0x139cadb30280001, negotiated timeout = 10000
[junit4:junit4]   2> 113 T2787 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13157bb name:ZooKeeperConnection Watcher:127.0.0.1:54649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 113 T2778 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 113 T2778 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 117 T2778 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 120 T2778 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 123 T2778 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 127 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 128 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 134 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 135 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 138 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 139 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 142 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 143 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 147 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 147 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 150 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 150 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 154 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 154 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 157 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 157 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 161 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 161 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 164 T2778 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 164 T2778 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 168 T2783 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139cadb30280001
[junit4:junit4]   2> 169 T2778 oaz.ZooKeeper.close Session: 0x139cadb30280001 closed
[junit4:junit4]   2> 169 T2780 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139cadb30280001, likely client has closed socket
[junit4:junit4]   2> 169 T2780 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54655 which had sessionid 0x139cadb30280001
[junit4:junit4]   2> 169 T2787 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 170 T2778 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> 170 T2778 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 173 T2778 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 173 T2778 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 240 T2778 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 312 T2778 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 313 T2778 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 318 T2778 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 914 T2778 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 923 T2778 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 927 T2778 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 933 T2778 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 937 T2778 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 941 T2778 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 941 T2778 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 942 T2778 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 942 T2778 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 943 T2778 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 952 T2778 oasc.CoreContainer.<init> New CoreContainer 8155157
[junit4:junit4]   2> 953 T2778 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54649/solr
[junit4:junit4]   2> 954 T2778 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54649/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1a296da
[junit4:junit4]   2> 955 T2788 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54649
[junit4:junit4]   2> 955 T2778 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 957 T2788 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54649, initiating session
[junit4:junit4]   2> 957 T2780 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54658
[junit4:junit4]   2> 958 T2780 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54658
[junit4:junit4]   2> 959 T2782 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cadb30280002 with negotiated timeout 10000 for client /127.0.0.1:54658
[junit4:junit4]   2> 959 T2788 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54649, sessionid = 0x139cadb30280002, negotiated timeout = 10000
[junit4:junit4]   2> 959 T2789 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a296da name:ZooKeeperConnection Watcher:127.0.0.1:54649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 959 T2778 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 962 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 964 T2778 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 966 T2778 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/JenkinsSlave-PC:null_solr
[junit4:junit4]   2> 967 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/JenkinsSlave-PC:null_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/JenkinsSlave-PC:null_solr
[junit4:junit4]   2> 968 T2778 oascc.SolrZkClient.makePath makePath: /live_nodes/JenkinsSlave-PC:null_solr
[junit4:junit4]   2> 972 T2778 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 972 T2778 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 972 T2778 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 973 T2778 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 973 T2778 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 973 T2778 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 973 T2778 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 973 T2778 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 973 T2778 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 973 T2778 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 980 T2778 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 987 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 988 T2778 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 991 T2778 oasc.Overseer.start Overseer (id=88324710465011714-JenkinsSlave-PC:null_solr-n_0000000000) starting
[junit4:junit4]   2> 992 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 995 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 997 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 999 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1000 T2791 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 1000 T2778 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 1004 T2778 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1006 T2778 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicZkTest-1347728125984\
[junit4:junit4]   2> 1006 T2790 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 1006 T2778 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15b1773
[junit4:junit4]   2> 1007 T2778 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicZkTest-1347728125984\index/
[junit4:junit4]   2> 1008 T2778 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicZkTest-1347728125984\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1009 T2778 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicZkTest-1347728125984\index forceNew:false
[junit4:junit4]   2> 1011 T2778 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1012 T2778 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1012 T2778 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1013 T2778 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1013 T2778 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1013 T2778 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1014 T2778 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1014 T2778 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1015 T2778 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1015 T2778 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1015 T2778 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1016 T2778 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1016 T2778 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1017 T2778 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1017 T2778 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1017 T2778 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1018 T2778 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1018 T2778 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1018 T2778 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1019 T2778 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1019 T2778 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1019 T2778 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1019 T2778 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1019 T2778 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1019 T2778 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1020 T2778 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1020 T2778 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1021 T2778 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1025 T2778 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1029 T2778 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1032 T2778 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [THROW.ERROR.ON.ADD.UPDATEPROCESSOR.JS, SOLRCONFIG-HIGHLIGHT.XML, SCHEMA-REQUIRED-FIELDS.XML, TRIVIAL.UPDATEPROCESSOR1.JS, BAD-SCHEMA-DUP-DYNAMICFIELD.XML, SCHEMA-MINIMAL.XML, SCHEMA-REPLICATION2.XML, SOLRCONFIG-CACHING.XML, SOLRCONFIG-REPEATER.XML, CURRENCY.XML, BAD-SCHEMA-NONTEXT-ANALYZER.XML, SOLRCONFIG-MERGEPOLICY.XML, SOLRCONFIG-TLOG.XML, SOLRCONFIG-MASTER.XML, BAD-SCHEMA-UNIQUEKEY-MULTIVALUED.XML, SCHEMA11.XML, SOLRCONFIG-BASIC.XML, DA_COMPOUNDDICTIONARY.TXT, MISSLEADING.EXTENSION.UPDATEPROCESSOR.JS.TXT, SCHEMA-COPYFIELD-TEST.XML, SOLRCONFIG-SLAVE.XML, ELEVATE.XML, SOLRCONFIG-PROPINJECT-INDEXDEFAULT.XML, TRIVIAL.UPDATEPROCESSOR0.JS, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML, SCHEMA-CHARFILTERS.XML, SCHEMA-IB.XML, SOLRCONFIG-QUERYSENDER.XML, DA_UTF8.XML, SCHEMA-REPLICATION1.XML, CONDITIONAL.UPDATEPROCESSOR.JS, MISSING.FUNCTIONS.UPDATEPROCESSOR.JS, HYPHENATION.DTD, SOLRCONFIG-ENABLEPLUGIN.XML, STEMDICT.TXT, SCHEMA-PHRASESUGGEST.XML, HUNSPELL-TEST.AFF, STOPTYPES-1.TXT, STOPWORDSWRONGENCODING.TXT, SCHEMA-NUMERIC.XML, SOLRCONFIG-TRANSFORMERS.XML, SOLRCONFIG-PROPINJECT.XML, BAD-SCHEMA-NOT-INDEXED-BUT-TF.XML, SOLRCONFIG-SIMPLELOCK.XML, WDFTYPES.TXT, STOPTYPES-2.TXT, SCHEMA-REVERSED.XML, BAD-SCHEMA-CURRENCY-FT-MULTIVALUED.XML, SOLRCONFIG-SPELLCHECKCOMPONENT.XML, BAD-SCHEMA-CURRENCY-MULTIVALUED.XML, SCHEMA-DFR.XML, SOLRCONFIG-PHRASESUGGEST.XML, BAD-SCHEMA-NOT-INDEXED-BUT-POS.XML, KEEP-1.TXT, OPEN-EXCHANGE-RATES.JSON, STOPWITHBOM.TXT, SOLRCONFIG-SPELLCHECKER.XML, SCHEMA-BINARYFIELD.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, BAD-SCHEMA-DUP-FIELDTYPE.XML, SOLRCONFIG-MASTER1.XML, SYNONYMS.TXT, BAD-SCHEMA-CURRENCY-DYNAMIC-MULTIVALUED.XML, SCHEMA.XML, SCHEMA_CODEC.XML, SOLRCONFIG-SOLR-749.XML, SOLRCONFIG-MASTER1-KEEPONEBACKUP.XML, STOP-2.TXT, SOLRCONFIG-FUNCTIONQUERY.XML, SOLRCONFIG-TERMINDEX.XML, SCHEMA-LMDIRICHLET.XML, SOLRCONFIG-ELEVATE.XML, STOPWORDS.TXT, SCHEMA-FOLDING.XML, SCHEMA-STOP-KEEP.XML, BAD-SCHEMA-NOT-INDEXED-BUT-NORMS.XML, SOLRCONFIG-SOLCOREPROPERTIES.XML, STOP-1.TXT, SOLRCONFIG-MASTER2.XML, SCHEMA-SPELLCHECKER.XML, SOLRCONFIG-LAZYWRITER.XML, SCHEMA-LUCENEMATCHVERSION.XML, FRENCHARTICLES.TXT, BAD-MP-SOLRCONFIG.XML, SCHEMA15.XML, SOLRCONFIG-REQHANDLER.INCL, SCHEMASURROUND.XML, SCHEMA-COLLATEFILTER.XML, SOLRCONFIG-MASTER3.XML, HUNSPELL-TEST.DIC, SOLRCONFIG-XINCLUDE.XML, BAD-SCHEMA-CODEC-GLOBAL-VS-FT-MISMATCH.XML, SOLRCONFIG-SLAVE1.XML, SOLRCONFIG-DELPOLICY1.XML, SCHEMA-SIM.XML, SCHEMA-COLLATE.XML, STOP-SNOWBALL.TXT, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, PROTWORDS.TXT, SCHEMA-TRIE.XML, SOLRCONFIG_CODEC.XML, BAD-SOLRCONFIG-INVALID-SCRIPTFILE.XML, SCHEMA-TFIDF.XML, SOLRCONFIG-SCRIPT-UPDATEPROCESSOR.XML, SCHEMA-LMJELINEKMERCER.XML, PHRASESUGGEST.TXT, BAD-SOLRCONFIG-MISSING-SCRIPTFILE.XML, SOLRCONFIG-BASIC-LUCENEVERSION31.XML, BAD-SCHEMA-UNIQUEKEY-USES-DEFAULT.XML, OLD_SYNONYMS.TXT, SOLRCONFIG-DELPOLICY2.XML, XSLT, SOLRCONFIG-NATIVELOCK.XML, BAD-SCHEMA-DUP-FIELD.XML, SOLRCONFIG-NOCACHE.XML, SCHEMA-BM25.XML, ADDFIELDS.UPDATEPROCESSOR.JS, SOLRCONFIG-ALTDIRECTORY.XML, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, COMPOUNDDICTIONARY.TXT, SOLRCONFIG_PERF.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, SCHEMA-NOT-REQUIRED-UNIQUE-KEY.XML, KEEP-2.TXT, BAD-SCHEMA-UNIQUEKEY-IS-COPYFIELD-DEST.XML, SCHEMA12.XML, MAPPING-ISOLATIN1ACCENT.TXT, BAD_SOLRCONFIG.XML, BAD-SCHEMA-EXTERNAL-FILEFIELD.XML]
[junit4:junit4]   2> 1035 T2778 oass.SolrIndexSearcher.<init> Opening Searcher@1b0474b main
[junit4:junit4]   2> 1036 T2778 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1036 T2778 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1036 T2778 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1037 T2778 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1046 T2778 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1109 T2792 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b0474b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1113 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:create cxid:0x2e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1458 T2790 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1460 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:create cxid:0x36 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1480 T2789 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1565 T2778 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1565 T2778 oasc.ZkController.register Register shard - core:collection1 address:http://JenkinsSlave-PC:null/solr shardId:shard1
[junit4:junit4]   2> 1567 T2778 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 1589 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:delete cxid:0x4f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 1592 T2778 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1593 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1601 T2778 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1601 T2778 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://JenkinsSlave-PC:null/solr/collection1/
[junit4:junit4]   2> 1602 T2778 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 1616 T2783 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cadb30280002 type:create cxid:0x59 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1933 T2790 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1948 T2789 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1984 T2778 oasc.ZkController.register We are http://JenkinsSlave-PC:null/solr/collection1/ and leader is http://JenkinsSlave-PC:null/solr/collection1/
[junit4:junit4]   2> 1984 T2778 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 1988 T2778 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1991 T2778 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   1> azt beforeclass
[junit4:junit4]   2> 1997 T2778 oas.SolrTestCaseJ4.setUp ###Starting testBasic
[junit4:junit4]   2> 2000 T2778 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2000 T2778 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> ASYNC  NEW_CORE C190 name=collection1 org.apache.solr.core.SolrCore@108bf1e url=http://JenkinsSlave-PC:null/solr/collection1 node=JenkinsSlave-PC:null_solr
[junit4:junit4]   2> 2001 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=qlkciyopsbgzyvkylsjhchghjrdf&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2017 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {delete=[42]} 0 0
[junit4:junit4]   2> 2024 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[42]} 0 0
[junit4:junit4]   2> 2031 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2031 T2778 C190 Pnull oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _0_Lucene40_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0.fnm, _0_Pulsing40_0.tim, _0_TestBloomFilteredLucene40Postings_0.prx, _0_nrm.cfs, _0_Lucene40_0.prx, _0_Direct_0.frq, _0_Pulsing40_0.prx, _0_Pulsing40_0.frq, _0_Lucene40_0.tip, _0_Lucene40_0.frq, _0_nrm.cfe, _0_Pulsing40_0.tip, segments_2, _0_TestBloomFilteredLucene40Postings_0.frq, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _0.si, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 2032 T2778 C190 Pnull oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 2033 T2778 C190 Pnull oass.SolrIndexSearcher.<init> Opening Searcher@2916a3 main
[junit4:junit4]   2> 2034 T2778 C190 Pnull oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2034 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2035 T2792 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2916a3 main{StandardDirectoryReader(segments_2:3 _0(4.0.0.2):C1)}
[junit4:junit4]   2> 2036 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4:junit4]   2> 2038 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:42+AND+val_s:aa\;bb&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2041 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:42+AND+val_s:"aa;bb"&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2044 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:42+AND+val_s:aa&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2051 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {delete=[42]} 0 0
[junit4:junit4]   2> 2057 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2058 T2778 C190 Pnull oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _0_Lucene40_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0.fnm, _0_Pulsing40_0.tim, _0_TestBloomFilteredLucene40Postings_0.prx, _0_nrm.cfs, _0_Lucene40_0.prx, _0_Direct_0.frq, _0_Pulsing40_0.prx, _0_Pulsing40_0.frq, _0_Lucene40_0.tip, _0_Lucene40_0.frq, _0_nrm.cfe, _0_Pulsing40_0.tip, segments_2, _0_TestBloomFilteredLucene40Postings_0.frq, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _0.si, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 2058 T2778 C190 Pnull oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 2059 T2778 C190 Pnull oass.SolrIndexSearcher.<init> Opening Searcher@1cf30dc main
[junit4:junit4]   2> 2060 T2778 C190 Pnull oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2061 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2061 T2792 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cf30dc main{StandardDirectoryReader(segments_3:5)}
[junit4:junit4]   2> 2063 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4:junit4]   2> 2064 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:42&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2071 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[42]} 0 1
[junit4:junit4]   2> 2076 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[42]} 0 0
[junit4:junit4]   2> 2082 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2084 T2778 C190 Pnull oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_4,generation=4,filenames=[_1_Lucene40_0.tim, _1_Pulsing40_0.tip, _1_Pulsing40_0.tim, _1_TestBloomFilteredLucene40Postings_0.blm, _1.fnm, _1_Lucene40_0.tip, _1_Pulsing40_0.frq, _1_TestBloomFilteredLucene40Postings_0.tip, _1_Pulsing40_0.prx, _1_Direct_0.tim, _1_TestBloomFilteredLucene40Postings_0.tim, _1_Direct_0.tip, _1_nrm.cfs, _1_TestBloomFilteredLucene40Postings_0.prx, _1_Lucene40_0.frq, _1_1.del, _1_Direct_0.prx, _1_TestBloomFilteredLucene40Postings_0.frq, _1_nrm.cfe, _1_Lucene40_0.prx, _1.fdx, _1.fdt, _1.si, segments_4, _1_Direct_0.frq]
[junit4:junit4]   2> 2084 T2778 C190 Pnull oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 2085 T2778 C190 Pnull oass.SolrIndexSearcher.<init> Opening Searcher@62f51c main
[junit4:junit4]   2> 2086 T2778 C190 Pnull oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2086 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2087 T2792 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62f51c main{StandardDirectoryReader(segments_4:7 _1(4.0.0.2):C2/1)}
[junit4:junit4]   2> 2088 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4:junit4]   2> 2090 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:42&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2097 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[42]} 0 1
[junit4:junit4]   2> 2103 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[42]} 0 2
[junit4:junit4]   2> 2108 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2111 T2778 C190 Pnull oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_4,generation=4,filenames=[_1_Lucene40_0.tim, _1_Pulsing40_0.tip, _1_Pulsing40_0.tim, _1_TestBloomFilteredLucene40Postings_0.blm, _1.fnm, _1_Lucene40_0.tip, _1_Pulsing40_0.frq, _1_TestBloomFilteredLucene40Postings_0.tip, _1_Pulsing40_0.prx, _1_Direct_0.tim, _1_TestBloomFilteredLucene40Postings_0.tim, _1_Direct_0.tip, _1_nrm.cfs, _1_TestBloomFilteredLucene40Postings_0.prx, _1_Lucene40_0.frq, _1_1.del, _1_Direct_0.prx, _1_TestBloomFilteredLucene40Postings_0.frq, _1_nrm.cfe, _1_Lucene40_0.prx, _1.fdx, _1.fdt, _1.si, segments_4, _1_Direct_0.frq]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_5,generation=5,filenames=[_2.si, _2_Direct_0.tip, _2_Pulsing40_0.tim, _2_Direct_0.tim, _2_TestBloomFilteredLucene40Postings_0.prx, _2_Pulsing40_0.tip, _2_TestBloomFilteredLucene40Postings_0.blm, _2_Lucene40_0.tip, _2_nrm.cfe, _2_nrm.cfs, _2_Lucene40_0.tim, _2_1.del, _2_Pulsing40_0.prx, _2_TestBloomFilteredLucene40Postings_0.frq, _2_TestBloomFilteredLucene40Postings_0.tim, _2.fdt, _2.fdx, _2_TestBloomFilteredLucene40Postings_0.tip, _2_Pulsing40_0.frq, _2.fnm, _2_Direct_0.prx, _2_Lucene40_0.frq, _2_Lucene40_0.prx, _2_Direct_0.frq, segments_5]
[junit4:junit4]   2> 2111 T2778 C190 Pnull oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 2112 T2778 C190 Pnull oass.SolrIndexSearcher.<init> Opening Searcher@1096020 main
[junit4:junit4]   2> 2113 T2778 C190 Pnull oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2113 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2114 T2792 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1096020 main{StandardDirectoryReader(segments_5:11 _2(4.0.0.2):C2/1)}
[junit4:junit4]   2> 2115 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4:junit4]   2> 2117 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:42&qt=standard&rows=20&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2125 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[101]} 0 1
[junit4:junit4]   2> 2137 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[101]} 0 0
[junit4:junit4]   2> 2143 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[105]} 0 1
[junit4:junit4]   2> 2148 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[102]} 0 0
[junit4:junit4]   2> 2155 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[103]} 0 1
[junit4:junit4]   2> 2162 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {add=[101]} 0 0
[junit4:junit4]   2> 2170 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2175 T2778 C190 Pnull oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_5,generation=5,filenames=[_2.si, _2_Direct_0.tip, _2_Pulsing40_0.tim, _2_Direct_0.tim, _2_TestBloomFilteredLucene40Postings_0.prx, _2_Pulsing40_0.tip, _2_TestBloomFilteredLucene40Postings_0.blm, _2_Lucene40_0.tip, _2_nrm.cfe, _2_nrm.cfs, _2_Lucene40_0.tim, _2_1.del, _2_Pulsing40_0.prx, _2_TestBloomFilteredLucene40Postings_0.frq, _2_TestBloomFilteredLucene40Postings_0.tim, _2.fdt, _2.fdx, _2_TestBloomFilteredLucene40Postings_0.tip, _2_Pulsing40_0.frq, _2.fnm, _2_Direct_0.prx, _2_Lucene40_0.frq, _2_Lucene40_0.prx, _2_Direct_0.frq, segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_6,generation=6,filenames=[_3_Pulsing40_0.frq, _3_nrm.cfe, _2.si, _2_Direct_0.tip, _3_Direct_0.frq, _2_Pulsing40_0.tim, _2_Direct_0.tim, _3_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _2_Pulsing40_0.tip, _2_TestBloomFilteredLucene40Postings_0.blm, _3.fdt, _3.fnm, _3_1.del, _2_Lucene40_0.tip, _2_nrm.cfe, _3_Direct_0.prx, _2_nrm.cfs, _2_Lucene40_0.tim, _3_TestBloomFilteredLucene40Postings_0.frq, _2_1.del, _2_Pulsing40_0.prx, _2_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _3_Direct_0.tip, _3_Pulsing40_0.tip, _2.fdt, _3_TestBloomFilteredLucene40Postings_0.tip, _2.fdx, _3_Pulsing40_0.tim, _2_TestBloomFilteredLucene40Postings_0.tip, _2_Pulsing40_0.frq, _2.fnm, _3.fdx, _3_nrm.cfs, _2_Direct_0.prx, _3_Pulsing40_0.prx, _2_Lucene40_0.frq, _2_Lucene40_0.prx, _3.si, _2_Direct_0.frq, _3_TestBloomFilteredLucene40Postings_0.blm, segments_6, _3_Direct_0.tim]
[junit4:junit4]   2> 2176 T2778 C190 Pnull oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4]   2> 2178 T2778 C190 Pnull oass.SolrIndexSearcher.<init> Opening Searcher@13b91af main
[junit4:junit4]   2> 2178 T2778 C190 Pnull oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2178 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2179 T2792 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13b91af main{StandardDirectoryReader(segments_6:13 _2(4.0.0.2):C2/1 _3(4.0.0.2):C6/2)}
[junit4:junit4]   2> 2180 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {commit=} 0 10
[junit4:junit4]   2> 2182 T2783 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4]   2> 2182 T2782 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> 2182 T2778 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 2907 T2781 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 4252 T2778 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54658 which had sessionid 0x139cadb30280002
[junit4:junit4]   2> 4253 T2788 oaz.ClientCnxn$SendThread.run Unable to read additional data from server sessionid 0x139cadb30280002, likely server has closed socket, closing socket connection and attempting reconnect
[junit4:junit4]   2> 4253 T2780 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> 4256 T2778 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 4353 T2789 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a296da name:ZooKeeperConnection Watcher:127.0.0.1:54649/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 4353 T2789 oascc.ConnectionManager.process zkClient has disconnected
[junit4:junit4]   2> 4551 T2778 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4555 T2793 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 4557 T2793 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:54649
[junit4:junit4]   2> 4561 T2793 oazsp.FileSnap.deserialize Reading snapshot C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicZkTest-1347728125984\zookeeper\server1\data\version-2\snapshot.0
[junit4:junit4]   2> 4566 T2793 oazsp.FileTxnSnapLog.save Snapshotting: 43
[junit4:junit4]   2> 4650 T2778 oasc.ZkTestServer.run start zk server on port:54649
[junit4:junit4]   2> 4945 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={q=id:[100+TO+110]&rows=2147483647} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 4947 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={q=id:[100+TO+111]&rows=1147483647} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 4948 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:[100+TO+110]&qt=standard&rows=20&version=2.2} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 4957 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {delete=[102]} 0 0
[junit4:junit4]   2> 4963 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 4966 T2778 C190 Pnull oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_6,generation=6,filenames=[_3_Pulsing40_0.frq, _3_nrm.cfe, _2.si, _2_Direct_0.tip, _3_Direct_0.frq, _2_Pulsing40_0.tim, _2_Direct_0.tim, _3_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _2_Pulsing40_0.tip, _2_TestBloomFilteredLucene40Postings_0.blm, _3.fdt, _3.fnm, _3_1.del, _2_Lucene40_0.tip, _2_nrm.cfe, _3_Direct_0.prx, _2_nrm.cfs, _2_Lucene40_0.tim, _3_TestBloomFilteredLucene40Postings_0.frq, _2_1.del, _2_Pulsing40_0.prx, _2_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _3_Direct_0.tip, _3_Pulsing40_0.tip, _2.fdt, _3_TestBloomFilteredLucene40Postings_0.tip, _2.fdx, _3_Pulsing40_0.tim, _2_TestBloomFilteredLucene40Postings_0.tip, _2_Pulsing40_0.frq, _2.fnm, _3.fdx, _3_nrm.cfs, _2_Direct_0.prx, _3_Pulsing40_0.prx, _2_Lucene40_0.frq, _2_Lucene40_0.prx, _3.si, _2_Direct_0.frq, _3_TestBloomFilteredLucene40Postings_0.blm, segments_6, _3_Direct_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_7,generation=7,filenames=[_3_Pulsing40_0.frq, _3_nrm.cfe, _2.si, _2_Direct_0.tip, _3_Direct_0.frq, _2_Pulsing40_0.tim, _2_Direct_0.tim, _3_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _2_Pulsing40_0.tip, _2_TestBloomFilteredLucene40Postings_0.blm, _3.fdt, _3.fnm, _2_Lucene40_0.tip, _2_nrm.cfe, _3_Direct_0.prx, _2_nrm.cfs, _2_Lucene40_0.tim, _3_TestBloomFilteredLucene40Postings_0.frq, _2_1.del, _2_Pulsing40_0.prx, _2_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _3_Direct_0.tip, _3_2.del, _3_Pulsing40_0.tip, _2.fdt, _3_TestBloomFilteredLucene40Postings_0.tip, _2.fdx, _3_Pulsing40_0.tim, _2_TestBloomFilteredLucene40Postings_0.tip, _2_Pulsing40_0.frq, _2.fnm, _3.fdx, _3_nrm.cfs, _2_Direct_0.prx, _3_Pulsing40_0.prx, segments_7, _2_Lucene40_0.frq, _2_Lucene40_0.prx, _3.si, _2_Direct_0.frq, _3_TestBloomFilteredLucene40Postings_0.blm, _3_Direct_0.tim]
[junit4:junit4]   2> 4966 T2778 C190 Pnull oasc.SolrDeletionPolicy.updateCommits newest commit = 7
[junit4:junit4]   2> 4967 T2778 C190 Pnull oass.SolrIndexSearcher.<init> Opening Searcher@d60f66 main
[junit4:junit4]   2> 4968 T2778 C190 Pnull oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4968 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 4970 T2792 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d60f66 main{StandardDirectoryReader(segments_7:14 _2(4.0.0.2):C2/1 _3(4.0.0.2):C6/3)}
[junit4:junit4]   2> 4970 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4:junit4]   2> 4972 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:[100+TO+110]&qt=standard&rows=20&version=2.2} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 4979 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {delete=[105]} 0 0
[junit4:junit4]   2> 4985 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 4987 T2778 C190 Pnull oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_7,generation=7,filenames=[_3_Pulsing40_0.frq, _3_nrm.cfe, _2.si, _2_Direct_0.tip, _3_Direct_0.frq, _2_Pulsing40_0.tim, _2_Direct_0.tim, _3_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _2_Pulsing40_0.tip, _2_TestBloomFilteredLucene40Postings_0.blm, _3.fdt, _3.fnm, _2_Lucene40_0.tip, _2_nrm.cfe, _3_Direct_0.prx, _2_nrm.cfs, _2_Lucene40_0.tim, _3_TestBloomFilteredLucene40Postings_0.frq, _2_1.del, _2_Pulsing40_0.prx, _2_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _3_Direct_0.tip, _3_2.del, _3_Pulsing40_0.tip, _2.fdt, _3_TestBloomFilteredLucene40Postings_0.tip, _2.fdx, _3_Pulsing40_0.tim, _2_TestBloomFilteredLucene40Postings_0.tip, _2_Pulsing40_0.frq, _2.fnm, _3.fdx, _3_nrm.cfs, _2_Direct_0.prx, _3_Pulsing40_0.prx, segments_7, _2_Lucene40_0.frq, _2_Lucene40_0.prx, _3.si, _2_Direct_0.frq, _3_TestBloomFilteredLucene40Postings_0.blm, _3_Direct_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_8,generation=8,filenames=[_3_Pulsing40_0.frq, _3_nrm.cfe, _2.si, _2_Direct_0.tip, _3_Direct_0.frq, _2_Pulsing40_0.tim, _2_Direct_0.tim, _3_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _2_Pulsing40_0.tip, _2_TestBloomFilteredLucene40Postings_0.blm, _3.fdt, _3.fnm, _2_Lucene40_0.tip, _2_nrm.cfe, _3_Direct_0.prx, _2_nrm.cfs, _2_Lucene40_0.tim, _3_TestBloomFilteredLucene40Postings_0.frq, _2_1.del, _2_Pulsing40_0.prx, _2_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _3_Direct_0.tip, _3_Pulsing40_0.tip, _2.fdt, _3_TestBloomFilteredLucene40Postings_0.tip, _3_3.del, _2.fdx, _3_Pulsing40_0.tim, _2_TestBloomFilteredLucene40Postings_0.tip, _2_Pulsing40_0.frq, _2.fnm, _3.fdx, _3_nrm.cfs, _2_Direct_0.prx, _3_Pulsing40_0.prx, segments_8, _2_Lucene40_0.frq, _2_Lucene40_0.prx, _3.si, _2_Direct_0.frq, _3_TestBloomFilteredLucene40Postings_0.blm, _3_Direct_0.tim]
[junit4:junit4]   2> 4988 T2778 C190 Pnull oasc.SolrDeletionPolicy.updateCommits newest commit = 8
[junit4:junit4]   2> 4989 T2778 C190 Pnull oass.SolrIndexSearcher.<init> Opening Searcher@1ea5155 main
[junit4:junit4]   2> 4990 T2778 C190 Pnull oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4990 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 4991 T2792 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ea5155 main{StandardDirectoryReader(segments_8:15 _2(4.0.0.2):C2/1 _3(4.0.0.2):C6/4)}
[junit4:junit4]   2> 4992 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4:junit4]   2> 4993 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:[100+TO+110]&qt=standard&rows=20&version=2.2} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 5001 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {deleteByQuery=id:[100 TO 110]} 0 0
[junit4:junit4]   2> 5007 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5010 T2778 C190 Pnull oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_8,generation=8,filenames=[_3_Pulsing40_0.frq, _3_nrm.cfe, _2.si, _2_Direct_0.tip, _3_Direct_0.frq, _2_Pulsing40_0.tim, _2_Direct_0.tim, _3_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _2_Pulsing40_0.tip, _2_TestBloomFilteredLucene40Postings_0.blm, _3.fdt, _3.fnm, _2_Lucene40_0.tip, _2_nrm.cfe, _3_Direct_0.prx, _2_nrm.cfs, _2_Lucene40_0.tim, _3_TestBloomFilteredLucene40Postings_0.frq, _2_1.del, _2_Pulsing40_0.prx, _2_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _3_Direct_0.tip, _3_Pulsing40_0.tip, _2.fdt, _3_TestBloomFilteredLucene40Postings_0.tip, _3_3.del, _2.fdx, _3_Pulsing40_0.tim, _2_TestBloomFilteredLucene40Postings_0.tip, _2_Pulsing40_0.frq, _2.fnm, _3.fdx, _3_nrm.cfs, _2_Direct_0.prx, _3_Pulsing40_0.prx, segments_8, _2_Lucene40_0.frq, _2_Lucene40_0.prx, _3.si, _2_Direct_0.frq, _3_TestBloomFilteredLucene40Postings_0.blm, _3_Direct_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bebd3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1efaf36),segFN=segments_9,generation=9,filenames=[_2.si, _2_Direct_0.tip, _2_Pulsing40_0.tim, _2_Direct_0.tim, _2_TestBloomFilteredLucene40Postings_0.prx, _2_Pulsing40_0.tip, _2_TestBloomFilteredLucene40Postings_0.blm, _2_Lucene40_0.tip, _2_nrm.cfe, _2_nrm.cfs, _2_Lucene40_0.tim, _2_1.del, _2_Pulsing40_0.prx, _2_TestBloomFilteredLucene40Postings_0.frq, _2_TestBloomFilteredLucene40Postings_0.tim, _2.fdt, _2.fdx, _2_TestBloomFilteredLucene40Postings_0.tip, _2_Pulsing40_0.frq, _2.fnm, segments_9, _2_Direct_0.prx, _2_Lucene40_0.frq, _2_Lucene40_0.prx, _2_Direct_0.frq]
[junit4:junit4]   2> 5010 T2778 C190 Pnull oasc.SolrDeletionPolicy.updateCommits newest commit = 9
[junit4:junit4]   2> 5011 T2778 C190 Pnull oass.SolrIndexSearcher.<init> Opening Searcher@18b482a main
[junit4:junit4]   2> 5011 T2778 C190 Pnull oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5012 T2778 C190 Pnull oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5013 T2792 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18b482a main{StandardDirectoryReader(segments_9:17 _2(4.0.0.2):C2/1)}
[junit4:junit4]   2> 5014 T2778 C190 Pnull UPDATE [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4:junit4]   2> 5016 T2778 C190 Pnull REQ [collection1] webapp=null path=null params={start=0&q=id:[100+TO+110]&qt=standard&rows=20&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 5979 T2788 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:54649
[junit4:junit4]   2> 5980 T2788 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54649, initiating session
[junit4:junit4]   2> 5980 T2794 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54661
[junit4:junit4]   2> 5982 T2794 oazs.NIOServerCnxn.readConnectRequest Client attempting to renew session 0x139cadb30280002 at /127.0.0.1:54661
[junit4:junit4]   2> 5983 T2794 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cadb30280002 with negotiated timeout 10000 for client /127.0.0.1:54661
[junit4:junit4]   2> 5983 T2788 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54649, sessionid = 0x139cadb30280002, negotiated timeout = 10000
[junit4:junit4]   2> 5984 T2789 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a296da name:ZooKeeperConnection Watcher:127.0.0.1:54649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5988 T2796 oazsp.FileTxnLog.append Creating new log file: log.44
[junit4:junit4]   2> 5993 T2778 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 5994 T2778 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5996 T2778 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5996 T2778 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5997 T2790 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6000 T2778 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 6005 T2778 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6008 T2778 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 6016 T2789 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6062 T2778 oasc.SolrException.log Ignoring exception matching SAXParseException
[junit4:junit4]   2> 6063 T2778 oasc.CoreContainer.reload SEVERE Unable to reload core: collection1 Ignoring exception matching SAXParseException
[junit4:junit4]   2> 6063 T2778 oas.SolrTestCaseJ4.tearDown ###Ending testBasic
[junit4:junit4]   2> 6064 T2797 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4]   2> 6065 T2796 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> 6065 T2778 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 6068 T2778 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54661 which had sessionid 0x139cadb30280002
[junit4:junit4]   2> 6068 T2788 oaz.ClientCnxn$SendThread.run Unable to read additional data from server sessionid 0x139cadb30280002, likely server has closed socket, closing socket connection and attempting reconnect
[junit4:junit4]   2> 6068 T2794 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> 6070 T2778 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 6167 T2789 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a296da name:ZooKeeperConnection Watcher:127.0.0.1:54649/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 6167 T2789 oascc.ConnectionManager.process zkClient has disconnected
[junit4:junit4]   2> 6996 T2795 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 7723 T2788 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:54649
[junit4:junit4]   2> 8030 T2778 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 8030 T2778 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=8155157
[junit4:junit4]   2> 8030 T2778 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@108bf1e
[junit4:junit4]   2> 8034 T2778 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 8034 T2778 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=8,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=11,cumulative_deletesById=4,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 8034 T2778 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 8037 T2778 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 8038 T2790 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88324710465011714-JenkinsSlave-PC:null_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   2> 8803 T2778 oaz.ZooKeeper.close Session: 0x139cadb30280002 closed
[junit4:junit4]   2> 8803 T2789 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {timestamp=Pulsing40(freqCutoff=17 minBlockSize=47 maxBlockSize=145), range_facet_l=Pulsing40(freqCutoff=17 minBlockSize=47 maxBlockSize=145), id=PostingsFormat(name=Direct), range_facet_sl=PostingsFormat(name=Direct), range_facet_si=Pulsing40(freqCutoff=17 minBlockSize=47 maxBlockSize=145), val_s=Lucene40(minBlockSize=47 maxBlockSize=145), multiDefault=Pulsing40(freqCutoff=17 minBlockSize=47 maxBlockSize=145), intDefault=PostingsFormat(name=TestBloomFilteredLucene40Postings)}, sim=DefaultSimilarity, locale=hr_HR, timezone=Etc/GMT+2
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_35 (32-bit)/cpus=2,threads=1,free=53873976,total=150171648
[junit4:junit4]   2> NOTE: All tests run in this JVM: [UniqFieldsUpdateProcessorFactoryTest, TestGroupingSearch, SyncSliceTest, TestValueSourceCache, FieldMutatingUpdateProcessorTest, PolyFieldTest, TestWriterPerf, TestDistributedGrouping, TestCharFilters, DistributedTermsComponentTest, TestMultiCoreConfBootstrap, TestSearchPerf, XsltUpdateRequestHandlerTest, TestRecovery, TestArbitraryIndexDir, MoreLikeThisHandlerTest, SortByFunctionTest, TestSystemIdResolver, FileUtilsTest, JSONWriterTest, TestFastWriter, DOMUtilTest, StandardRequestHandlerTest, NumericFieldsTest, SuggesterFSTTest, TestSolrJ, TestMergePolicyConfig, PluginInfoTest, SOLR749Test, TestRangeQuery, TestStressVersions, TestCSVLoader, SolrRequestParserTest, BinaryUpdateRequestHandlerTest, TestLuceneMatchVersion, TestJoin, ZkSolrClientTest, LegacyHTMLStripCharFilterTest, TestRTGBase, UpdateRequestProcessorFactoryTest, TestExtendedDismaxParser, TestCSVResponseWriter, QueryParsingTest, CSVRequestHandlerTest, BadIndexSchemaTest, SolrCmdDistributorTest, OverseerTest, IndexSchemaTest, TestStressReorder, XmlUpdateRequestHandlerTest, TestSolrCoreProperties, TestQueryUtils, UpdateParamsTest, TestJmxMonitoredMap, RAMDirectoryFactoryTest, BasicDistributedZkTest, HighlighterConfigTest, PingRequestHandlerTest, TestFastOutputStream, DistributedQueryElevationComponentTest, TestLFUCache, FieldAnalysisRequestHandlerTest, QueryEqualityTest, DateMathParserTest, ConvertedLegacyTest, DateFieldTest, RequestHandlersTest, FastVectorHighlighterTest, SpellPossibilityIteratorTest, TestSolrDeletionPolicy1, TestRandomFaceting, SpatialFilterTest, TermsComponentTest, AutoCommitTest, TestCollationField, TestHashPartitioner, JsonLoaderTest, TestLMDirichletSimilarityFactory, DirectUpdateHandlerOptimizeTest, TestQuerySenderListener, SolrCoreCheckLockOnStartupTest, SuggesterWFSTTest, URLClassifyProcessorTest, TestBadConfig, WordBreakSolrSpellCheckerTest, TestRealTimeGet, DirectSolrConnectionTest, TestReplicationHandler, MultiTermTest, TestSuggestSpellingConverter, TestDefaultSimilarityFactory, TestSolrXMLSerializer, PrimUtilsTest, MBeansHandlerTest, BadComponentTest, PrimitiveFieldTypeTest, SolrPluginUtilsTest, TestIndexingPerformance, TestDistributedSearch, ResourceLoaderTest, TestSurroundQueryParser, SolrIndexConfigTest, TestCollationKeyRangeQueries, FullSolrCloudDistribCmdsTest, CurrencyFieldTest, TestPluginEnable, RecoveryZkTest, SpellCheckComponentTest, LeaderElectionIntegrationTest, LeaderElectionTest, DistributedSpellCheckComponentTest, ZkControllerTest, SpellCheckCollatorTest, SoftAutoCommitTest, BasicZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicZkTest -Dtests.seed=FCA6EA40823DC876 -Dtests.slow=true -Dtests.locale=hr_HR -Dtests.timezone=Etc/GMT+2 -Dtests.file.encoding=Cp1252
[junit4:junit4] ERROR   0.00s | BasicZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: junit.framework.AssertionFailedError: Clean up static fields (in @AfterClass?), your test seems to hang on to approximately 20,468,480 bytes (threshold is 10,485,760):
[junit4:junit4]    >   - 20,467,272 bytes, protected static org.apache.solr.cloud.ZkTestServer org.apache.solr.cloud.AbstractZkTestCase.zkServer
[junit4:junit4]    >   - 304 bytes, protected static java.lang.String org.apache.solr.cloud.AbstractZkTestCase.zkDir
[junit4:junit4]    >   - 256 bytes, public static org.junit.rules.TestRule org.apache.solr.SolrTestCaseJ4.solrClassRules
[junit4:junit4]    >   - 240 bytes, public static java.io.File org.apache.solr.cloud.AbstractZkTestCase.SOLRHOME
[junit4:junit4]    >   - 224 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.testSolrHome
[junit4:junit4]    >   - 120 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp
[junit4:junit4]    >   - 64 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([FCA6EA40823DC876]:0)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StaticFieldsInvariantRule$1.afterAlways(StaticFieldsInvariantRule.java:119)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4] Completed in 9.35s, 1 test, 1 failure <<< FAILURES!

[...truncated 323 lines...]
BUILD FAILED
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\build.xml:32: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:146: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\module-build.xml:63: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1133: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:810: There were test failures: 229 suites, 927 tests, 1 suite-level error, 11 ignored (6 assumptions)

Total time: 36 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.6.0_35 -server -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message