You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/01/20 16:50:29 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.7.0_10) - Build # 3857
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/3857/
Java: 32bit/jdk1.7.0_10 -client -XX:+UseG1GC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest
Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=523, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=523, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
at __randomizedtesting.SeedInfo.seed([63E6FA91B07D4C69]:0)
at java.lang.Thread.getStackTrace(Thread.java:1567)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:517)
at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:983)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:875)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:867)
at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
at org.apache.solr.handler.SnapPuller.downloadIndexFiles(SnapPuller.java:731)
at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:395)
at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:274)
at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:153)
at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:409)
at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:223)
Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.RAMDirectory@fc29e0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@415726)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:722)
Build Log:
[...truncated 8747 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4] 2> 0 T273 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /h/jy
[junit4:junit4] 2> 3 T273 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1358696339885
[junit4:junit4] 2> 6 T273 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 9 T274 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 28 T274 oaz.Environment.logEnv Server environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 28 T274 oaz.Environment.logEnv Server environment:host.name=serv1
[junit4:junit4] 2> 28 T274 oaz.Environment.logEnv Server environment:java.version=1.7.0_10
[junit4:junit4] 2> 28 T274 oaz.Environment.logEnv Server environment:java.vendor=Oracle Corporation
[junit4:junit4] 2> 28 T274 oaz.Environment.logEnv Server environment:java.home=/var/lib/jenkins/tools/java/32bit/jdk1.7.0_10/jre
[junit4:junit4] 2> 28 T274 oaz.Environment.logEnv Server environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/common/lucene-analyzers-common-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/highlighter/lucene-highlighter-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/memory/lucene-memory-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/misc/lucene-misc-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/spatial/lucene-spatial-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/suggest/lucene-suggest-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/grouping/lucene-grouping-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/queries/lucene-queries-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/queryparser/lucene-queryparser-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/cglib-nodep-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/easymock-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/guava-13.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/objenesis-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/zookeeper-3.4.5.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-continuation-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-deploy-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-http-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-io-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-jmx-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-security-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-server-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-servlet-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-util-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-webapp-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-xml-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.8.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.2.0.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/java/32bit/jdk1.7.0_10/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.8.jar
[junit4:junit4] 2> 29 T274 oaz.Environment.logEnv Server environment:java.library.path=/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit4:junit4] 2> 30 T274 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4] 2> 30 T274 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4] 2> 30 T274 oaz.Environment.logEnv Server environment:os.name=Linux
[junit4:junit4] 2> 30 T274 oaz.Environment.logEnv Server environment:os.arch=i386
[junit4:junit4] 2> 30 T274 oaz.Environment.logEnv Server environment:os.version=3.2.0-36-generic
[junit4:junit4] 2> 30 T274 oaz.Environment.logEnv Server environment:user.name=jenkins
[junit4:junit4] 2> 30 T274 oaz.Environment.logEnv Server environment:user.home=/var/lib/jenkins
[junit4:junit4] 2> 30 T274 oaz.Environment.logEnv Server environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 42 T274 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 42 T274 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 42 T274 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 44 T274 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 67 T274 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4] 2> 108 T273 oasc.ZkTestServer.run start zk server on port:54057
[junit4:junit4] 2> 128 T273 oaz.Environment.logEnv Client environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 129 T273 oaz.Environment.logEnv Client environment:host.name=serv1
[junit4:junit4] 2> 129 T273 oaz.Environment.logEnv Client environment:java.version=1.7.0_10
[junit4:junit4] 2> 129 T273 oaz.Environment.logEnv Client environment:java.vendor=Oracle Corporation
[junit4:junit4] 2> 130 T273 oaz.Environment.logEnv Client environment:java.home=/var/lib/jenkins/tools/java/32bit/jdk1.7.0_10/jre
[junit4:junit4] 2> 130 T273 oaz.Environment.logEnv Client environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/common/lucene-analyzers-common-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/highlighter/lucene-highlighter-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/memory/lucene-memory-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/misc/lucene-misc-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/spatial/lucene-spatial-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/suggest/lucene-suggest-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/grouping/lucene-grouping-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/queries/lucene-queries-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/queryparser/lucene-queryparser-4.2-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/cglib-nodep-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/easymock-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/guava-13.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/objenesis-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/zookeeper-3.4.5.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-continuation-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-deploy-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-http-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-io-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-jmx-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-security-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-server-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-servlet-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-util-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-webapp-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-xml-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.8.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.2.0.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/java/32bit/jdk1.7.0_10/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.8.jar
[junit4:junit4] 2> 131 T273 oaz.Environment.logEnv Client environment:java.library.path=/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit4:junit4] 2> 131 T273 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4] 2> 132 T273 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4] 2> 132 T273 oaz.Environment.logEnv Client environment:os.name=Linux
[junit4:junit4] 2> 132 T273 oaz.Environment.logEnv Client environment:os.arch=i386
[junit4:junit4] 2> 132 T273 oaz.Environment.logEnv Client environment:os.version=3.2.0-36-generic
[junit4:junit4] 2> 133 T273 oaz.Environment.logEnv Client environment:user.name=jenkins
[junit4:junit4] 2> 133 T273 oaz.Environment.logEnv Client environment:user.home=/var/lib/jenkins
[junit4:junit4] 2> 133 T273 oaz.Environment.logEnv Client environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 135 T273 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54057 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@414022
[junit4:junit4] 2> 154 T273 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 157 T279 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:54057. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 158 T279 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:54057, initiating session
[junit4:junit4] 2> 158 T275 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:39272
[junit4:junit4] 2> 166 T275 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:39272
[junit4:junit4] 2> 169 T277 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 291 T277 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c589cd9f70000 with negotiated timeout 10000 for client /127.0.0.1:39272
[junit4:junit4] 2> 291 T279 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:54057, sessionid = 0x13c589cd9f70000, negotiated timeout = 10000
[junit4:junit4] 2> 295 T280 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@414022 name:ZooKeeperConnection Watcher:127.0.0.1:54057 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 296 T273 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 297 T273 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 323 T278 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c589cd9f70000
[junit4:junit4] 2> 323 T280 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 323 T273 oaz.ZooKeeper.close Session: 0x13c589cd9f70000 closed
[junit4:junit4] 2> 324 T275 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:39272 which had sessionid 0x13c589cd9f70000
[junit4:junit4] 2> 324 T273 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54057/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b22d38
[junit4:junit4] 2> 325 T281 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:54057. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 325 T273 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 325 T281 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:54057, initiating session
[junit4:junit4] 2> 325 T275 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:39273
[junit4:junit4] 2> 326 T275 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:39273
[junit4:junit4] 2> 327 T277 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c589cd9f70001 with negotiated timeout 10000 for client /127.0.0.1:39273
[junit4:junit4] 2> 327 T281 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:54057, sessionid = 0x13c589cd9f70001, negotiated timeout = 10000
[junit4:junit4] 2> 327 T282 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b22d38 name:ZooKeeperConnection Watcher:127.0.0.1:54057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 327 T273 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 328 T273 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 331 T273 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 333 T273 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 336 T273 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 339 T273 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 352 T273 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 361 T273 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4] 2> 362 T273 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 365 T273 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4] 2> 365 T273 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 368 T273 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4] 2> 369 T273 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 371 T273 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4] 2> 372 T273 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 374 T273 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 374 T273 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 377 T273 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 378 T273 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 380 T273 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 381 T273 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 384 T273 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4] 2> 384 T273 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 388 T278 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c589cd9f70001
[junit4:junit4] 2> 389 T282 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 389 T275 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:39273 which had sessionid 0x13c589cd9f70001
[junit4:junit4] 2> 389 T273 oaz.ZooKeeper.close Session: 0x13c589cd9f70001 closed
[junit4:junit4] 2> 453 T273 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 457 T273 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50017
[junit4:junit4] 2> 458 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 458 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 459 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1358696340272
[junit4:junit4] 2> 459 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1358696340272/solr.xml
[junit4:junit4] 2> 459 T273 oasc.CoreContainer.<init> New CoreContainer 32423949
[junit4:junit4] 2> 460 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1358696340272/'
[junit4:junit4] 2> 460 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1358696340272/'
[junit4:junit4] 2> 477 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 478 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 478 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 478 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 479 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 479 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 479 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 480 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 480 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 480 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 487 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 499 T273 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54057/solr
[junit4:junit4] 2> 507 T273 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 507 T273 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54057 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@12f5cc1
[junit4:junit4] 2> 508 T273 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 508 T292 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:54057. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 509 T292 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:54057, initiating session
[junit4:junit4] 2> 509 T275 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:39274
[junit4:junit4] 2> 510 T275 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:39274
[junit4:junit4] 2> 510 T277 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c589cd9f70002 with negotiated timeout 20000 for client /127.0.0.1:39274
[junit4:junit4] 2> 510 T292 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:54057, sessionid = 0x13c589cd9f70002, negotiated timeout = 20000
[junit4:junit4] 2> 511 T293 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12f5cc1 name:ZooKeeperConnection Watcher:127.0.0.1:54057 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 511 T273 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 512 T278 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c589cd9f70002
[junit4:junit4] 2> 513 T293 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 513 T275 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:39274 which had sessionid 0x13c589cd9f70002
[junit4:junit4] 2> 513 T273 oaz.ZooKeeper.close Session: 0x13c589cd9f70002 closed
[junit4:junit4] 2> 514 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 518 T273 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54057/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@16e8311
[junit4:junit4] 2> 519 T273 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 519 T294 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:54057. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 520 T294 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:54057, initiating session
[junit4:junit4] 2> 520 T275 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:39275
[junit4:junit4] 2> 520 T275 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:39275
[junit4:junit4] 2> 521 T277 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c589cd9f70003 with negotiated timeout 20000 for client /127.0.0.1:39275
[junit4:junit4] 2> 521 T294 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:54057, sessionid = 0x13c589cd9f70003, negotiated timeout = 20000
[junit4:junit4] 2> 521 T295 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16e8311 name:ZooKeeperConnection Watcher:127.0.0.1:54057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 521 T273 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 527 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 531 T273 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 532 T273 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50017_h%2Fjy
[junit4:junit4] 2> 534 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:delete cxid:0x7 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:50017_h%2Fjy Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:50017_h%2Fjy
[junit4:junit4] 2> 535 T273 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50017_h%2Fjy
[junit4:junit4] 2> 538 T273 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 548 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:delete cxid:0x15 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4] 2> 549 T273 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 551 T273 oasc.Overseer.start Overseer (id=89043523335553027-127.0.0.1:50017_h%2Fjy-n_0000000000) starting
[junit4:junit4] 2> 552 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:create cxid:0x1a zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 559 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:create cxid:0x1b zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 561 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:create cxid:0x1c zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 564 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:create cxid:0x1d zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 564 T297 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4] 2> 565 T273 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 567 T273 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 571 T296 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 579 T298 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1358696340272/collection1
[junit4:junit4] 2> 580 T298 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4] 2> 581 T298 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 581 T298 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4] 2> 582 T298 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1358696340272/collection1/'
[junit4:junit4] 2> 583 T298 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1358696340272/collection1/lib/README' to classloader
[junit4:junit4] 2> 583 T298 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1358696340272/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 620 T298 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4] 2> 666 T298 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 667 T298 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 672 T298 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 1098 T298 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 1106 T298 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 1111 T298 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 1133 T298 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1138 T298 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1145 T298 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1147 T298 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1150 T298 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1358696340272/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1358696339885/control/data/
[junit4:junit4] 2> 1150 T298 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8314f2
[junit4:junit4] 2> 1151 T298 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 1152 T298 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/control/data forceNew:false
[junit4:junit4] 2> 1152 T298 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/control/data
[junit4:junit4] 2> 1153 T298 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1358696339885/control/data/index/
[junit4:junit4] 2> 1153 T298 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1358696339885/control/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 1154 T298 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/control/data/index forceNew:false
[junit4:junit4] 2> 1157 T298 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1965f39 lockFactory=org.apache.lucene.store.NativeFSLockFactory@408e9d)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 1157 T298 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 1158 T298 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/control/data/index
[junit4:junit4] 2> 1159 T298 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 1161 T298 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 1161 T298 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 1162 T298 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 1162 T298 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 1163 T298 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 1164 T298 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 1164 T298 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 1165 T298 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 1166 T298 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 1167 T298 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 1167 T298 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 1168 T298 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 1169 T298 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 1169 T298 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 1169 T298 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 1170 T298 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 1171 T298 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1172 T298 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1172 T298 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1173 T298 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1174 T298 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1174 T298 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1175 T298 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1176 T298 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1177 T298 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 1177 T298 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 1182 T298 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 1183 T298 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 1185 T298 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 1186 T298 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/control/data
[junit4:junit4] 2> 1187 T298 oass.SolrIndexSearcher.<init> Opening Searcher@1810a0 main
[junit4:junit4] 2> 1187 T298 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 1188 T298 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 1188 T298 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 1192 T298 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 1230 T299 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1810a0 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 1238 T298 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 1238 T298 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 1239 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:create cxid:0x50 zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4] 2> 2075 T296 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 2076 T296 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:50017_h%2Fjy",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50017/h/jy"}
[junit4:junit4] 2> 2077 T296 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4] 2> 2078 T296 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 2079 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:create cxid:0x58 zxid:0x2c txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4] 2> 2089 T295 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 2243 T298 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 2244 T298 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50017/h/jy collection:control_collection shard:shard1
[junit4:junit4] 2> 2248 T298 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4] 2> 2255 T298 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4] 2> 2262 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:delete cxid:0x72 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4] 2> 2263 T298 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 2263 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 2265 T298 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 2265 T298 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 2267 T298 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50017/h/jy/collection1/
[junit4:junit4] 2> 2267 T298 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 2268 T298 oasc.SyncStrategy.syncToMe http://127.0.0.1:50017/h/jy/collection1/ has no replicas
[junit4:junit4] 2> 2268 T298 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50017/h/jy/collection1/
[junit4:junit4] 2> 2268 T298 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4] 2> 2272 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3593 T296 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3601 T295 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 3626 T298 oasc.ZkController.register We are http://127.0.0.1:50017/h/jy/collection1/ and leader is http://127.0.0.1:50017/h/jy/collection1/
[junit4:junit4] 2> 3627 T298 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50017/h/jy
[junit4:junit4] 2> 3627 T298 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 3627 T298 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 3627 T298 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 3630 T298 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3631 T273 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 3631 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 3631 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 3635 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 3639 T273 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54057/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7623ab
[junit4:junit4] 2> 3639 T273 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3640 T300 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:54057. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3640 T300 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:54057, initiating session
[junit4:junit4] 2> 3640 T275 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:39276
[junit4:junit4] 2> 3640 T275 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:39276
[junit4:junit4] 2> 3641 T277 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c589cd9f70004 with negotiated timeout 10000 for client /127.0.0.1:39276
[junit4:junit4] 2> 3642 T300 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:54057, sessionid = 0x13c589cd9f70004, negotiated timeout = 10000
[junit4:junit4] 2> 3642 T301 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7623ab name:ZooKeeperConnection Watcher:127.0.0.1:54057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3642 T273 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3643 T273 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 3645 T273 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 3709 T273 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 3711 T273 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60509
[junit4:junit4] 2> 3712 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 3712 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 3713 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1358696343526
[junit4:junit4] 2> 3713 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1358696343526/solr.xml
[junit4:junit4] 2> 3713 T273 oasc.CoreContainer.<init> New CoreContainer 15292796
[junit4:junit4] 2> 3714 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1358696343526/'
[junit4:junit4] 2> 3714 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1358696343526/'
[junit4:junit4] 2> 3732 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 3732 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 3732 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 3733 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 3733 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 3733 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 3734 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 3734 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 3734 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 3735 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 3742 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 3756 T273 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54057/solr
[junit4:junit4] 2> 3756 T273 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 3757 T273 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54057 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1a3e07c
[junit4:junit4] 2> 3758 T311 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:54057. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3757 T273 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3758 T311 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:54057, initiating session
[junit4:junit4] 2> 3761 T275 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:39277
[junit4:junit4] 2> 3761 T275 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:39277
[junit4:junit4] 2> 3762 T277 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c589cd9f70005 with negotiated timeout 20000 for client /127.0.0.1:39277
[junit4:junit4] 2> 3762 T311 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:54057, sessionid = 0x13c589cd9f70005, negotiated timeout = 20000
[junit4:junit4] 2> 3762 T312 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a3e07c name:ZooKeeperConnection Watcher:127.0.0.1:54057 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3762 T273 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3763 T278 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c589cd9f70005
[junit4:junit4] 2> 3764 T275 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:39277 which had sessionid 0x13c589cd9f70005
[junit4:junit4] 2> 3764 T273 oaz.ZooKeeper.close Session: 0x13c589cd9f70005 closed
[junit4:junit4] 2> 3764 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 3764 T312 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 3767 T273 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54057/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@5b967
[junit4:junit4] 2> 3768 T313 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:54057. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3768 T273 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3768 T313 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:54057, initiating session
[junit4:junit4] 2> 3769 T275 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:39278
[junit4:junit4] 2> 3769 T275 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:39278
[junit4:junit4] 2> 3771 T277 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c589cd9f70006 with negotiated timeout 20000 for client /127.0.0.1:39278
[junit4:junit4] 2> 3771 T313 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:54057, sessionid = 0x13c589cd9f70006, negotiated timeout = 20000
[junit4:junit4] 2> 3771 T314 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5b967 name:ZooKeeperConnection Watcher:127.0.0.1:54057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3771 T273 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3772 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3773 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3774 T273 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60509_h%2Fjy
[junit4:junit4] 2> 3775 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70006 type:delete cxid:0x4 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60509_h%2Fjy Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60509_h%2Fjy
[junit4:junit4] 2> 3775 T273 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60509_h%2Fjy
[junit4:junit4] 2> 3778 T295 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 3778 T301 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 3778 T295 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 3786 T273 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 3795 T315 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1358696343526/collection1
[junit4:junit4] 2> 3796 T315 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 3797 T315 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 3797 T315 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 3798 T315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1358696343526/collection1/'
[junit4:junit4] 2> 3798 T315 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1358696343526/collection1/lib/README' to classloader
[junit4:junit4] 2> 3799 T315 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1358696343526/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 3835 T315 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4] 2> 3886 T315 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 3887 T315 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 3893 T315 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 4348 T315 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 4354 T315 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 4358 T315 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 4371 T315 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 4375 T315 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 4378 T315 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 4379 T315 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 4381 T315 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1358696343526/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty1/
[junit4:junit4] 2> 4381 T315 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8314f2
[junit4:junit4] 2> 4381 T315 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 4382 T315 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty1 forceNew:false
[junit4:junit4] 2> 4382 T315 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty1
[junit4:junit4] 2> 4382 T315 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty1/index/
[junit4:junit4] 2> 4383 T315 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 4383 T315 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty1/index forceNew:false
[junit4:junit4] 2> 4385 T315 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@13b05f5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16b5de)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 4385 T315 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 4385 T315 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty1/index
[junit4:junit4] 2> 4386 T315 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 4387 T315 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 4388 T315 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 4388 T315 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 4388 T315 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 4388 T315 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 4389 T315 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 4389 T315 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 4390 T315 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 4390 T315 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 4391 T315 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 4391 T315 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 4392 T315 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 4392 T315 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 4392 T315 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 4393 T315 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 4393 T315 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 4394 T315 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4394 T315 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4395 T315 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4395 T315 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4396 T315 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4396 T315 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4397 T315 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4397 T315 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4398 T315 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 4398 T315 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 4401 T315 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 4403 T315 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 4405 T315 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 4407 T315 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty1
[junit4:junit4] 2> 4408 T315 oass.SolrIndexSearcher.<init> Opening Searcher@353909 main
[junit4:junit4] 2> 4409 T315 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 4409 T315 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 4410 T315 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 4415 T315 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 4440 T316 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@353909 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 4446 T315 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 4446 T315 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 5106 T296 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 5107 T296 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:50017_h%2Fjy",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50017/h/jy"}
[junit4:junit4] 2> 5111 T296 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:60509_h%2Fjy",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:60509/h/jy"}
[junit4:junit4] 2> 5111 T296 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4] 2> 5112 T296 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 5328 T301 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5328 T295 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5328 T314 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5447 T315 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 5447 T315 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60509/h/jy collection:collection1 shard:shard1
[junit4:junit4] 2> 5448 T315 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4] 2> 5453 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70006 type:delete cxid:0x43 zxid:0x5a txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4] 2> 5454 T315 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 5454 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70006 type:create cxid:0x44 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 5456 T315 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 5456 T315 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 5457 T315 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60509/h/jy/collection1/
[junit4:junit4] 2> 5457 T315 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 5457 T315 oasc.SyncStrategy.syncToMe http://127.0.0.1:60509/h/jy/collection1/ has no replicas
[junit4:junit4] 2> 5457 T315 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60509/h/jy/collection1/
[junit4:junit4] 2> 5458 T315 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 5461 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70006 type:create cxid:0x4e zxid:0x5f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 6835 T296 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 6844 T314 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 6844 T301 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 6844 T295 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 6865 T315 oasc.ZkController.register We are http://127.0.0.1:60509/h/jy/collection1/ and leader is http://127.0.0.1:60509/h/jy/collection1/
[junit4:junit4] 2> 6865 T315 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60509/h/jy
[junit4:junit4] 2> 6865 T315 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 6866 T315 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 6866 T315 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 6918 T315 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 6919 T273 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 6919 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 6920 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 6972 T273 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 6974 T273 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:39136
[junit4:junit4] 2> 6975 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 6975 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 6975 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1358696346803
[junit4:junit4] 2> 6976 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1358696346803/solr.xml
[junit4:junit4] 2> 6976 T273 oasc.CoreContainer.<init> New CoreContainer 19390659
[junit4:junit4] 2> 6976 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1358696346803/'
[junit4:junit4] 2> 6977 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1358696346803/'
[junit4:junit4] 2> 6992 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 6992 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 6993 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 6993 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 6993 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 6993 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 6994 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 6994 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 6994 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 6995 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 7001 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 7013 T273 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54057/solr
[junit4:junit4] 2> 7013 T273 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 7014 T273 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54057 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1d52136
[junit4:junit4] 2> 7014 T273 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 7015 T326 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:54057. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 7015 T326 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:54057, initiating session
[junit4:junit4] 2> 7015 T275 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:39286
[junit4:junit4] 2> 7016 T275 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:39286
[junit4:junit4] 2> 7017 T277 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c589cd9f70007 with negotiated timeout 20000 for client /127.0.0.1:39286
[junit4:junit4] 2> 7017 T326 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:54057, sessionid = 0x13c589cd9f70007, negotiated timeout = 20000
[junit4:junit4] 2> 7018 T327 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d52136 name:ZooKeeperConnection Watcher:127.0.0.1:54057 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 7018 T273 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 7019 T278 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c589cd9f70007
[junit4:junit4] 2> 7019 T275 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:39286 which had sessionid 0x13c589cd9f70007
[junit4:junit4] 2> 7020 T327 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 7019 T273 oaz.ZooKeeper.close Session: 0x13c589cd9f70007 closed
[junit4:junit4] 2> 7020 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 7023 T273 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54057/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b80dab
[junit4:junit4] 2> 7023 T273 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 7024 T328 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:54057. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 7024 T328 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:54057, initiating session
[junit4:junit4] 2> 7024 T275 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:39287
[junit4:junit4] 2> 7025 T275 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:39287
[junit4:junit4] 2> 7025 T277 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c589cd9f70008 with negotiated timeout 20000 for client /127.0.0.1:39287
[junit4:junit4] 2> 7025 T328 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:54057, sessionid = 0x13c589cd9f70008, negotiated timeout = 20000
[junit4:junit4] 2> 7026 T329 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b80dab name:ZooKeeperConnection Watcher:127.0.0.1:54057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 7026 T273 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 7027 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70008 type:create cxid:0x1 zxid:0x6c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 7027 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70008 type:create cxid:0x2 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 7028 T273 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39136_h%2Fjy
[junit4:junit4] 2> 7028 T278 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c589cd9f70008 type:delete cxid:0x4 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:39136_h%2Fjy Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:39136_h%2Fjy
[junit4:junit4] 2> 7029 T273 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:39136_h%2Fjy
[junit4:junit4] 2> 7031 T301 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 7031 T314 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 7031 T295 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 7031 T301 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 7031 T295 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 7031 T314 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 7033 T273 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 7038 T330 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1358696346803/collection1
[junit4:junit4] 2> 7038 T330 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 7039 T330 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 7039 T330 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 7040 T330 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1358696346803/collection1/'
[junit4:junit4] 2> 7041 T330 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1358696346803/collection1/lib/README' to classloader
[junit4:junit4] 2> 7041 T330 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1358696346803/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 7072 T330 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4] 2> 7122 T330 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 7123 T330 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 7127 T330 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 7536 T330 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 7542 T330 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 7545 T330 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 7555 T330 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 7558 T330 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 7561 T330 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 7562 T330 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 7563 T330 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1358696346803/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2/
[junit4:junit4] 2> 7564 T330 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8314f2
[junit4:junit4] 2> 7564 T330 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 7565 T330 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2 forceNew:false
[junit4:junit4] 2> 7565 T330 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2
[junit4:junit4] 2> 7565 T330 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2/index/
[junit4:junit4] 2> 7565 T330 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 7566 T330 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2/index forceNew:false
[junit4:junit4] 2> 7567 T330 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@fe7c4c lockFactory=org.apache.lucene.store.NativeFSLockFactory@14a20a3)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 7567 T330 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 7567 T330 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2/index
[junit4:junit4] 2> 7568 T330 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 7569 T330 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 7569 T330 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 7569 T330 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 7570 T330 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 7570 T330 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 7570 T330 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 7570 T330 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 7571 T330 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 7571 T330 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 7572 T330 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 7572 T330 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 7573 T330 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 7573 T330 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 7573 T330 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 7573 T330 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 7574 T330 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 7574 T330 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7575 T330 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7575 T330 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7576 T330 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7576 T330 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7577 T330 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7577 T330 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7577 T330 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7578 T330 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 7578 T330 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 7594 T330 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 7596 T330 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 7597 T330 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 7599 T330 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2
[junit4:junit4] 2> 7599 T330 oass.SolrIndexSearcher.<init> Opening Searcher@1493b48 main
[junit4:junit4] 2> 7600 T330 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 7600 T330 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 7601 T330 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 7604 T330 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 7623 T331 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1493b48 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 7627 T330 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 7627 T330 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 8352 T296 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8353 T296 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:60509_h%2Fjy",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:60509/h/jy"}
[junit4:junit4] 2> 8357 T296 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:39136_h%2Fjy",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:39136/h/jy"}
[junit4:junit4] 2> 8357 T296 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4] 2> 8357 T296 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 8361 T329 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 8361 T301 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 8361 T295 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 8361 T314 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 8628 T330 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 8628 T330 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:39136/h/jy collection:collection1 shard:shard1
[junit4:junit4] 2> 8632 T330 oasc.ZkController.register We are http://127.0.0.1:39136/h/jy/collection1/ and leader is http://127.0.0.1:60509/h/jy/collection1/
[junit4:junit4] 2> 8632 T330 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:39136/h/jy
[junit4:junit4] 2> 8632 T330 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 8632 T330 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C52 name=collection1 org.apache.solr.core.SolrCore@15fc27 url=http://127.0.0.1:39136/h/jy/collection1 node=127.0.0.1:39136_h%2Fjy C52_STATE=coll:collection1 core:collection1 props:{shard=null, roles=null, state=down, core=collection1, collection=collection1, node_name=127.0.0.1:39136_h%2Fjy, base_url=http://127.0.0.1:39136/h/jy}
[junit4:junit4] 2> 8635 T332 C52 P39136 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 8636 T330 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8637 T332 C52 P39136 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 8637 T332 C52 P39136 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 8637 T332 C52 P39136 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 8637 T273 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 8638 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 8638 T332 C52 P39136 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 8638 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 8644 T273 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> ASYNC NEW_CORE C53 name=collection1 org.apache.solr.core.SolrCore@1d9d5b8 url=http://127.0.0.1:50017/h/jy/collection1 node=127.0.0.1:50017_h%2Fjy C53_STATE=coll:control_collection core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:50017_h%2Fjy, base_url=http://127.0.0.1:50017/h/jy, leader=true}
[junit4:junit4] 2> 8657 T289 C53 P50017 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/control/data
[junit4:junit4] 2> 8658 T289 C53 P50017 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1965f39 lockFactory=org.apache.lucene.store.NativeFSLockFactory@408e9d)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 8658 T289 C53 P50017 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 8661 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10001 (1424696382363533312)]} 0 9
[junit4:junit4] 2> 8661 T288 C53 P50017 /update {wt=javabin&version=2} {add=[1 (1424696382363533313)]} 0 9
[junit4:junit4] 2> ASYNC NEW_CORE C54 name=collection1 org.apache.solr.core.SolrCore@a76178 url=http://127.0.0.1:60509/h/jy/collection1 node=127.0.0.1:60509_h%2Fjy C54_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:60509_h%2Fjy, base_url=http://127.0.0.1:60509/h/jy, leader=true}
[junit4:junit4] 2> 8668 T309 C54 P60509 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty1
[junit4:junit4] 2> 8670 T309 C54 P60509 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@13b05f5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16b5de)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 8670 T309 C54 P60509 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 8672 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10001 (1424696382376116224)]} 0 4
[junit4:junit4] 2> 8672 T308 C54 P60509 /update {wt=javabin&version=2} {add=[1 (1424696382376116225)]} 0 4
[junit4:junit4] 2> 8676 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10002 (1424696382383456256)]} 0 1
[junit4:junit4] 2> 8676 T290 C53 P50017 /update {wt=javabin&version=2} {add=[2 (1424696382383456257)]} 0 1
[junit4:junit4] 2> 8679 T309 C54 P60509 /update {wt=javabin&version=2} {add=[2 (1424696382387650561)]} 0 1
[junit4:junit4] 2> 8680 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10002 (1424696382387650560)]} 0 2
[junit4:junit4] 2> 8682 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[1 (-1424696382390796288)]} 0 0
[junit4:junit4] 2> 8682 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10001 (-1424696382390796289)]} 0 0
[junit4:junit4] 2> 8685 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[1 (-1424696382393942016)]} 0 1
[junit4:junit4] 2> 8685 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10001 (-1424696382393942017)]} 0 0
[junit4:junit4] 2> 8687 T291 C53 P50017 /update {wt=javabin&version=2} {add=[3 (1424696382396039168)]} 0 0
[junit4:junit4] 2> 8687 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10003 (1424696382396039169)]} 0 0
[junit4:junit4] 2> 8690 T307 C54 P60509 /update {wt=javabin&version=2} {add=[3 (1424696382398136320)]} 0 1
[junit4:junit4] 2> 8690 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10003 (1424696382399184896)]} 0 1
[junit4:junit4] 2> 8692 T288 C53 P50017 /update {wt=javabin&version=2} {add=[4 (1424696382401282048)]} 0 0
[junit4:junit4] 2> 8692 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10004 (1424696382401282049)]} 0 0
[junit4:junit4] 2> 8694 T309 C54 P60509 /update {wt=javabin&version=2} {add=[4 (1424696382403379200)]} 0 0
[junit4:junit4] 2> 8695 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10004 (1424696382403379201)]} 0 1
[junit4:junit4] 2> 8696 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[4 (-1424696382405476352)]} 0 0
[junit4:junit4] 2> 8697 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10004 (-1424696382406524928)]} 0 0
[junit4:junit4] 2> 8699 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[4 (-1424696382408622080)]} 0 1
[junit4:junit4] 2> 8699 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10004 (-1424696382408622081)]} 0 0
[junit4:junit4] 2> 8701 T288 C53 P50017 /update {wt=javabin&version=2} {add=[5 (1424696382410719232)]} 0 0
[junit4:junit4] 2> 8702 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10005 (1424696382410719233)]} 0 1
[junit4:junit4] 2> 8704 T307 C54 P60509 /update {wt=javabin&version=2} {add=[5 (1424696382412816384)]} 0 1
[junit4:junit4] 2> 8704 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10005 (1424696382413864960)]} 0 0
[junit4:junit4] 2> 8707 T291 C53 P50017 /update {wt=javabin&version=2} {add=[6 (1424696382415962112)]} 0 1
[junit4:junit4] 2> 8707 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10006 (1424696382417010688)]} 0 1
[junit4:junit4] 2> 8709 T309 C54 P60509 /update {wt=javabin&version=2} {add=[6 (1424696382418059264)]} 0 1
[junit4:junit4] 2> 8710 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10006 (1424696382419107840)]} 0 1
[junit4:junit4] 2> 8711 T288 C53 P50017 /update {wt=javabin&version=2} {add=[7 (1424696382421204992)]} 0 0
[junit4:junit4] 2> 8713 T308 C54 P60509 /update {wt=javabin&version=2} {add=[7 (1424696382423302144)]} 0 0
[junit4:junit4] 2> 8714 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10007 (1424696382422253568)]} 0 2
[junit4:junit4] 2> 8715 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[6 (-1424696382425399296)]} 0 0
[junit4:junit4] 2> 8716 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10007 (1424696382426447872)]} 0 0
[junit4:junit4] 2> 8717 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[6 (-1424696382427496448)]} 0 0
[junit4:junit4] 2> 8718 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10006 (-1424696382428545024)]} 0 0
[junit4:junit4] 2> 8719 T288 C53 P50017 /update {wt=javabin&version=2} {add=[8 (1424696382429593600)]} 0 0
[junit4:junit4] 2> 8720 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10006 (-1424696382430642176)]} 0 0
[junit4:junit4] 2> 8722 T309 C54 P60509 /update {wt=javabin&version=2} {add=[8 (1424696382431690752)]} 0 1
[junit4:junit4] 2> 8722 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10008 (1424696382432739328)]} 0 0
[junit4:junit4] 2> 8724 T291 C53 P50017 /update {wt=javabin&version=2} {add=[9 (1424696382433787904)]} 0 1
[junit4:junit4] 2> 8728 T308 C54 P60509 /update {wt=javabin&version=2} {add=[9 (1424696382437982208)]} 0 1
[junit4:junit4] 2> 8728 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10008 (1424696382434836480)]} 0 4
[junit4:junit4] 2> 8730 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10009 (1424696382441127937)]} 0 0
[junit4:junit4] 2> 8730 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10 (1424696382441127936)]} 0 1
[junit4:junit4] 2> 8733 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10009 (1424696382443225088)]} 0 1
[junit4:junit4] 2> 8733 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10 (1424696382443225089)]} 0 1
[junit4:junit4] 2> 8735 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10010 (1424696382446370816)]} 0 0
[junit4:junit4] 2> 8735 T291 C53 P50017 /update {wt=javabin&version=2} {add=[11 (1424696382446370817)]} 0 0
[junit4:junit4] 2> 8738 T309 C54 P60509 /update {wt=javabin&version=2} {add=[11 (1424696382448467969)]} 0 1
[junit4:junit4] 2> 8738 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10010 (1424696382448467968)]} 0 1
[junit4:junit4] 2> 8739 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10 (-1424696382450565120)]} 0 0
[junit4:junit4] 2> 8740 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10011 (1424696382451613696)]} 0 0
[junit4:junit4] 2> 8741 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10 (-1424696382452662272)]} 0 0
[junit4:junit4] 2> 8742 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10011 (1424696382453710848)]} 0 0
[junit4:junit4] 2> 8743 T289 C53 P50017 /update {wt=javabin&version=2} {add=[12 (1424696382454759424)]} 0 0
[junit4:junit4] 2> 8744 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10010 (-1424696382455808000)]} 0 0
[junit4:junit4] 2> 8746 T307 C54 P60509 /update {wt=javabin&version=2} {add=[12 (1424696382456856576)]} 0 1
[junit4:junit4] 2> 8746 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10010 (-1424696382457905152)]} 0 0
[junit4:junit4] 2> 8748 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[11 (-1424696382460002304)]} 0 0
[junit4:junit4] 2> 8749 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10012 (1424696382460002305)]} 0 1
[junit4:junit4] 2> 8750 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[11 (-1424696382462099456)]} 0 0
[junit4:junit4] 2> 8751 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10012 (1424696382463148032)]} 0 0
[junit4:junit4] 2> 8752 T289 C53 P50017 /update {wt=javabin&version=2} {add=[13 (1424696382464196608)]} 0 0
[junit4:junit4] 2> 8753 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10011 (-1424696382465245184)]} 0 0
[junit4:junit4] 2> 8754 T308 C54 P60509 /update {wt=javabin&version=2} {add=[13 (1424696382466293760)]} 0 0
[junit4:junit4] 2> 8755 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10011 (-1424696382467342336)]} 0 0
[junit4:junit4] 2> 8757 T288 C53 P50017 /update {wt=javabin&version=2} {add=[14 (1424696382468390912)]} 0 1
[junit4:junit4] 2> 8759 T307 C54 P60509 /update {wt=javabin&version=2} {add=[14 (1424696382470488064)]} 0 1
[junit4:junit4] 2> 8761 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10013 (1424696382469439488)]} 0 4
[junit4:junit4] 2> 8762 T289 C53 P50017 /update {wt=javabin&version=2} {add=[15 (1424696382473633792)]} 0 1
[junit4:junit4] 2> 8763 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10013 (1424696382475730944)]} 0 1
[junit4:junit4] 2> 8765 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10014 (1424696382477828096)]} 0 0
[junit4:junit4] 2> 8766 T309 C54 P60509 /update {wt=javabin&version=2} {add=[15 (1424696382475730945)]} 0 3
[junit4:junit4] 2> 8768 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10014 (1424696382479925248)]} 0 1
[junit4:junit4] 2> 8768 T288 C53 P50017 /update {wt=javabin&version=2} {add=[16 (1424696382480973824)]} 0 1
[junit4:junit4] 2> 8770 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10015 (1424696382482022400)]} 0 1
[junit4:junit4] 2> 8772 T308 C54 P60509 /update {wt=javabin&version=2} {add=[16 (1424696382483070976)]} 0 2
[junit4:junit4] 2> 8773 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10015 (1424696382485168128)]} 0 1
[junit4:junit4] 2> 8775 T289 C53 P50017 /update {wt=javabin&version=2} {add=[17 (1424696382487265280)]} 0 1
[junit4:junit4] 2> 8775 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10016 (1424696382488313856)]} 0 0
[junit4:junit4] 2> 8778 T307 C54 P60509 /update {wt=javabin&version=2} {add=[17 (1424696382490411008)]} 0 1
[junit4:junit4] 2> 8778 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10016 (1424696382490411009)]} 0 1
[junit4:junit4] 2> 8780 T288 C53 P50017 /update {wt=javabin&version=2} {add=[18 (1424696382492508160)]} 0 1
[junit4:junit4] 2> 8780 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10017 (1424696382493556736)]} 0 0
[junit4:junit4] 2> 8782 T309 C54 P60509 /update {wt=javabin&version=2} {add=[18 (1424696382495653888)]} 0 0
[junit4:junit4] 2> 8783 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10017 (1424696382495653889)]} 0 1
[junit4:junit4] 2> 8784 T289 C53 P50017 /update {wt=javabin&version=2} {add=[19 (1424696382497751040)]} 0 0
[junit4:junit4] 2> 8785 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10018 (1424696382498799616)]} 0 1
[junit4:junit4] 2> 8787 T308 C54 P60509 /update {wt=javabin&version=2} {add=[19 (1424696382499848192)]} 0 1
[junit4:junit4] 2> 8787 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10018 (1424696382500896768)]} 0 0
[junit4:junit4] 2> 8789 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[12 (-1424696382502993920)]} 0 1
[junit4:junit4] 2> 8790 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10019 (1424696382502993921)]} 0 1
[junit4:junit4] 2> 8790 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[12 (-1424696382504042496)]} 0 0
[junit4:junit4] 2> 8792 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10019 (1424696382506139648)]} 0 1
[junit4:junit4] 2> 8795 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10012 (-1424696382509285376)]} 0 1
[junit4:junit4] 2> 8796 T289 C53 P50017 /update {wt=javabin&version=2} {add=[20 (1424696382506139648)]} 0 4
[junit4:junit4] 2> 8797 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10012 (-1424696382511382528)]} 0 0
[junit4:junit4] 2> 8798 T307 C54 P60509 /update {wt=javabin&version=2} {add=[20 (1424696382512431104)]} 0 0
[junit4:junit4] 2> 8800 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[13 (-1424696382514528256)]} 0 0
[junit4:junit4] 2> 8801 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10020 (1424696382513479680)]} 0 2
[junit4:junit4] 2> 8802 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[13 (-1424696382516625408)]} 0 0
[junit4:junit4] 2> 8803 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10020 (1424696382517673984)]} 0 0
[junit4:junit4] 2> 8805 T291 C53 P50017 /update {wt=javabin&version=2} {add=[21 (1424696382518722560)]} 0 1
[junit4:junit4] 2> 8805 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10013 (-1424696382519771136)]} 0 0
[junit4:junit4] 2> 8807 T307 C54 P60509 /update {wt=javabin&version=2} {add=[21 (1424696382520819712)]} 0 1
[junit4:junit4] 2> 8807 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10013 (-1424696382521868288)]} 0 0
[junit4:junit4] 2> 8809 T290 C53 P50017 /update {wt=javabin&version=2} {add=[22 (1424696382523965440)]} 0 1
[junit4:junit4] 2> 8809 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10021 (1424696382523965441)]} 0 0
[junit4:junit4] 2> 8811 T309 C54 P60509 /update {wt=javabin&version=2} {add=[22 (1424696382526062592)]} 0 0
[junit4:junit4] 2> 8812 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10021 (1424696382526062593)]} 0 1
[junit4:junit4] 2> 8813 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[15 (-1424696382528159744)]} 0 0
[junit4:junit4] 2> 8814 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10022 (1424696382528159745)]} 0 1
[junit4:junit4] 2> 8815 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[15 (-1424696382530256896)]} 0 0
[junit4:junit4] 2> 8816 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10022 (1424696382531305472)]} 0 0
[junit4:junit4] 2> 8818 T290 C53 P50017 /update {wt=javabin&version=2} {add=[23 (1424696382532354048)]} 0 1
[junit4:junit4] 2> 8818 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10015 (-1424696382533402624)]} 0 0
[junit4:junit4] 2> 8820 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10015 (-1424696382535499777)]} 0 0
[junit4:junit4] 2> 8820 T307 C54 P60509 /update {wt=javabin&version=2} {add=[23 (1424696382535499776)]} 0 0
[junit4:junit4] 2> 8822 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10023 (1424696382537596928)]} 0 0
[junit4:junit4] 2> 8823 T289 C53 P50017 /update {wt=javabin&version=2} {add=[24 (1424696382537596929)]} 0 1
[junit4:junit4] 2> 8824 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10023 (1424696382539694080)]} 0 0
[junit4:junit4] 2> 8826 T308 C54 P60509 /update {wt=javabin&version=2} {add=[24 (1424696382539694081)]} 0 2
[junit4:junit4] 2> 8827 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10024 (1424696382541791232)]} 0 1
[junit4:junit4] 2> 8829 T288 C53 P50017 /update {wt=javabin&version=2} {add=[25 (1424696382543888384)]} 0 1
[junit4:junit4] 2> 8830 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10024 (1424696382544936960)]} 0 1
[junit4:junit4] 2> 8831 T309 C54 P60509 /update {wt=javabin&version=2} {add=[25 (1424696382547034112)]} 0 1
[junit4:junit4] 2> 8832 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10025 (1424696382547034112)]} 0 1
[junit4:junit4] 2> 8833 T289 C53 P50017 /update {wt=javabin&version=2} {add=[26 (1424696382549131264)]} 0 0
[junit4:junit4] 2> 8834 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10025 (1424696382550179840)]} 0 1
[junit4:junit4] 2> 8835 T307 C54 P60509 /update {wt=javabin&version=2} {add=[26 (1424696382551228416)]} 0 0
[junit4:junit4] 2> 8837 T288 C53 P50017 /update {wt=javabin&version=2} {add=[27 (1424696382553325568)]} 0 0
[junit4:junit4] 2> 8839 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10026 (1424696382552276992)]} 0 3
[junit4:junit4] 2> 8840 T309 C54 P60509 /update {wt=javabin&version=2} {add=[27 (1424696382555422720)]} 0 1
[junit4:junit4] 2> 8842 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10026 (1424696382557519872)]} 0 1
[junit4:junit4] 2> 8842 T291 C53 P50017 /update {wt=javabin&version=2} {add=[28 (1424696382558568448)]} 0 0
[junit4:junit4] 2> 8844 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10027 (1424696382560665600)]} 0 0
[junit4:junit4] 2> 8845 T307 C54 P60509 /update {wt=javabin&version=2} {add=[28 (1424696382560665600)]} 0 1
[junit4:junit4] 2> 8847 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[16 (-1424696382563811328)]} 0 0
[junit4:junit4] 2> 8848 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10027 (1424696382562762752)]} 0 2
[junit4:junit4] 2> 8849 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[16 (-1424696382565908480)]} 0 0
[junit4:junit4] 2> 8851 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10028 (1424696382566957056)]} 0 1
[junit4:junit4] 2> 8851 T291 C53 P50017 /update {wt=javabin&version=2} {add=[29 (1424696382568005632)]} 0 0
[junit4:junit4] 2> 8853 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10028 (1424696382570102784)]} 0 0
[junit4:junit4] 2> 8854 T309 C54 P60509 /update {wt=javabin&version=2} {add=[29 (1424696382570102785)]} 0 1
[junit4:junit4] 2> 8855 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10016 (-1424696382572199936)]} 0 0
[junit4:junit4] 2> 8856 T288 C53 P50017 /update {wt=javabin&version=2} {add=[30 (1424696382573248512)]} 0 0
[junit4:junit4] 2> 8857 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10016 (-1424696382574297088)]} 0 0
[junit4:junit4] 2> 8858 T307 C54 P60509 /update {wt=javabin&version=2} {add=[30 (1424696382575345664)]} 0 0
[junit4:junit4] 2> 8859 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10029 (1424696382576394240)]} 0 0
[junit4:junit4] 2> 8860 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[17 (-1424696382577442816)]} 0 0
[junit4:junit4] 2> 8862 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[17 (-1424696382579539968)]} 0 0
[junit4:junit4] 2> 8862 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10029 (1424696382578491392)]} 0 1
[junit4:junit4] 2> 8864 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10030 (1424696382581637120)]} 0 0
[junit4:junit4] 2> 8864 T289 C53 P50017 /update {wt=javabin&version=2} {add=[31 (1424696382581637121)]} 0 0
[junit4:junit4] 2> 8867 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10030 (1424696382583734272)]} 0 1
[junit4:junit4] 2> 8867 T307 C54 P60509 /update {wt=javabin&version=2} {add=[31 (1424696382583734273)]} 0 1
[junit4:junit4] 2> 8868 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10017 (-1424696382585831424)]} 0 0
[junit4:junit4] 2> 8869 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[18 (-1424696382585831425)]} 0 1
[junit4:junit4] 2> 8870 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10017 (-1424696382587928576)]} 0 0
[junit4:junit4] 2> 8871 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[18 (-1424696382587928577)]} 0 1
[junit4:junit4] 2> 8873 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10031 (1424696382590025728)]} 0 1
[junit4:junit4] 2> 8873 T289 C53 P50017 /update {wt=javabin&version=2} {add=[32 (1424696382590025729)]} 0 1
[junit4:junit4] 2> 8875 T309 C54 P60509 /update {wt=javabin&version=2} {add=[32 (1424696382593171457)]} 0 0
[junit4:junit4] 2> 8877 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10031 (1424696382593171456)]} 0 2
[junit4:junit4] 2> 8878 T290 C53 P50017 /update {wt=javabin&version=2} {add=[33 (1424696382595268608)]} 0 1
[junit4:junit4] 2> 8879 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10018 (-1424696382597365760)]} 0 1
[junit4:junit4] 2> 8880 T308 C54 P60509 /update {wt=javabin&version=2} {add=[33 (1424696382598414336)]} 0 0
[junit4:junit4] 2> 8880 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10018 (-1424696382598414337)]} 0 0
[junit4:junit4] 2> 8882 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[22 (-1424696382600511488)]} 0 0
[junit4:junit4] 2> 8883 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10032 (1424696382600511489)]} 0 1
[junit4:junit4] 2> 8884 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[22 (-1424696382602608640)]} 0 0
[junit4:junit4] 2> 8885 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10032 (1424696382603657216)]} 0 1
[junit4:junit4] 2> 8887 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10033 (1424696382605754368)]} 0 0
[junit4:junit4] 2> 8889 T290 C53 P50017 /update {wt=javabin&version=2} {add=[34 (1424696382604705792)]} 0 3
[junit4:junit4] 2> 8890 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10033 (1424696382607851520)]} 0 1
[junit4:junit4] 2> 8891 T307 C54 P60509 /update {wt=javabin&version=2} {add=[34 (1424696382609948672)]} 0 0
[junit4:junit4] 2> 8892 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10022 (-1424696382610997248)]} 0 1
[junit4:junit4] 2> 8894 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10022 (-1424696382613094400)]} 0 1
[junit4:junit4] 2> 8894 T289 C53 P50017 /update {wt=javabin&version=2} {add=[35 (1424696382612045824)]} 0 1
[junit4:junit4] 2> 8896 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10034 (1424696382614142976)]} 0 1
[junit4:junit4] 2> 8896 T309 C54 P60509 /update {wt=javabin&version=2} {add=[35 (1424696382615191552)]} 0 0
[junit4:junit4] 2> 8898 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10034 (1424696382617288704)]} 0 0
[junit4:junit4] 2> 8898 T290 C53 P50017 /update {wt=javabin&version=2} {add=[36 (1424696382617288704)]} 0 0
[junit4:junit4] 2> 8900 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10035 (1424696382619385856)]} 0 0
[junit4:junit4] 2> 8901 T308 C54 P60509 /update {wt=javabin&version=2} {add=[36 (1424696382619385856)]} 0 1
[junit4:junit4] 2> 8903 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10035 (1424696382621483008)]} 0 0
[junit4:junit4] 2> 8903 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[24 (-1424696382621483008)]} 0 1
[junit4:junit4] 2> 8905 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[24 (-1424696382624628736)]} 0 1
[junit4:junit4] 2> 8905 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10036 (1424696382623580160)]} 0 1
[junit4:junit4] 2> 8907 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10036 (1424696382626725888)]} 0 1
[junit4:junit4] 2> 8907 T290 C53 P50017 /update {wt=javabin&version=2} {add=[37 (1424696382625677312)]} 0 1
[junit4:junit4] 2> 8909 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10024 (-1424696382628823040)]} 0 0
[junit4:junit4] 2> 8909 T309 C54 P60509 /update {wt=javabin&version=2} {add=[37 (1424696382628823040)]} 0 0
[junit4:junit4] 2> 8911 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10024 (-1424696382630920192)]} 0 0
[junit4:junit4] 2> 8911 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[26 (-1424696382630920192)]} 0 0
[junit4:junit4] 2> 8913 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10037 (1424696382631968768)]} 0 1
[junit4:junit4] 2> 8913 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[26 (-1424696382633017344)]} 0 0
[junit4:junit4] 2> 8915 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10037 (1424696382635114496)]} 0 0
[junit4:junit4] 2> 8915 T290 C53 P50017 /update {wt=javabin&version=2} {add=[38 (1424696382635114496)]} 0 0
[junit4:junit4] 2> 8917 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10026 (-1424696382637211648)]} 0 0
[junit4:junit4] 2> 8918 T307 C54 P60509 /update {wt=javabin&version=2} {add=[38 (1424696382637211648)]} 0 1
[junit4:junit4] 2> 8919 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10026 (-1424696382639308800)]} 0 0
[junit4:junit4] 2> 8920 T289 C53 P50017 /update {wt=javabin&version=2} {add=[39 (1424696382639308800)]} 0 1
[junit4:junit4] 2> 8922 T307 C54 P60509 /update {wt=javabin&version=2} {add=[39 (1424696382642454528)]} 0 0
[junit4:junit4] 2> 8923 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10038 (1424696382641405952)]} 0 2
[junit4:junit4] 2> 8924 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[27 (-1424696382644551680)]} 0 0
[junit4:junit4] 2> 8925 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10038 (1424696382645600256)]} 0 1
[junit4:junit4] 2> 8926 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[27 (-1424696382646648832)]} 0 0
[junit4:junit4] 2> 8927 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10039 (1424696382647697408)]} 0 0
[junit4:junit4] 2> 8928 T289 C53 P50017 /update {wt=javabin&version=2} {add=[40 (1424696382647697409)]} 0 1
[junit4:junit4] 2> 8929 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10039 (1424696382649794560)]} 0 0
[junit4:junit4] 2> 8931 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10027 (-1424696382651891712)]} 0 0
[junit4:junit4] 2> 8932 T309 C54 P60509 /update {wt=javabin&version=2} {add=[40 (1424696382650843136)]} 0 2
[junit4:junit4] 2> 8933 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10027 (-1424696382653988864)]} 0 0
[junit4:junit4] 2> 8934 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[29 (-1424696382655037440)]} 0 0
[junit4:junit4] 2> 8935 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10040 (1424696382656086016)]} 0 0
[junit4:junit4] 2> 8936 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[29 (-1424696382657134592)]} 0 0
[junit4:junit4] 2> 8938 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10040 (1424696382658183168)]} 0 1
[junit4:junit4] 2> 8938 T289 C53 P50017 /update {wt=javabin&version=2} {add=[41 (1424696382659231744)]} 0 0
[junit4:junit4] 2> 8940 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10029 (-1424696382660280320)]} 0 1
[junit4:junit4] 2> 8941 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10029 (-1424696382662377472)]} 0 0
[junit4:junit4] 2> 8942 T308 C54 P60509 /update {wt=javabin&version=2} {add=[41 (1424696382661328896)]} 0 2
[junit4:junit4] 2> 8944 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10041 (1424696382664474624)]} 0 1
[junit4:junit4] 2> 8946 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10041 (1424696382667620352)]} 0 0
[junit4:junit4] 2> 8947 T288 C53 P50017 /update {wt=javabin&version=2} {add=[42 (1424696382665523200)]} 0 3
[junit4:junit4] 2> 8949 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10042 (1424696382669717504)]} 0 1
[junit4:junit4] 2> 8949 T307 C54 P60509 /update {wt=javabin&version=2} {add=[42 (1424696382670766080)]} 0 0
[junit4:junit4] 2> 8951 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10042 (1424696382672863232)]} 0 1
[junit4:junit4] 2> 8951 T291 C53 P50017 /update {wt=javabin&version=2} {add=[43 (1424696382672863232)]} 0 0
[junit4:junit4] 2> 8954 T309 C54 P60509 /update {wt=javabin&version=2} {add=[43 (1424696382674960384)]} 0 1
[junit4:junit4] 2> 8954 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10043 (1424696382674960384)]} 0 1
[junit4:junit4] 2> 8956 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[31 (-1424696382678106112)]} 0 0
[junit4:junit4] 2> 8957 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10043 (1424696382678106112)]} 0 1
[junit4:junit4] 2> 8958 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[31 (-1424696382680203264)]} 0 0
[junit4:junit4] 2> 8959 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10031 (-1424696382681251840)]} 0 1
[junit4:junit4] 2> 8960 T291 C53 P50017 /update {wt=javabin&version=2} {add=[44 (1424696382682300416)]} 0 0
[junit4:junit4] 2> 8961 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10031 (-1424696382683348992)]} 0 1
[junit4:junit4] 2> 8963 T307 C54 P60509 /update {wt=javabin&version=2} {add=[44 (1424696382685446144)]} 0 1
[junit4:junit4] 2> 8963 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10044 (1424696382685446144)]} 0 0
[junit4:junit4] 2> 8965 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[32 (-1424696382687543296)]} 0 0
[junit4:junit4] 2> 8966 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10044 (1424696382687543296)]} 0 1
[junit4:junit4] 2> 8967 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[32 (-1424696382689640448)]} 0 0
[junit4:junit4] 2> 8968 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10032 (-1424696382690689024)]} 0 1
[junit4:junit4] 2> 8970 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10032 (-1424696382691737600)]} 0 1
[junit4:junit4] 2> 8970 T291 C53 P50017 /update {wt=javabin&version=2} {add=[45 (1424696382691737600)]} 0 1
[junit4:junit4] 2> 8972 T308 C54 P60509 /update {wt=javabin&version=2} {add=[45 (1424696382694883328)]} 0 1
[junit4:junit4] 2> 8974 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10045 (1424696382693834752)]} 0 3
[junit4:junit4] 2> 8975 T288 C53 P50017 /update {wt=javabin&version=2} {add=[46 (1424696382696980480)]} 0 1
[junit4:junit4] 2> 8977 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10045 (1424696382699077632)]} 0 1
[junit4:junit4] 2> 8977 T307 C54 P60509 /update {wt=javabin&version=2} {add=[46 (1424696382700126208)]} 0 0
[junit4:junit4] 2> 8979 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[34 (-1424696382702223361)]} 0 0
[junit4:junit4] 2> 8979 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10046 (1424696382702223360)]} 0 0
[junit4:junit4] 2> 8981 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[34 (-1424696382704320512)]} 0 0
[junit4:junit4] 2> 8982 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10046 (1424696382704320513)]} 0 1
[junit4:junit4] 2> 8983 T290 C53 P50017 /update {wt=javabin&version=2} {add=[47 (1424696382706417664)]} 0 0
[junit4:junit4] 2> 8984 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10034 (-1424696382707466240)]} 0 1
[junit4:junit4] 2> 8986 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10034 (-1424696382709563392)]} 0 1
[junit4:junit4] 2> 8986 T307 C54 P60509 /update {wt=javabin&version=2} {add=[47 (1424696382708514816)]} 0 1
[junit4:junit4] 2> 8988 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[35 (-1424696382711660545)]} 0 0
[junit4:junit4] 2> 8988 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10047 (1424696382711660544)]} 0 1
[junit4:junit4] 2> 8990 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[35 (-1424696382713757696)]} 0 0
[junit4:junit4] 2> 8990 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10047 (1424696382713757697)]} 0 0
[junit4:junit4] 2> 8992 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10035 (-1424696382715854849)]} 0 0
[junit4:junit4] 2> 8992 T290 C53 P50017 /update {wt=javabin&version=2} {add=[48 (1424696382715854848)]} 0 0
[junit4:junit4] 2> 8994 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10035 (-1424696382717952000)]} 0 0
[junit4:junit4] 2> 8995 T309 C54 P60509 /update {wt=javabin&version=2} {add=[48 (1424696382717952001)]} 0 1
[junit4:junit4] 2> 8996 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10048 (1424696382720049152)]} 0 0
[junit4:junit4] 2> 8997 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[36 (-1424696382721097728)]} 0 0
[junit4:junit4] 2> 8999 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[36 (-1424696382722146305)]} 0 1
[junit4:junit4] 2> 9001 T288 C53 P50017 /update {wt=javabin&version=2} {add=[49 (1424696382724243456)]} 0 1
[junit4:junit4] 2> 9003 T309 C54 P60509 /update {wt=javabin&version=2} {add=[49 (1424696382726340608)]} 0 1
[junit4:junit4] 2> 9005 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[37 (-1424696382729486336)]} 0 1
[junit4:junit4] 2> 9007 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[37 (-1424696382731583488)]} 0 1
[junit4:junit4] 2> 9009 T289 C53 P50017 /update {wt=javabin&version=2} {add=[50 (1424696382732632064)]} 0 1
[junit4:junit4] 2> 9011 T309 C54 P60509 /update {wt=javabin&version=2} {add=[50 (1424696382735777792)]} 0 0
[junit4:junit4] 2> 9013 T291 C53 P50017 /update {wt=javabin&version=2} {add=[51 (1424696382737874944)]} 0 0
[junit4:junit4] 2> 9048 T307 C54 P60509 /update {wt=javabin&version=2} {add=[51 (1424696382739972096)]} 0 33
[junit4:junit4] 2> 9050 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10048 (1424696382722146304)]} 0 52
[junit4:junit4] 2> 9050 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[40 (-1424696382776672256)]} 0 0
[junit4:junit4] 2> 9052 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10036 (-1424696382778769408)]} 0 0
[junit4:junit4] 2> 9052 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[40 (-1424696382778769408)]} 0 0
[junit4:junit4] 2> 9054 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10036 (-1424696382780866560)]} 0 0
[junit4:junit4] 2> 9055 T290 C53 P50017 /update {wt=javabin&version=2} {add=[52 (1424696382780866560)]} 0 1
[junit4:junit4] 2> 9057 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10049 (1424696382782963712)]} 0 1
[junit4:junit4] 2> 9057 T308 C54 P60509 /update {wt=javabin&version=2} {add=[52 (1424696382784012288)]} 0 0
[junit4:junit4] 2> 9059 T291 C53 P50017 /update {wt=javabin&version=2} {add=[53 (1424696382786109440)]} 0 0
[junit4:junit4] 2> 9059 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10049 (1424696382786109440)]} 0 1
[junit4:junit4] 2> 9062 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10037 (-1424696382789255168)]} 0 1
[junit4:junit4] 2> 9062 T307 C54 P60509 /update {wt=javabin&version=2} {add=[53 (1424696382788206592)]} 0 1
[junit4:junit4] 2> 9063 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10037 (-1424696382790303744)]} 0 0
[junit4:junit4] 2> 9066 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10050 (1424696382792400896)]} 0 1
[junit4:junit4] 2> 9066 T290 C53 P50017 /update {wt=javabin&version=2} {add=[54 (1424696382791352320)]} 0 3
[junit4:junit4] 2> 9069 T307 C54 P60509 /update {wt=javabin&version=2} {add=[54 (1424696382795546625)]} 0 1
[junit4:junit4] 2> 9070 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10050 (1424696382795546624)]} 0 3
[junit4:junit4] 2> 9071 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[41 (-1424696382798692352)]} 0 0
[junit4:junit4] 2> 9073 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10051 (1424696382799740928)]} 0 1
[junit4:junit4] 2> 9073 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[41 (-1424696382800789504)]} 0 0
[junit4:junit4] 2> 9075 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10051 (1424696382802886656)]} 0 0
[junit4:junit4] 2> 9075 T288 C53 P50017 /update {wt=javabin&version=2} {add=[55 (1424696382802886656)]} 0 0
[junit4:junit4] 2> 9077 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10040 (-1424696382804983808)]} 0 0
[junit4:junit4] 2> 9078 T309 C54 P60509 /update {wt=javabin&version=2} {add=[55 (1424696382804983808)]} 0 1
[junit4:junit4] 2> 9079 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10040 (-1424696382807080960)]} 0 0
[junit4:junit4] 2> 9080 T291 C53 P50017 /update {wt=javabin&version=2} {add=[56 (1424696382808129536)]} 0 0
[junit4:junit4] 2> 9081 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10052 (1424696382809178112)]} 0 0
[junit4:junit4] 2> 9082 T307 C54 P60509 /update {wt=javabin&version=2} {add=[56 (1424696382810226688)]} 0 0
[junit4:junit4] 2> 9083 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10052 (1424696382811275264)]} 0 0
[junit4:junit4] 2> 9084 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[45 (-1424696382812323840)]} 0 0
[junit4:junit4] 2> 9086 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10053 (1424696382813372416)]} 0 1
[junit4:junit4] 2> 9086 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[45 (-1424696382814420992)]} 0 0
[junit4:junit4] 2> 9088 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10053 (1424696382816518144)]} 0 0
[junit4:junit4] 2> 9088 T291 C53 P50017 /update {wt=javabin&version=2} {add=[57 (1424696382816518144)]} 0 0
[junit4:junit4] 2> 9090 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10054 (1424696382818615296)]} 0 0
[junit4:junit4] 2> 9091 T309 C54 P60509 /update {wt=javabin&version=2} {add=[57 (1424696382818615296)]} 0 1
[junit4:junit4] 2> 9093 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10054 (1424696382820712448)]} 0 1
[junit4:junit4] 2> 9093 T288 C53 P50017 /update {wt=javabin&version=2} {add=[58 (1424696382820712448)]} 0 1
[junit4:junit4] 2> 9095 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10041 (-1424696382822809600)]} 0 1
[junit4:junit4] 2> 9095 T307 C54 P60509 /update {wt=javabin&version=2} {add=[58 (1424696382823858176)]} 0 1
[junit4:junit4] 2> 9097 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[47 (-1424696382825955328)]} 0 0
[junit4:junit4] 2> 9096 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10041 (-1424696382824906752)]} 0 0
[junit4:junit4] 2> 9099 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[47 (-1424696382828052480)]} 0 0
[junit4:junit4] 2> 9099 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10055 (1424696382828052480)]} 0 0
[junit4:junit4] 2> 9101 T288 C53 P50017 /update {wt=javabin&version=2} {add=[59 (1424696382830149632)]} 0 1
[junit4:junit4] 2> 9103 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10055 (1424696382830149632)]} 0 2
[junit4:junit4] 2> 9103 T309 C54 P60509 /update {wt=javabin&version=2} {add=[59 (1424696382832246784)]} 0 0
[junit4:junit4] 2> 9106 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10056 (1424696382834343936)]} 0 1
[junit4:junit4] 2> 9106 T291 C53 P50017 /update {wt=javabin&version=2} {add=[60 (1424696382834343937)]} 0 1
[junit4:junit4] 2> 9108 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10056 (1424696382837489664)]} 0 0
[junit4:junit4] 2> 9109 T307 C54 P60509 /update {wt=javabin&version=2} {add=[60 (1424696382837489665)]} 0 1
[junit4:junit4] 2> 9110 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10045 (-1424696382839586816)]} 0 0
[junit4:junit4] 2> 9111 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[48 (-1424696382839586817)]} 0 1
[junit4:junit4] 2> 9112 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10045 (-1424696382841683968)]} 0 0
[junit4:junit4] 2> 9112 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[48 (-1424696382841683969)]} 0 0
[junit4:junit4] 2> 9115 T291 C53 P50017 /update {wt=javabin&version=2} {add=[61 (1424696382843781121)]} 0 1
[junit4:junit4] 2> 9117 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10057 (1424696382843781120)]} 0 3
[junit4:junit4] 2> 9117 T307 C54 P60509 /update {wt=javabin&version=2} {add=[61 (1424696382846926848)]} 0 0
[junit4:junit4] 2> 9119 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10057 (1424696382849024000)]} 0 0
[junit4:junit4] 2> 9119 T289 C53 P50017 /update {wt=javabin&version=2} {add=[62 (1424696382849024000)]} 0 0
[junit4:junit4] 2> 9121 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10058 (1424696382851121152)]} 0 0
[junit4:junit4] 2> 9122 T308 C54 P60509 /update {wt=javabin&version=2} {add=[62 (1424696382851121152)]} 0 1
[junit4:junit4] 2> 9124 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10058 (1424696382853218304)]} 0 1
[junit4:junit4] 2> 9124 T291 C53 P50017 /update {wt=javabin&version=2} {add=[63 (1424696382853218304)]} 0 1
[junit4:junit4] 2> 9126 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10047 (-1424696382855315456)]} 0 1
[junit4:junit4] 2> 9126 T309 C54 P60509 /update {wt=javabin&version=2} {add=[63 (1424696382856364032)]} 0 1
[junit4:junit4] 2> 9128 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10047 (-1424696382857412608)]} 0 1
[junit4:junit4] 2> 9128 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[50 (-1424696382858461184)]} 0 0
[junit4:junit4] 2> 9130 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[50 (-1424696382860558336)]} 0 1
[junit4:junit4] 2> 9130 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10059 (1424696382859509760)]} 0 1
[junit4:junit4] 2> 9132 T291 C53 P50017 /update {wt=javabin&version=2} {add=[64 (1424696382862655488)]} 0 0
[junit4:junit4] 2> 9132 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10059 (1424696382862655488)]} 0 0
[junit4:junit4] 2> 9134 T308 C54 P60509 /update {wt=javabin&version=2} {add=[64 (1424696382864752640)]} 0 0
[junit4:junit4] 2> 9135 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10060 (1424696382864752640)]} 0 1
[junit4:junit4] 2> 9136 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[54 (-1424696382866849792)]} 0 0
[junit4:junit4] 2> 9137 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10060 (1424696382867898368)]} 0 1
[junit4:junit4] 2> 9138 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[54 (-1424696382868946944)]} 0 0
[junit4:junit4] 2> 9139 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10048 (-1424696382869995520)]} 0 0
[junit4:junit4] 2> 9140 T291 C53 P50017 /update {wt=javabin&version=2} {add=[65 (1424696382871044096)]} 0 1
[junit4:junit4] 2> 9141 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10048 (-1424696382871044096)]} 0 1
[junit4:junit4] 2> 9142 T307 C54 P60509 /update {wt=javabin&version=2} {add=[65 (1424696382873141248)]} 0 0
[junit4:junit4] 2> 9143 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10061 (1424696382873141248)]} 0 1
[junit4:junit4] 2> 9144 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[57 (-1424696382875238400)]} 0 0
[junit4:junit4] 2> 9145 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10061 (1424696382876286976)]} 0 0
[junit4:junit4] 2> 9146 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[57 (-1424696382877335552)]} 0 1
[junit4:junit4] 2> 9148 T291 C53 P50017 /update {wt=javabin&version=2} {add=[66 (1424696382878384129)]} 0 1
[junit4:junit4] 2> 9149 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10062 (1424696382878384128)]} 0 2
[junit4:junit4] 2> 9150 T307 C54 P60509 /update {wt=javabin&version=2} {add=[66 (1424696382881529856)]} 0 1
[junit4:junit4] 2> 9151 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10062 (1424696382882578432)]} 0 0
[junit4:junit4] 2> 9152 T290 C53 P50017 /update {wt=javabin&version=2} {add=[67 (1424696382883627008)]} 0 0
[junit4:junit4] 2> 9155 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10063 (1424696382884675584)]} 0 2
[junit4:junit4] 2> 9156 T308 C54 P60509 /update {wt=javabin&version=2} {add=[67 (1424696382885724160)]} 0 2
[junit4:junit4] 2> 9157 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10063 (1424696382888869888)]} 0 0
[junit4:junit4] 2> 9158 T291 C53 P50017 /update {wt=javabin&version=2} {add=[68 (1424696382889918464)]} 0 0
[junit4:junit4] 2> 9159 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10050 (-1424696382890967040)]} 0 0
[junit4:junit4] 2> 9161 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10050 (-1424696382893064192)]} 0 0
[junit4:junit4] 2> 9162 T309 C54 P60509 /update {wt=javabin&version=2} {add=[68 (1424696382892015616)]} 0 2
[junit4:junit4] 2> 9164 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10064 (1424696382895161344)]} 0 1
[junit4:junit4] 2> 9165 T289 C53 P50017 /update {wt=javabin&version=2} {add=[69 (1424696382896209920)]} 0 1
[junit4:junit4] 2> 9166 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10064 (1424696382898307072)]} 0 1
[junit4:junit4] 2> 9167 T308 C54 P60509 /update {wt=javabin&version=2} {add=[69 (1424696382898307073)]} 0 1
[junit4:junit4] 2> 9168 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10054 (-1424696382900404224)]} 0 0
[junit4:junit4] 2> 9169 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[58 (-1424696382901452800)]} 0 1
[junit4:junit4] 2> 9170 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10054 (-1424696382901452800)]} 0 1
[junit4:junit4] 2> 9170 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[58 (-1424696382902501376)]} 0 0
[junit4:junit4] 2> 9172 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10065 (1424696382903549952)]} 0 1
[junit4:junit4] 2> 9173 T289 C53 P50017 /update {wt=javabin&version=2} {add=[70 (1424696382904598528)]} 0 1
[junit4:junit4] 2> 9174 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10065 (1424696382906695680)]} 0 0
[junit4:junit4] 2> 9175 T309 C54 P60509 /update {wt=javabin&version=2} {add=[70 (1424696382907744256)]} 0 0
[junit4:junit4] 2> 9176 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10057 (-1424696382908792832)]} 0 0
[junit4:junit4] 2> 9177 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[59 (-1424696382909841408)]} 0 0
[junit4:junit4] 2> 9178 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10057 (-1424696382910889984)]} 0 0
[junit4:junit4] 2> 9179 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[59 (-1424696382911938560)]} 0 0
[junit4:junit4] 2> 9181 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10066 (1424696382912987136)]} 0 1
[junit4:junit4] 2> 9181 T289 C53 P50017 /update {wt=javabin&version=2} {add=[71 (1424696382914035712)]} 0 0
[junit4:junit4] 2> 9183 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10066 (1424696382915084288)]} 0 1
[junit4:junit4] 2> 9183 T307 C54 P60509 /update {wt=javabin&version=2} {add=[71 (1424696382916132864)]} 0 0
[junit4:junit4] 2> 9185 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10067 (1424696382918230016)]} 0 0
[junit4:junit4] 2> 9185 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[60 (-1424696382918230017)]} 0 0
[junit4:junit4] 2> 9187 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[60 (-1424696382920327169)]} 0 0
[junit4:junit4] 2> 9187 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10067 (1424696382920327168)]} 0 0
[junit4:junit4] 2> 9189 T290 C53 P50017 /update {wt=javabin&version=2} {add=[72 (1424696382922424320)]} 0 0
[junit4:junit4] 2> 9191 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10068 (1424696382922424321)]} 0 2
[junit4:junit4] 2> 9192 T307 C54 P60509 /update {wt=javabin&version=2} {add=[72 (1424696382925570048)]} 0 1
[junit4:junit4] 2> 9193 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10068 (1424696382926618624)]} 0 0
[junit4:junit4] 2> 9194 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[61 (-1424696382927667200)]} 0 0
[junit4:junit4] 2> 9196 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[61 (-1424696382929764352)]} 0 0
[junit4:junit4] 2> 9196 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10069 (1424696382928715776)]} 0 1
[junit4:junit4] 2> 9198 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10069 (1424696382931861504)]} 0 0
[junit4:junit4] 2> 9198 T290 C53 P50017 /update {wt=javabin&version=2} {add=[73 (1424696382931861504)]} 0 1
[junit4:junit4] 2> 9200 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10058 (-1424696382933958656)]} 0 0
[junit4:junit4] 2> 9201 T309 C54 P60509 /update {wt=javabin&version=2} {add=[73 (1424696382933958656)]} 0 1
[junit4:junit4] 2> 9202 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10058 (-1424696382936055808)]} 0 0
[junit4:junit4] 2> 9202 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[62 (-1424696382936055808)]} 0 0
[junit4:junit4] 2> 9204 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[62 (-1424696382938152960)]} 0 0
[junit4:junit4] 2> 9204 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10070 (1424696382938152960)]} 0 0
[junit4:junit4] 2> 9207 T290 C53 P50017 /update {wt=javabin&version=2} {add=[74 (1424696382940250112)]} 0 1
[junit4:junit4] 2> 9207 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10070 (1424696382940250112)]} 0 1
[junit4:junit4] 2> 9209 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10059 (-1424696382943395840)]} 0 1
[junit4:junit4] 2> 9209 T308 C54 P60509 /update {wt=javabin&version=2} {add=[74 (1424696382942347264)]} 0 1
[junit4:junit4] 2> 9210 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10059 (-1424696382944444416)]} 0 0
[junit4:junit4] 2> 9211 T291 C53 P50017 /update {wt=javabin&version=2} {add=[75 (1424696382945492992)]} 0 1
[junit4:junit4] 2> 9213 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10071 (1424696382946541568)]} 0 1
[junit4:junit4] 2> 9213 T309 C54 P60509 /update {wt=javabin&version=2} {add=[75 (1424696382947590144)]} 0 0
[junit4:junit4] 2> 9215 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[64 (-1424696382949687296)]} 0 0
[junit4:junit4] 2> 9216 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10071 (1424696382948638720)]} 0 2
[junit4:junit4] 2> 9217 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[64 (-1424696382951784448)]} 0 0
[junit4:junit4] 2> 9218 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10060 (-1424696382952833024)]} 0 0
[junit4:junit4] 2> 9219 T291 C53 P50017 /update {wt=javabin&version=2} {add=[76 (1424696382953881600)]} 0 1
[junit4:junit4] 2> 9220 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10060 (-1424696382954930176)]} 0 0
[junit4:junit4] 2> 9222 T308 C54 P60509 /update {wt=javabin&version=2} {add=[76 (1424696382955978752)]} 0 1
[junit4:junit4] 2> 9222 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10072 (1424696382957027328)]} 0 0
[junit4:junit4] 2> 9224 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[65 (-1424696382959124480)]} 0 0
[junit4:junit4] 2> 9225 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10072 (1424696382959124480)]} 0 1
[junit4:junit4] 2> 9226 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[65 (-1424696382960173056)]} 0 1
[junit4:junit4] 2> 9227 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10061 (-1424696382962270208)]} 0 0
[junit4:junit4] 2> 9228 T291 C53 P50017 /update {wt=javabin&version=2} {add=[77 (1424696382962270209)]} 0 1
[junit4:junit4] 2> 9229 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10061 (-1424696382964367360)]} 0 1
[junit4:junit4] 2> 9230 T307 C54 P60509 /update {wt=javabin&version=2} {add=[77 (1424696382964367361)]} 0 1
[junit4:junit4] 2> 9231 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10073 (1424696382965415936)]} 0 1
[junit4:junit4] 2> 9232 T290 C53 P50017 /update {wt=javabin&version=2} {add=[78 (1424696382967513088)]} 0 0
[junit4:junit4] 2> 9233 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10073 (1424696382968561664)]} 0 1
[junit4:junit4] 2> 9234 T308 C54 P60509 /update {wt=javabin&version=2} {add=[78 (1424696382969610240)]} 0 0
[junit4:junit4] 2> 9235 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10062 (-1424696382970658816)]} 0 0
[junit4:junit4] 2> 9236 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[67 (-1424696382971707392)]} 0 0
[junit4:junit4] 2> 9237 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10062 (-1424696382972755968)]} 0 0
[junit4:junit4] 2> 9238 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[67 (-1424696382973804544)]} 0 0
[junit4:junit4] 2> 9239 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10074 (1424696382973804544)]} 0 1
[junit4:junit4] 2> 9240 T290 C53 P50017 /update {wt=javabin&version=2} {add=[79 (1424696382975901696)]} 0 1
[junit4:junit4] 2> 9241 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10074 (1424696382976950272)]} 0 1
[junit4:junit4] 2> 9242 T307 C54 P60509 /update {wt=javabin&version=2} {add=[79 (1424696382977998848)]} 0 0
[junit4:junit4] 2> 9243 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10075 (1424696382979047424)]} 0 0
[junit4:junit4] 2> 9244 T291 C53 P50017 /update {wt=javabin&version=2} {add=[80 (1424696382980096000)]} 0 0
[junit4:junit4] 2> 9245 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10075 (1424696382981144576)]} 0 0
[junit4:junit4] 2> 9246 T308 C54 P60509 /update {wt=javabin&version=2} {add=[80 (1424696382982193152)]} 0 0
[junit4:junit4] 2> 9247 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10064 (-1424696382983241728)]} 0 0
[junit4:junit4] 2> 9249 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10064 (-1424696382985338880)]} 0 0
[junit4:junit4] 2> 9251 T290 C53 P50017 /update {wt=javabin&version=2} {add=[81 (1424696382984290304)]} 0 3
[junit4:junit4] 2> 9251 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10076 (1424696382986387456)]} 0 1
[junit4:junit4] 2> 9253 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10076 (1424696382989533185)]} 0 0
[junit4:junit4] 2> 9254 T309 C54 P60509 /update {wt=javabin&version=2} {add=[81 (1424696382989533184)]} 0 1
[junit4:junit4] 2> 9255 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10065 (-1424696382991630336)]} 0 0
[junit4:junit4] 2> 9256 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[69 (-1424696382991630337)]} 0 1
[junit4:junit4] 2> 9257 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10065 (-1424696382993727488)]} 0 0
[junit4:junit4] 2> 9257 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[69 (-1424696382993727489)]} 0 0
[junit4:junit4] 2> 9259 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10077 (1424696382995824640)]} 0 0
[junit4:junit4] 2> 9260 T289 C53 P50017 /update {wt=javabin&version=2} {add=[82 (1424696382995824641)]} 0 1
[junit4:junit4] 2> 9262 T307 C54 P60509 /update {wt=javabin&version=2} {add=[82 (1424696382997921793)]} 0 1
[junit4:junit4] 2> 9264 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10077 (1424696382997921792)]} 0 3
[junit4:junit4] 2> 9264 T291 C53 P50017 /update {wt=javabin&version=2} {add=[83 (1424696383001067520)]} 0 0
[junit4:junit4] 2> 9267 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10078 (1424696383003164672)]} 0 1
[junit4:junit4] 2> 9270 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10078 (1424696383006310400)]} 0 1
[junit4:junit4] 2> 9271 T308 C54 P60509 /update {wt=javabin&version=2} {add=[83 (1424696383003164672)]} 0 5
[junit4:junit4] 2> 9272 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10067 (-1424696383009456128)]} 0 0
[junit4:junit4] 2> 9273 T289 C53 P50017 /update {wt=javabin&version=2} {add=[84 (1424696383010504704)]} 0 0
[junit4:junit4] 2> 9274 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10067 (-1424696383011553280)]} 0 0
[junit4:junit4] 2> 9276 T307 C54 P60509 /update {wt=javabin&version=2} {add=[84 (1424696383012601856)]} 0 1
[junit4:junit4] 2> 9276 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10079 (1424696383013650432)]} 0 0
[junit4:junit4] 2> 9277 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[71 (-1424696383014699008)]} 0 0
[junit4:junit4] 2> 9278 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10079 (1424696383015747584)]} 0 0
[junit4:junit4] 2> 9279 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[71 (-1424696383016796160)]} 0 0
[junit4:junit4] 2> 9281 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10080 (1424696383017844736)]} 0 1
[junit4:junit4] 2> 9282 T289 C53 P50017 /update {wt=javabin&version=2} {add=[85 (1424696383018893312)]} 0 1
[junit4:junit4] 2> 9283 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10080 (1424696383020990464)]} 0 1
[junit4:junit4] 2> 9284 T308 C54 P60509 /update {wt=javabin&version=2} {add=[85 (1424696383020990465)]} 0 1
[junit4:junit4] 2> 9285 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10081 (1424696383023087616)]} 0 0
[junit4:junit4] 2> 9286 T288 C53 P50017 /update {wt=javabin&version=2} {add=[86 (1424696383023087617)]} 0 1
[junit4:junit4] 2> 9287 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10081 (1424696383025184768)]} 0 0
[junit4:junit4] 2> 9288 T307 C54 P60509 /update {wt=javabin&version=2} {add=[86 (1424696383026233344)]} 0 0
[junit4:junit4] 2> 9289 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10069 (-1424696383027281920)]} 0 0
[junit4:junit4] 2> 9290 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[72 (-1424696383028330496)]} 0 0
[junit4:junit4] 2> 9291 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10069 (-1424696383029379072)]} 0 0
[junit4:junit4] 2> 9292 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[72 (-1424696383030427648)]} 0 1
[junit4:junit4] 2> 9294 T288 C53 P50017 /update {wt=javabin&version=2} {add=[87 (1424696383031476225)]} 0 1
[junit4:junit4] 2> 9295 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10082 (1424696383031476224)]} 0 2
[junit4:junit4] 2> 9296 T307 C54 P60509 /update {wt=javabin&version=2} {add=[87 (1424696383034621952)]} 0 1
[junit4:junit4] 2> 9297 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10082 (1424696383035670528)]} 0 0
[junit4:junit4] 2> 9298 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[73 (-1424696383036719104)]} 0 0
[junit4:junit4] 2> 9299 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10083 (1424696383037767680)]} 0 0
[junit4:junit4] 2> 9300 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[73 (-1424696383037767680)]} 0 1
[junit4:junit4] 2> 9302 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10083 (1424696383039864832)]} 0 1
[junit4:junit4] 2> 9303 T288 C53 P50017 /update {wt=javabin&version=2} {add=[88 (1424696383039864832)]} 0 2
[junit4:junit4] 2> 9304 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10084 (1424696383041961984)]} 0 1
[junit4:junit4] 2> 9306 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10084 (1424696383045107712)]} 0 0
[junit4:junit4] 2> 9307 T308 C54 P60509 /update {wt=javabin&version=2} {add=[88 (1424696383044059136)]} 0 2
[junit4:junit4] 2> 9308 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10071 (-1424696383047204864)]} 0 0
[junit4:junit4] 2> 9309 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[74 (-1424696383048253440)]} 0 0
[junit4:junit4] 2> 9310 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10071 (-1424696383049302016)]} 0 0
[junit4:junit4] 2> 9311 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[74 (-1424696383050350592)]} 0 0
[junit4:junit4] 2> 9313 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10085 (1424696383051399168)]} 0 1
[junit4:junit4] 2> 9313 T291 C53 P50017 /update {wt=javabin&version=2} {add=[89 (1424696383051399169)]} 0 1
[junit4:junit4] 2> 9315 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10085 (1424696383053496320)]} 0 1
[junit4:junit4] 2> 9315 T307 C54 P60509 /update {wt=javabin&version=2} {add=[89 (1424696383054544896)]} 0 1
[junit4:junit4] 2> 9317 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10086 (1424696383056642048)]} 0 0
[junit4:junit4] 2> 9318 T289 C53 P50017 /update {wt=javabin&version=2} {add=[90 (1424696383056642049)]} 0 1
[junit4:junit4] 2> 9319 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10086 (1424696383058739200)]} 0 0
[junit4:junit4] 2> 9320 T308 C54 P60509 /update {wt=javabin&version=2} {add=[90 (1424696383058739201)]} 0 1
[junit4:junit4] 2> 9321 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10072 (-1424696383060836352)]} 0 0
[junit4:junit4] 2> 9322 T291 C53 P50017 /update {wt=javabin&version=2} {add=[91 (1424696383061884928)]} 0 1
[junit4:junit4] 2> 9323 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10072 (-1424696383062933504)]} 0 0
[junit4:junit4] 2> 9324 T308 C54 P60509 /update {wt=javabin&version=2} {add=[91 (1424696383063982080)]} 0 0
[junit4:junit4] 2> 9325 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10087 (1424696383065030656)]} 0 0
[junit4:junit4] 2> 9326 T289 C53 P50017 /update {wt=javabin&version=2} {add=[92 (1424696383066079232)]} 0 0
[junit4:junit4] 2> 9328 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10087 (1424696383067127808)]} 0 1
[junit4:junit4] 2> 9328 T309 C54 P60509 /update {wt=javabin&version=2} {add=[92 (1424696383068176384)]} 0 0
[junit4:junit4] 2> 9329 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10073 (-1424696383069224960)]} 0 0
[junit4:junit4] 2> 9330 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[75 (-1424696383070273536)]} 0 0
[junit4:junit4] 2> 9331 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10073 (-1424696383071322112)]} 0 0
[junit4:junit4] 2> 9332 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[75 (-1424696383072370688)]} 0 0
[junit4:junit4] 2> 9333 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10088 (1424696383073419264)]} 0 0
[junit4:junit4] 2> 9335 T289 C53 P50017 /update {wt=javabin&version=2} {add=[93 (1424696383074467840)]} 0 2
[junit4:junit4] 2> 9336 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10088 (1424696383075516416)]} 0 1
[junit4:junit4] 2> 9337 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10074 (-1424696383077613568)]} 0 0
[junit4:junit4] 2> 9338 T308 C54 P60509 /update {wt=javabin&version=2} {add=[93 (1424696383077613568)]} 0 1
[junit4:junit4] 2> 9339 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10074 (-1424696383079710720)]} 0 0
[junit4:junit4] 2> 9339 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[76 (-1424696383079710720)]} 0 0
[junit4:junit4] 2> 9341 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[76 (-1424696383081807872)]} 0 0
[junit4:junit4] 2> 9342 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10089 (1424696383081807872)]} 0 1
[junit4:junit4] 2> 9343 T289 C53 P50017 /update {wt=javabin&version=2} {add=[94 (1424696383083905024)]} 0 0
[junit4:junit4] 2> 9344 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10089 (1424696383084953600)]} 0 1
[junit4:junit4] 2> 9346 T307 C54 P60509 /update {wt=javabin&version=2} {add=[94 (1424696383086002176)]} 0 1
[junit4:junit4] 2> 9346 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10090 (1424696383087050752)]} 0 0
[junit4:junit4] 2> 9348 T291 C53 P50017 /update {wt=javabin&version=2} {add=[95 (1424696383089147904)]} 0 1
[junit4:junit4] 2> 9348 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10090 (1424696383089147904)]} 0 0
[junit4:junit4] 2> 9350 T308 C54 P60509 /update {wt=javabin&version=2} {add=[95 (1424696383091245056)]} 0 0
[junit4:junit4] 2> 9351 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10091 (1424696383091245056)]} 0 1
[junit4:junit4] 2> 9352 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[81 (-1424696383093342208)]} 0 0
[junit4:junit4] 2> 9354 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[81 (-1424696383095439360)]} 0 0
[junit4:junit4] 2> 9354 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10091 (1424696383093342208)]} 0 2
[junit4:junit4] 2> 9356 T288 C53 P50017 /update {wt=javabin&version=2} {add=[96 (1424696383097536512)]} 0 0
[junit4:junit4] 2> 9356 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10092 (1424696383097536513)]} 0 0
[junit4:junit4] 2> 9359 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10092 (1424696383099633665)]} 0 1
[junit4:junit4] 2> 9359 T308 C54 P60509 /update {wt=javabin&version=2} {add=[96 (1424696383099633664)]} 0 1
[junit4:junit4] 2> 9360 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10075 (-1424696383101730816)]} 0 0
[junit4:junit4] 2> 9361 T289 C53 P50017 /update {wt=javabin&version=2} {add=[97 (1424696383102779392)]} 0 0
[junit4:junit4] 2> 9362 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10075 (-1424696383103827968)]} 0 0
[junit4:junit4] 2> 9363 T309 C54 P60509 /update {wt=javabin&version=2} {add=[97 (1424696383104876544)]} 0 0
[junit4:junit4] 2> 9365 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10093 (1424696383105925120)]} 0 1
[junit4:junit4] 2> 9365 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[82 (-1424696383106973696)]} 0 0
[junit4:junit4] 2> 9367 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10093 (1424696383108022272)]} 0 1
[junit4:junit4] 2> 9367 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[82 (-1424696383109070848)]} 0 0
[junit4:junit4] 2> 9369 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10076 (-1424696383110119424)]} 0 1
[junit4:junit4] 2> 9369 T289 C53 P50017 /update {wt=javabin&version=2} {add=[98 (1424696383111168000)]} 0 0
[junit4:junit4] 2> 9370 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10076 (-1424696383112216576)]} 0 0
[junit4:junit4] 2> 9371 T309 C54 P60509 /update {wt=javabin&version=2} {add=[98 (1424696383113265152)]} 0 0
[junit4:junit4] 2> 9373 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10094 (1424696383114313728)]} 0 1
[junit4:junit4] 2> 9373 T291 C53 P50017 /update {wt=javabin&version=2} {add=[99 (1424696383115362304)]} 0 0
[junit4:junit4] 2> 9375 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10094 (1424696383116410880)]} 0 1
[junit4:junit4] 2> 9375 T308 C54 P60509 /update {wt=javabin&version=2} {add=[99 (1424696383117459456)]} 0 0
[junit4:junit4] 2> 9377 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10095 (1424696383119556608)]} 0 1
[junit4:junit4] 2> 9377 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[83 (-1424696383119556609)]} 0 0
[junit4:junit4] 2> 9379 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[83 (-1424696383121653761)]} 0 0
[junit4:junit4] 2> 9381 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10095 (1424696383121653760)]} 0 2
[junit4:junit4] 2> 9382 T288 C53 P50017 /update {wt=javabin&version=2} {add=[100 (1424696383123750912)]} 0 1
[junit4:junit4] 2> 9383 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10081 (-1424696383125848064)]} 0 0
[junit4:junit4] 2> 9384 T308 C54 P60509 /update {wt=javabin&version=2} {add=[100 (1424696383125848064)]} 0 1
[junit4:junit4] 2> 9385 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10081 (-1424696383127945216)]} 0 1
[junit4:junit4] 2> 9387 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10096 (1424696383128993792)]} 0 1
[junit4:junit4] 2> 9388 T290 C53 P50017 /update {wt=javabin&version=2} {add=[101 (1424696383127945216)]} 0 3
[junit4:junit4] 2> 9390 T308 C54 P60509 /update {wt=javabin&version=2} {add=[101 (1424696383133188096)]} 0 1
[junit4:junit4] 2> 9390 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10096 (1424696383132139520)]} 0 1
[junit4:junit4] 2> 9392 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[84 (-1424696383135285248)]} 0 0
[junit4:junit4] 2> 9393 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10097 (1424696383135285249)]} 0 1
[junit4:junit4] 2> 9394 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[84 (-1424696383137382400)]} 0 0
[junit4:junit4] 2> 9395 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10097 (1424696383138430976)]} 0 1
[junit4:junit4] 2> 9396 T289 C53 P50017 /update {wt=javabin&version=2} {add=[102 (1424696383139479552)]} 0 0
[junit4:junit4] 2> 9397 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[10082 (-1424696383140528128)]} 0 0
[junit4:junit4] 2> 9398 T309 C54 P60509 /update {wt=javabin&version=2} {add=[102 (1424696383141576704)]} 0 0
[junit4:junit4] 2> 9398 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10082 (-1424696383141576705)]} 0 0
[junit4:junit4] 2> 9400 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[87 (-1424696383143673857)]} 0 0
[junit4:junit4] 2> 9401 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10098 (1424696383143673856)]} 0 1
[junit4:junit4] 2> 9402 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[87 (-1424696383145771008)]} 0 0
[junit4:junit4] 2> 9403 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10098 (1424696383145771009)]} 0 1
[junit4:junit4] 2> 9404 T289 C53 P50017 /update {wt=javabin&version=2} {add=[103 (1424696383147868160)]} 0 0
[junit4:junit4] 2> 9405 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10099 (1424696383148916736)]} 0 0
[junit4:junit4] 2> 9407 T307 C54 P60509 /update {wt=javabin&version=2} {add=[103 (1424696383149965312)]} 0 1
[junit4:junit4] 2> 9407 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10099 (1424696383151013888)]} 0 0
[junit4:junit4] 2> 9409 T291 C53 P50017 /update {wt=javabin&version=2} {add=[104 (1424696383152062464)]} 0 1
[junit4:junit4] 2> 9409 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[10083 (-1424696383153111040)]} 0 0
[junit4:junit4] 2> 9411 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10083 (-1424696383155208192)]} 0 0
[junit4:junit4] 2> 9412 T309 C54 P60509 /update {wt=javabin&version=2} {add=[104 (1424696383155208193)]} 0 1
[junit4:junit4] 2> 9413 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[88 (-1424696383157305345)]} 0 0
[junit4:junit4] 2> 9414 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10100 (1424696383157305344)]} 0 1
[junit4:junit4] 2> 9415 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[88 (-1424696383159402496)]} 0 0
[junit4:junit4] 2> 9417 T291 C53 P50017 /update {wt=javabin&version=2} {add=[105 (1424696383161499648)]} 0 0
[junit4:junit4] 2> 9418 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10100 (1424696383160451072)]} 0 3
[junit4:junit4] 2> 9420 T309 C54 P60509 /update {wt=javabin&version=2} {add=[105 (1424696383163596800)]} 0 1
[junit4:junit4] 2> 9420 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10101 (1424696383164645376)]} 0 1
[junit4:junit4] 2> 9422 T290 C53 P50017 /update {wt=javabin&version=2} {add=[106 (1424696383166742528)]} 0 1
[junit4:junit4] 2> 9422 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10101 (1424696383166742528)]} 0 0
[junit4:junit4] 2> 9424 T307 C54 P60509 /update {wt=javabin&version=2} {add=[106 (1424696383168839680)]} 0 0
[junit4:junit4] 2> 9424 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10084 (-1424696383168839680)]} 0 0
[junit4:junit4] 2> 9426 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10084 (-1424696383170936832)]} 0 0
[junit4:junit4] 2> 9428 T291 C53 P50017 /update {wt=javabin&version=2} {add=[107 (1424696383170936832)]} 0 2
[junit4:junit4] 2> 9428 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10102 (1424696383173033984)]} 0 0
[junit4:junit4] 2> 9430 T308 C54 P60509 /update {wt=javabin&version=2} {add=[107 (1424696383175131136)]} 0 0
[junit4:junit4] 2> 9431 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10102 (1424696383175131137)]} 0 1
[junit4:junit4] 2> 9432 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10087 (-1424696383177228289)]} 0 0
[junit4:junit4] 2> 9433 T290 C53 P50017 /update {wt=javabin&version=2} {add=[108 (1424696383177228288)]} 0 1
[junit4:junit4] 2> 9434 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10087 (-1424696383179325440)]} 0 0
[junit4:junit4] 2> 9435 T308 C54 P60509 /update {wt=javabin&version=2} {add=[108 (1424696383180374016)]} 0 0
[junit4:junit4] 2> 9437 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10103 (1424696383181422592)]} 0 1
[junit4:junit4] 2> 9439 T288 C53 P50017 /update {wt=javabin&version=2} {add=[109 (1424696383182471168)]} 0 2
[junit4:junit4] 2> 9439 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10103 (1424696383184568320)]} 0 1
[junit4:junit4] 2> 9441 T309 C54 P60509 /update {wt=javabin&version=2} {add=[109 (1424696383186665472)]} 0 0
[junit4:junit4] 2> 9441 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10104 (1424696383186665472)]} 0 0
[junit4:junit4] 2> 9443 T290 C53 P50017 /update {wt=javabin&version=2} {add=[110 (1424696383188762624)]} 0 0
[junit4:junit4] 2> 9444 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10104 (1424696383188762624)]} 0 1
[junit4:junit4] 2> 9446 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10088 (-1424696383190859776)]} 0 1
[junit4:junit4] 2> 9446 T307 C54 P60509 /update {wt=javabin&version=2} {add=[110 (1424696383190859776)]} 0 1
[junit4:junit4] 2> 9447 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10088 (-1424696383192956928)]} 0 0
[junit4:junit4] 2> 9448 T288 C53 P50017 /update {wt=javabin&version=2} {add=[111 (1424696383192956928)]} 0 1
[junit4:junit4] 2> 9450 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10105 (1424696383195054080)]} 0 1
[junit4:junit4] 2> 9450 T308 C54 P60509 /update {wt=javabin&version=2} {add=[111 (1424696383196102656)]} 0 0
[junit4:junit4] 2> 9452 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[89 (-1424696383198199808)]} 0 0
[junit4:junit4] 2> 9453 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10105 (1424696383197151232)]} 0 2
[junit4:junit4] 2> 9454 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[89 (-1424696383200296960)]} 0 0
[junit4:junit4] 2> 9455 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10106 (1424696383201345536)]} 0 0
[junit4:junit4] 2> 9456 T289 C53 P50017 /update {wt=javabin&version=2} {add=[112 (1424696383202394112)]} 0 0
[junit4:junit4] 2> 9458 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10106 (1424696383203442688)]} 0 1
[junit4:junit4] 2> 9458 T307 C54 P60509 /update {wt=javabin&version=2} {add=[112 (1424696383204491264)]} 0 0
[junit4:junit4] 2> 9460 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10107 (1424696383205539840)]} 0 1
[junit4:junit4] 2> 9460 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[90 (-1424696383206588416)]} 0 0
[junit4:junit4] 2> 9462 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[90 (-1424696383208685569)]} 0 0
[junit4:junit4] 2> 9462 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10107 (1424696383208685568)]} 0 0
[junit4:junit4] 2> 9464 T288 C53 P50017 /update {wt=javabin&version=2} {add=[113 (1424696383210782720)]} 0 0
[junit4:junit4] 2> 9464 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10108 (1424696383210782721)]} 0 0
[junit4:junit4] 2> 9466 T307 C54 P60509 /update {wt=javabin&version=2} {add=[113 (1424696383212879872)]} 0 0
[junit4:junit4] 2> 9467 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10108 (1424696383212879873)]} 0 1
[junit4:junit4] 2> 9468 T290 C53 P50017 /update {wt=javabin&version=2} {delete=[91 (-1424696383214977024)]} 0 0
[junit4:junit4] 2> 9469 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10109 (1424696383216025600)]} 0 0
[junit4:junit4] 2> 9470 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[91 (-1424696383217074176)]} 0 0
[junit4:junit4] 2> 9471 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10109 (1424696383218122752)]} 0 0
[junit4:junit4] 2> 9472 T288 C53 P50017 /update {wt=javabin&version=2} {add=[114 (1424696383219171328)]} 0 0
[junit4:junit4] 2> 9475 T307 C54 P60509 /update {wt=javabin&version=2} {add=[114 (1424696383221268480)]} 0 1
[junit4:junit4] 2> 9475 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10110 (1424696383220219904)]} 0 2
[junit4:junit4] 2> 9477 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10110 (1424696383224414208)]} 0 0
[junit4:junit4] 2> 9478 T290 C53 P50017 /update {wt=javabin&version=2} {add=[115 (1424696383224414208)]} 0 1
[junit4:junit4] 2> 9479 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10111 (1424696383226511360)]} 0 0
[junit4:junit4] 2> 9480 T308 C54 P60509 /update {wt=javabin&version=2} {add=[115 (1424696383227559936)]} 0 0
[junit4:junit4] 2> 9482 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10111 (1424696383228608512)]} 0 1
[junit4:junit4] 2> 9482 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[93 (-1424696383229657088)]} 0 0
[junit4:junit4] 2> 9483 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10089 (-1424696383230705664)]} 0 0
[junit4:junit4] 2> 9484 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[93 (-1424696383231754240)]} 0 0
[junit4:junit4] 2> 9485 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10089 (-1424696383232802816)]} 0 0
[junit4:junit4] 2> 9486 T290 C53 P50017 /update {wt=javabin&version=2} {add=[116 (1424696383233851392)]} 0 0
[junit4:junit4] 2> 9487 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10112 (1424696383234899968)]} 0 0
[junit4:junit4] 2> 9488 T307 C54 P60509 /update {wt=javabin&version=2} {add=[116 (1424696383235948544)]} 0 0
[junit4:junit4] 2> 9489 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10112 (1424696383236997120)]} 0 0
[junit4:junit4] 2> 9490 T288 C53 P50017 /update {wt=javabin&version=2} {add=[117 (1424696383238045696)]} 0 0
[junit4:junit4] 2> 9491 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10090 (-1424696383239094272)]} 0 0
[junit4:junit4] 2> 9493 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10090 (-1424696383241191424)]} 0 0
[junit4:junit4] 2> 9494 T308 C54 P60509 /update {wt=javabin&version=2} {add=[117 (1424696383240142848)]} 0 2
[junit4:junit4] 2> 9495 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10113 (1424696383243288576)]} 0 0
[junit4:junit4] 2> 9496 T291 C53 P50017 /update {wt=javabin&version=2} {add=[118 (1424696383244337152)]} 0 0
[junit4:junit4] 2> 9499 T307 C54 P60509 /update {wt=javabin&version=2} {add=[118 (1424696383246434304)]} 0 1
[junit4:junit4] 2> 9500 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10113 (1424696383245385728)]} 0 3
[junit4:junit4] 2> 9502 T288 C53 P50017 /update {wt=javabin&version=2} {add=[119 (1424696383249580032)]} 0 1
[junit4:junit4] 2> 9502 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10091 (-1424696383250628608)]} 0 0
[junit4:junit4] 2> 9504 T308 C54 P60509 /update {wt=javabin&version=2} {add=[119 (1424696383251677184)]} 0 1
[junit4:junit4] 2> 9504 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[10091 (-1424696383252725760)]} 0 0
[junit4:junit4] 2> 9506 T290 C53 P50017 /update {wt=javabin&version=2} {add=[120 (1424696383254822912)]} 0 1
[junit4:junit4] 2> 9506 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10114 (1424696383254822913)]} 0 0
[junit4:junit4] 2> 9508 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10114 (1424696383256920065)]} 0 0
[junit4:junit4] 2> 9510 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10115 (1424696383259017216)]} 0 0
[junit4:junit4] 2> 9513 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10115 (1424696383261114368)]} 0 1
[junit4:junit4] 2> 9514 T289 C53 P50017 /update {wt=javabin&version=2} {delete=[10093 (-1424696383263211520)]} 0 0
[junit4:junit4] 2> 9516 T308 C54 P60509 /update {wt=javabin&version=2} {delete=[10093 (-1424696383265308672)]} 0 0
[junit4:junit4] 2> 9518 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10116 (1424696383267405824)]} 0 0
[junit4:junit4] 2> 9520 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10116 (1424696383269502976)]} 0 0
[junit4:junit4] 2> 9535 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10117 (1424696383271600128)]} 0 13
[junit4:junit4] 2> 9538 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10117 (1424696383287328768)]} 0 1
[junit4:junit4] 2> 9541 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10118 (1424696383290474496)]} 0 1
[junit4:junit4] 2> 9543 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10118 (1424696383293620224)]} 0 1
[junit4:junit4] 2> 9546 T289 C53 P50017 /update {wt=javabin&version=2} {add=[10119 (1424696383295717376)]} 0 1
[junit4:junit4] 2> 9548 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10119 (1424696383297814528)]} 0 1
[junit4:junit4] 2> 9550 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10120 (1424696383299911680)]} 0 1
[junit4:junit4] 2> 9552 T309 C54 P60509 /update {wt=javabin&version=2} {add=[120 (1424696383256920064)]} 0 44
[junit4:junit4] 2> 9552 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10120 (1424696383303057408)]} 0 0
[junit4:junit4] 2> 9554 T288 C53 P50017 /update {wt=javabin&version=2} {add=[10121 (1424696383305154561)]} 0 0
[junit4:junit4] 2> 9554 T291 C53 P50017 /update {wt=javabin&version=2} {add=[121 (1424696383305154560)]} 0 0
[junit4:junit4] 2> 9557 T309 C54 P60509 /update {wt=javabin&version=2} {add=[121 (1424696383307251713)]} 0 1
[junit4:junit4] 2> 9576 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10121 (1424696383307251712)]} 0 20
[junit4:junit4] 2> 9576 T289 C53 P50017 /update {wt=javabin&version=2} {add=[122 (1424696383327174656)]} 0 1
[junit4:junit4] 2> 9579 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10122 (1424696383330320384)]} 0 1
[junit4:junit4] 2> 9579 T307 C54 P60509 /update {wt=javabin&version=2} {add=[122 (1424696383330320384)]} 0 1
[junit4:junit4] 2> 9581 T309 C54 P60509 /update {wt=javabin&version=2} {add=[10122 (1424696383333466112)]} 0 0
[junit4:junit4] 2> 9583 T288 C53 P50017 /update {wt=javabin&version=2} {add=[123 (1424696383333466112)]} 0 2
[junit4:junit4] 2> 9583 T291 C53 P50017 /update {wt=javabin&version=2} {add=[10123 (1424696383335563264)]} 0 0
[junit4:junit4] 2> 9585 T308 C54 P60509 /update {wt=javabin&version=2} {add=[123 (1424696383337660416)]} 0 1
[junit4:junit4] 2> 9586 T307 C54 P60509 /update {wt=javabin&version=2} {add=[10123 (1424696383337660417)]} 0 1
[junit4:junit4] 2> 9587 T289 C53 P50017 /update {wt=javabin&version=2} {add=[124 (1424696383339757568)]} 0 0
[junit4:junit4] 2> 9588 T290 C53 P50017 /update {wt=javabin&version=2} {add=[10124 (1424696383339757569)]} 0 1
[junit4:junit4] 2> 9589 T309 C54 P60509 /update {wt=javabin&version=2} {add=[124 (1424696383341854720)]} 0 0
[junit4:junit4] 2> 9590 T308 C54 P60509 /update {wt=javabin&version=2} {add=[10124 (1424696383341854721)]} 0 1
[junit4:junit4] 2> 9591 T288 C53 P50017 /update {wt=javabin&version=2} {delete=[95 (-1424696383343951872)]} 0 0
[junit4:junit4] 2> 9592 T291 C53 P50017 /update {wt=javabin&version=2} {delete=[10095 (-1424696383345000448)]} 0 0
[junit4:junit4] 2> 9593 T307 C54 P60509 /update {wt=javabin&version=2} {delete=[95 (-1424696383346049024)]} 0 0
[junit4:junit4] 2> 9593 T309 C54 P60509 /update {wt=javabin&version=2} {delete=[10095 (-1424696383346049025)]} 0 0
[junit4:junit4] 2> 9595 T289 C53 P50017 /up
[...truncated too long message...]
oud.RecoveryZkTest-1358696339885/jetty2/index.20130120213931571
[junit4:junit4] 2> 41342 T273 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 41346 T273 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> 41355 T272 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4:junit4] 2> 41419 T474 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2/index
[junit4:junit4] 2> 41419 T474 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1358696339885/jetty2/index
[junit4:junit4] 2> 41420 T474 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 41420 T474 oasc.LeaderElector$1.process WARNING org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard1/election/89043523335553034-127.0.0.1:39136_h%2Fjy_collection1-n_0000000002
[junit4:junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4:junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4:junit4] 2> at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:873)
[junit4:junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:153)
[junit4:junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:150)
[junit4:junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4:junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:150)
[junit4:junit4] 2> at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:63)
[junit4:junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:243)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:156)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:100)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:129)
[junit4:junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4:junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4:junit4] 2>
[junit4:junit4] 2> 41420 T474 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 41421 T474 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 41421 T474 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 41421 T474 oasc.LeaderElector$1.process WARNING org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4:junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4:junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4:junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
[junit4:junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:236)
[junit4:junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:233)
[junit4:junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4:junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:233)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:84)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:129)
[junit4:junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4:junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4:junit4] 2>
[junit4:junit4] 2> 41421 T474 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=vi, timezone=BST
[junit4:junit4] 2> NOTE: Linux 3.2.0-36-generic i386/Oracle Corporation 1.7.0_10 (32-bit)/cpus=8,threads=1,free=195730664,total=269484032
[junit4:junit4] 2> NOTE: All tests run in this JVM: [IndexSchemaRuntimeFieldTest, TestComponentsName, TestRemoteStreaming, SoftAutoCommitTest, TestPostingsSolrHighlighter, SolrCoreTest, TestQueryUtils, TestHashPartitioner, ResponseLogComponentTest, DisMaxRequestHandlerTest, DirectUpdateHandlerTest, QueryEqualityTest, ZkNodePropsTest, TestReversedWildcardFilterFactory, TestDistributedSearch, TermVectorComponentTest, URLClassifyProcessorTest, TestLRUCache, ClusterStateTest, TestReload, QueryParsingTest, RecoveryZkTest]
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RecoveryZkTest -Dtests.seed=63E6FA91B07D4C69 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=vi -Dtests.timezone=BST -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR 0.00s J1 | RecoveryZkTest (suite) <<<
[junit4:junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=523, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([63E6FA91B07D4C69]:0)
[junit4:junit4] > at java.lang.Thread.getStackTrace(Thread.java:1567)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:517)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:983)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:875)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:867)
[junit4:junit4] > at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
[junit4:junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
[junit4:junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
[junit4:junit4] > at org.apache.solr.handler.SnapPuller.downloadIndexFiles(SnapPuller.java:731)
[junit4:junit4] > at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:395)
[junit4:junit4] > at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:274)
[junit4:junit4] > at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:153)
[junit4:junit4] > at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:409)
[junit4:junit4] > at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:223)
[junit4:junit4] > Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.RAMDirectory@fc29e0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@415726)
[junit4:junit4] > at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4] > at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] Completed on J1 in 46.37s, 1 test, 1 error <<< FAILURES!
[...truncated 565 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:344: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:428: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1176: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:841: There were test failures: 248 suites, 1034 tests, 1 suite-level error, 9 ignored (4 assumptions)
Total time: 31 minutes 7 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.7.0_10 -client -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure