You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@zookeeper.apache.org by Apache Hudson Server <hu...@hudson.apache.org> on 2010/09/10 12:51:53 UTC
Build failed in Hudson: ZooKeeper-trunk #931
See <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/931/>
------------------------------------------
[...truncated 170997 lines...]
[junit] 2010-09-10 10:55:12,074 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11237:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:33203
[junit] 2010-09-10 10:55:12,074 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11237:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:33203
[junit] 2010-09-10 10:55:12,074 [myid:] - INFO [Thread-330:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-10 10:55:12,075 [myid:] - INFO [Thread-330:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:33203 (no session established for client)
[junit] 2010-09-10 10:55:12,075 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11237 is accepting client connections
[junit] 2010-09-10 10:55:12,075 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11238
[junit] 2010-09-10 10:55:12,076 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:51673
[junit] 2010-09-10 10:55:12,076 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:51673
[junit] 2010-09-10 10:55:12,076 [myid:] - INFO [Thread-331:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-10 10:55:12,077 [myid:] - INFO [Thread-331:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:51673 (no session established for client)
[junit] 2010-09-10 10:55:12,078 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11238 is accepting client connections
[junit] 2010-09-10 10:55:12,078 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-10 10:55:12,078 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35239
[junit] 2010-09-10 10:55:12,078 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35239
[junit] 2010-09-10 10:55:12,079 [myid:] - INFO [Thread-332:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35239 (no session established for client)
[junit] 2010-09-10 10:55:12,329 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-10 10:55:12,330 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35240
[junit] 2010-09-10 10:55:12,330 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35240
[junit] 2010-09-10 10:55:12,330 [myid:] - INFO [Thread-333:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35240 (no session established for client)
[junit] 2010-09-10 10:55:12,581 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-10 10:55:12,581 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35241
[junit] 2010-09-10 10:55:12,581 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35241
[junit] 2010-09-10 10:55:12,582 [myid:] - INFO [Thread-334:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35241 (no session established for client)
[junit] 2010-09-10 10:55:12,832 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-10 10:55:12,832 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35242
[junit] 2010-09-10 10:55:12,833 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35242
[junit] 2010-09-10 10:55:12,833 [myid:] - INFO [Thread-335:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35242 (no session established for client)
[junit] 2010-09-10 10:55:13,083 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-10 10:55:13,084 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35243
[junit] 2010-09-10 10:55:13,084 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35243
[junit] 2010-09-10 10:55:13,084 [myid:] - INFO [Thread-336:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-10 10:55:13,085 [myid:] - INFO [Thread-336:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35243 (no session established for client)
[junit] JMXEnv.dump() follows
[junit] 2010-09-10 10:55:13,085 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11239 is accepting client connections
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower,name3=InMemoryDataTree
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1,name2=
[junit] found:name0=ReplicatedServer_id1,name1=replica.1,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2,name2=
[junit] found:name0=ReplicatedServer_id2,name1=replica.2,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3,name2=
[junit] found:name0=ReplicatedServer_id3,name1=replica.3,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4,name2=
[junit] found:name0=ReplicatedServer_id4,name1=replica.4,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5,name2=
[junit] found:name0=ReplicatedServer_id5,name1=replica.5,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1
[junit] found:name0=ReplicatedServer_id1,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.2
[junit] found:name0=ReplicatedServer_id1,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] expect:name0=ReplicatedServer_id1,name1=replica.3
[junit] found:name0=ReplicatedServer_id1,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] expect:name0=ReplicatedServer_id1,name1=replica.4
[junit] found:name0=ReplicatedServer_id1,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] expect:name0=ReplicatedServer_id1,name1=replica.5
[junit] found:name0=ReplicatedServer_id1,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] expect:name0=ReplicatedServer_id2,name1=replica.1
[junit] found:name0=ReplicatedServer_id2,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2
[junit] found:name0=ReplicatedServer_id2,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] expect:name0=ReplicatedServer_id2,name1=replica.3
[junit] found:name0=ReplicatedServer_id2,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] expect:name0=ReplicatedServer_id2,name1=replica.4
[junit] found:name0=ReplicatedServer_id2,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] expect:name0=ReplicatedServer_id2,name1=replica.5
[junit] found:name0=ReplicatedServer_id2,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] expect:name0=ReplicatedServer_id3,name1=replica.1
[junit] found:name0=ReplicatedServer_id3,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] expect:name0=ReplicatedServer_id3,name1=replica.2
[junit] found:name0=ReplicatedServer_id3,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3
[junit] found:name0=ReplicatedServer_id3,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] expect:name0=ReplicatedServer_id3,name1=replica.4
[junit] found:name0=ReplicatedServer_id3,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] expect:name0=ReplicatedServer_id3,name1=replica.5
[junit] found:name0=ReplicatedServer_id3,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] expect:name0=ReplicatedServer_id4,name1=replica.1
[junit] found:name0=ReplicatedServer_id4,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] expect:name0=ReplicatedServer_id4,name1=replica.2
[junit] found:name0=ReplicatedServer_id4,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] expect:name0=ReplicatedServer_id4,name1=replica.3
[junit] found:name0=ReplicatedServer_id4,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4
[junit] found:name0=ReplicatedServer_id4,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] 2010-09-10 10:55:13,096 [myid:] - INFO [main:QuorumBase@109] - Initial fdcount is: 99
[junit] 2010-09-10 10:55:13,096 [myid:] - INFO [main:QuorumBase@113] - Setup finished
[junit] 2010-09-10 10:55:13,096 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testNull
[junit] 2010-09-10 10:55:13,096 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testNull
[junit] 2010-09-10 10:55:13,097 [myid:] - INFO [main:ZkDatabaseCorruptionTest@56] - STOPPING quorum org.apache.zookeeper.test.ZkDatabaseCorruptionTest
[junit] 2010-09-10 10:55:13,097 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testNull
[junit] 2010-09-10 10:55:13,097 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testNull
[junit] expect:name0=ReplicatedServer_id4,name1=replica.5
[junit] found:name0=ReplicatedServer_id4,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] expect:name0=ReplicatedServer_id5,name1=replica.1
[junit] found:name0=ReplicatedServer_id5,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] expect:name0=ReplicatedServer_id5,name1=replica.2
[junit] found:name0=ReplicatedServer_id5,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] expect:name0=ReplicatedServer_id5,name1=replica.3
[junit] found:name0=ReplicatedServer_id5,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] expect:name0=ReplicatedServer_id5,name1=replica.4
[junit] found:name0=ReplicatedServer_id5,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5
[junit] found:name0=ReplicatedServer_id5,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] expect:name0=ReplicatedServer_id1
[junit] found:name0=ReplicatedServer_id1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2
[junit] found:name0=ReplicatedServer_id2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] expect:name0=ReplicatedServer_id3
[junit] found:name0=ReplicatedServer_id3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] expect:name0=ReplicatedServer_id4
[junit] found:name0=ReplicatedServer_id4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] expect:name0=ReplicatedServer_id5
[junit] found:name0=ReplicatedServer_id5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 29.68 sec
[junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest
[junit] 2010-09-10 10:55:13,895 [myid:] - INFO [main:PortAssignment@31] - assigning port 11221
[junit] 2010-09-10 10:55:13,900 [myid:] - INFO [main:ZKTestCase$1@49] - STARTING testQuota
[junit] 2010-09-10 10:55:13,903 [myid:] - INFO [main:ClientBase@407] - Initial fdcount is: 25
[junit] 2010-09-10 10:55:14,085 [myid:] - INFO [main:ClientBase@425] - STARTING server
[junit] 2010-09-10 10:55:14,097 [myid:] - INFO [main:Environment@97] - Server environment:zookeeper.version=3.4.0-995736, built on 09/10/2010 10:35 GMT
[junit] 2010-09-10 10:55:14,097 [myid:] - INFO [main:Environment@97] - Server environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-10 10:55:14,098 [myid:] - INFO [main:Environment@97] - Server environment:java.version=1.6.0_11
[junit] 2010-09-10 10:55:14,098 [myid:] - INFO [main:Environment@97] - Server environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-10 10:55:14,099 [myid:] - INFO [main:Environment@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-10 10:55:14,100 [myid:] - INFO [main:Environment@97] - Server environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-10 10:55:14,100 [myid:] - INFO [main:Environment@97] - Server environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-10 10:55:14,101 [myid:] - INFO [main:Environment@97] - Server environment:java.io.tmpdir=/tmp
[junit] 2010-09-10 10:55:14,101 [myid:] - INFO [main:Environment@97] - Server environment:java.compiler=<NA>
[junit] 2010-09-10 10:55:14,102 [myid:] - INFO [main:Environment@97] - Server environment:os.name=Linux
[junit] 2010-09-10 10:55:14,102 [myid:] - INFO [main:Environment@97] - Server environment:os.arch=i386
[junit] 2010-09-10 10:55:14,103 [myid:] - INFO [main:Environment@97] - Server environment:os.version=2.6.28-18-generic
[junit] 2010-09-10 10:55:14,103 [myid:] - INFO [main:Environment@97] - Server environment:user.name=hudson
[junit] 2010-09-10 10:55:14,104 [myid:] - INFO [main:Environment@97] - Server environment:user.home=/homes/hudson
[junit] 2010-09-10 10:55:14,104 [myid:] - INFO [main:Environment@97] - Server environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-10 10:55:14,129 [myid:] - INFO [main:ZooKeeperServer@153] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test814065271874930060.junit.dir/version-2> snapdir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test814065271874930060.junit.dir/version-2>
[junit] 2010-09-10 10:55:14,145 [myid:] - INFO [main:NIOServerCnxnFactory@93] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2010-09-10 10:55:14,152 [myid:] - INFO [main:FileTxnSnapLog@208] - Snapshotting: 0
[junit] 2010-09-10 10:55:14,189 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] 2010-09-10 10:55:14,192 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:36071
[junit] 2010-09-10 10:55:14,201 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:36071
[junit] 2010-09-10 10:55:14,202 [myid:] - INFO [Thread-3:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-10 10:55:14,204 [myid:] - INFO [Thread-3:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:36071 (no session established for client)
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2010-09-10 10:55:14,210 [myid:] - INFO [main:ClientBase@421] - Client test setup finished
[junit] 2010-09-10 10:55:14,211 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testQuota
[junit] 2010-09-10 10:55:14,219 [myid:] - INFO [main:Environment@97] - Client environment:zookeeper.version=3.4.0-995736, built on 09/10/2010 10:35 GMT
[junit] 2010-09-10 10:55:14,219 [myid:] - INFO [main:Environment@97] - Client environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-10 10:55:14,220 [myid:] - INFO [main:Environment@97] - Client environment:java.version=1.6.0_11
[junit] 2010-09-10 10:55:14,220 [myid:] - INFO [main:Environment@97] - Client environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-10 10:55:14,221 [myid:] - INFO [main:Environment@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-10 10:55:14,221 [myid:] - INFO [main:Environment@97] - Client environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-10 10:55:14,222 [myid:] - INFO [main:Environment@97] - Client environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-10 10:55:14,222 [myid:] - INFO [main:Environment@97] - Client environment:java.io.tmpdir=/tmp
[junit] 2010-09-10 10:55:14,223 [myid:] - INFO [main:Environment@97] - Client environment:java.compiler=<NA>
[junit] 2010-09-10 10:55:14,223 [myid:] - INFO [main:Environment@97] - Client environment:os.name=Linux
[junit] 2010-09-10 10:55:14,224 [myid:] - INFO [main:Environment@97] - Client environment:os.arch=i386
[junit] 2010-09-10 10:55:14,224 [myid:] - INFO [main:Environment@97] - Client environment:os.version=2.6.28-18-generic
[junit] 2010-09-10 10:55:14,225 [myid:] - INFO [main:Environment@97] - Client environment:user.name=hudson
[junit] 2010-09-10 10:55:14,225 [myid:] - INFO [main:Environment@97] - Client environment:user.home=/homes/hudson
[junit] 2010-09-10 10:55:14,226 [myid:] - INFO [main:Environment@97] - Client environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-10 10:55:14,227 [myid:] - INFO [main:ZooKeeper@375] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@1c7e2da
[junit] 2010-09-10 10:55:14,243 [myid:] - INFO [main-SendThread():ClientCnxn$SendThread@1021] - Opening socket connection to server /127.0.0.1:11221
[junit] 2010-09-10 10:55:14,244 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:36072
[junit] 2010-09-10 10:55:14,245 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@929] - Socket connection established to localhost/127.0.0.1:11221, initiating session
[junit] 2010-09-10 10:55:14,248 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:ZooKeeperServer@801] - Client attempting to establish new session at /127.0.0.1:36072
[junit] 2010-09-10 10:55:14,258 [myid:] - INFO [SyncThread:0:FileTxnLog@197] - Creating new log file: log.1
[junit] 2010-09-10 10:55:14,283 [myid:] - INFO [SyncThread:0:ZooKeeperServer@577] - Established session 0x12afb48eaf10000 with negotiated timeout 30000 for client /127.0.0.1:36072
[junit] 2010-09-10 10:55:14,283 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@722] - Session establishment complete on server localhost/127.0.0.1:11221, sessionid = 0x12afb48eaf10000, negotiated timeout = 30000
[junit] expect:0x12afb48eaf10000
[junit] found:0x12afb48eaf10000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12afb48eaf10000
[junit] 2010-09-10 10:55:14,336 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testQuota
[junit] 2010-09-10 10:55:14,336 [myid:] - INFO [main:ClientBase@465] - tearDown starting
[junit] 2010-09-10 10:55:14,337 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@386] - Processed session termination for sessionid: 0x12afb48eaf10000
[junit] 2010-09-10 10:55:14,338 [myid:] - INFO [main:ZooKeeper@540] - Session: 0x12afb48eaf10000 closed
[junit] 2010-09-10 10:55:14,339 [myid:] - INFO [main:ClientBase@432] - STOPPING server
[junit] 2010-09-10 10:55:14,339 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:36072 which had sessionid 0x12afb48eaf10000
[junit] 2010-09-10 10:55:14,340 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@223] - NIOServerCnxn factory exited run method
[junit] 2010-09-10 10:55:14,341 [myid:] - INFO [main:ZooKeeperServer@405] - shutting down
[junit] 2010-09-10 10:55:14,341 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2010-09-10 10:55:14,342 [myid:] - INFO [main:PrepRequestProcessor@509] - Shutting down
[junit] 2010-09-10 10:55:14,342 [myid:] - INFO [main:SyncRequestProcessor@169] - Shutting down
[junit] 2010-09-10 10:55:14,342 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@120] - PrepRequestProcessor exited loop!
[junit] 2010-09-10 10:55:14,342 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-09-10 10:55:14,343 [myid:] - INFO [main:FinalRequestProcessor@373] - shutdown of request processor complete
[junit] 2010-09-10 10:55:14,344 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] ensureOnly:[]
[junit] 2010-09-10 10:55:14,347 [myid:] - INFO [main:ClientBase@493] - fdcount after test is: 34 at start it was 25
[junit] 2010-09-10 10:55:14,347 [myid:] - INFO [main:ClientBase@495] - sleeping for 20 secs
[junit] 2010-09-10 10:55:14,348 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testQuota
[junit] 2010-09-10 10:55:14,348 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.508 sec
BUILD FAILED
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:935: The following error occurred while executing this line:
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:846: Tests failed!
Total time: 19 minutes 37 seconds
[WARNINGS] Skipping publisher since build result is FAILURE
[FINDBUGS] Skipping publisher since build result is FAILURE
Publishing Javadoc
Recording fingerprints
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Hudson build is back to normal : ZooKeeper-trunk #936
Posted by Apache Hudson Server <hu...@hudson.apache.org>.
See <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/936/changes>
Build failed in Hudson: ZooKeeper-trunk #935
Posted by Apache Hudson Server <hu...@hudson.apache.org>.
See <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/935/>
------------------------------------------
[...truncated 168881 lines...]
[junit] 2010-09-14 10:53:45,982 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11237:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:36698
[junit] 2010-09-14 10:53:45,983 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11237:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:36698
[junit] 2010-09-14 10:53:45,983 [myid:] - INFO [Thread-286:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-14 10:53:45,984 [myid:] - INFO [Thread-286:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:36698 (no session established for client)
[junit] 2010-09-14 10:53:45,984 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11237 is accepting client connections
[junit] 2010-09-14 10:53:45,984 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11238
[junit] 2010-09-14 10:53:45,985 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:43779
[junit] 2010-09-14 10:53:45,985 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:43779
[junit] 2010-09-14 10:53:45,985 [myid:] - INFO [Thread-287:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-14 10:53:45,986 [myid:] - INFO [Thread-287:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:43779 (no session established for client)
[junit] 2010-09-14 10:53:45,987 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11238 is accepting client connections
[junit] 2010-09-14 10:53:45,987 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-14 10:53:45,987 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:55466
[junit] 2010-09-14 10:53:45,988 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:55466
[junit] 2010-09-14 10:53:45,988 [myid:] - INFO [Thread-288:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:55466 (no session established for client)
[junit] 2010-09-14 10:53:46,238 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-14 10:53:46,239 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:55467
[junit] 2010-09-14 10:53:46,239 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:55467
[junit] 2010-09-14 10:53:46,240 [myid:] - INFO [Thread-289:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:55467 (no session established for client)
[junit] 2010-09-14 10:53:46,490 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-14 10:53:46,490 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:55468
[junit] 2010-09-14 10:53:46,490 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:55468
[junit] 2010-09-14 10:53:46,491 [myid:] - INFO [Thread-290:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:55468 (no session established for client)
[junit] 2010-09-14 10:53:46,741 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-14 10:53:46,741 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:55469
[junit] 2010-09-14 10:53:46,742 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:55469
[junit] 2010-09-14 10:53:46,742 [myid:] - INFO [Thread-291:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:55469 (no session established for client)
[junit] 2010-09-14 10:53:46,992 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-14 10:53:46,993 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:55470
[junit] 2010-09-14 10:53:46,993 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:55470
[junit] 2010-09-14 10:53:46,993 [myid:] - INFO [Thread-292:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-14 10:53:46,994 [myid:] - INFO [Thread-292:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:55470 (no session established for client)
[junit] JMXEnv.dump() follows
[junit] 2010-09-14 10:53:46,994 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11239 is accepting client connections
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower,name3=InMemoryDataTree
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1,name2=
[junit] found:name0=ReplicatedServer_id1,name1=replica.1,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2,name2=
[junit] found:name0=ReplicatedServer_id2,name1=replica.2,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3,name2=
[junit] found:name0=ReplicatedServer_id3,name1=replica.3,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4,name2=
[junit] found:name0=ReplicatedServer_id4,name1=replica.4,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5,name2=
[junit] found:name0=ReplicatedServer_id5,name1=replica.5,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1
[junit] found:name0=ReplicatedServer_id1,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.2
[junit] found:name0=ReplicatedServer_id1,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] expect:name0=ReplicatedServer_id1,name1=replica.3
[junit] found:name0=ReplicatedServer_id1,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] expect:name0=ReplicatedServer_id1,name1=replica.4
[junit] found:name0=ReplicatedServer_id1,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] expect:name0=ReplicatedServer_id1,name1=replica.5
[junit] found:name0=ReplicatedServer_id1,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] expect:name0=ReplicatedServer_id2,name1=replica.1
[junit] found:name0=ReplicatedServer_id2,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2
[junit] found:name0=ReplicatedServer_id2,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] expect:name0=ReplicatedServer_id2,name1=replica.3
[junit] found:name0=ReplicatedServer_id2,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] expect:name0=ReplicatedServer_id2,name1=replica.4
[junit] found:name0=ReplicatedServer_id2,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] expect:name0=ReplicatedServer_id2,name1=replica.5
[junit] found:name0=ReplicatedServer_id2,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] expect:name0=ReplicatedServer_id3,name1=replica.1
[junit] found:name0=ReplicatedServer_id3,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] expect:name0=ReplicatedServer_id3,name1=replica.2
[junit] found:name0=ReplicatedServer_id3,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3
[junit] found:name0=ReplicatedServer_id3,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] expect:name0=ReplicatedServer_id3,name1=replica.4
[junit] found:name0=ReplicatedServer_id3,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] expect:name0=ReplicatedServer_id3,name1=replica.5
[junit] found:name0=ReplicatedServer_id3,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] expect:name0=ReplicatedServer_id4,name1=replica.1
[junit] found:name0=ReplicatedServer_id4,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] expect:name0=ReplicatedServer_id4,name1=replica.2
[junit] found:name0=ReplicatedServer_id4,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] expect:name0=ReplicatedServer_id4,name1=replica.3
[junit] found:name0=ReplicatedServer_id4,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4
[junit] found:name0=ReplicatedServer_id4,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] expect:name0=ReplicatedServer_id4,name1=replica.5
[junit] found:name0=ReplicatedServer_id4,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] expect:name0=ReplicatedServer_id5,name1=replica.1
[junit] found:name0=ReplicatedServer_id5,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] expect:name0=ReplicatedServer_id5,name1=replica.2
[junit] found:name0=ReplicatedServer_id5,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] expect:name0=ReplicatedServer_id5,name1=replica.3
[junit] found:name0=ReplicatedServer_id5,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] expect:name0=ReplicatedServer_id5,name1=replica.4
[junit] found:name0=ReplicatedServer_id5,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5
[junit] found:name0=ReplicatedServer_id5,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] expect:name0=ReplicatedServer_id1
[junit] found:name0=ReplicatedServer_id1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2
[junit] found:name0=ReplicatedServer_id2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] expect:name0=ReplicatedServer_id3
[junit] found:name0=ReplicatedServer_id3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] expect:name0=ReplicatedServer_id4
[junit] found:name0=ReplicatedServer_id4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] expect:name0=ReplicatedServer_id5
[junit] found:name0=ReplicatedServer_id5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] 2010-09-14 10:53:47,006 [myid:] - INFO [main:QuorumBase@109] - Initial fdcount is: 95
[junit] 2010-09-14 10:53:47,006 [myid:] - INFO [main:QuorumBase@113] - Setup finished
[junit] 2010-09-14 10:53:47,006 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testNull
[junit] 2010-09-14 10:53:47,006 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testNull
[junit] 2010-09-14 10:53:47,006 [myid:] - INFO [main:ZkDatabaseCorruptionTest@56] - STOPPING quorum org.apache.zookeeper.test.ZkDatabaseCorruptionTest
[junit] 2010-09-14 10:53:47,006 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testNull
[junit] 2010-09-14 10:53:47,007 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testNull
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 31.631 sec
[junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest
[junit] 2010-09-14 10:53:47,838 [myid:] - INFO [main:PortAssignment@31] - assigning port 11221
[junit] 2010-09-14 10:53:47,842 [myid:] - INFO [main:ZKTestCase$1@49] - STARTING testQuota
[junit] 2010-09-14 10:53:47,845 [myid:] - INFO [main:ClientBase@407] - Initial fdcount is: 25
[junit] 2010-09-14 10:53:48,030 [myid:] - INFO [main:ClientBase@425] - STARTING server
[junit] 2010-09-14 10:53:48,042 [myid:] - INFO [main:Environment@97] - Server environment:zookeeper.version=3.4.0-996819, built on 09/14/2010 10:37 GMT
[junit] 2010-09-14 10:53:48,042 [myid:] - INFO [main:Environment@97] - Server environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-14 10:53:48,043 [myid:] - INFO [main:Environment@97] - Server environment:java.version=1.6.0_11
[junit] 2010-09-14 10:53:48,043 [myid:] - INFO [main:Environment@97] - Server environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-14 10:53:48,044 [myid:] - INFO [main:Environment@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-14 10:53:48,045 [myid:] - INFO [main:Environment@97] - Server environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-14 10:53:48,045 [myid:] - INFO [main:Environment@97] - Server environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-14 10:53:48,046 [myid:] - INFO [main:Environment@97] - Server environment:java.io.tmpdir=/tmp
[junit] 2010-09-14 10:53:48,046 [myid:] - INFO [main:Environment@97] - Server environment:java.compiler=<NA>
[junit] 2010-09-14 10:53:48,047 [myid:] - INFO [main:Environment@97] - Server environment:os.name=Linux
[junit] 2010-09-14 10:53:48,047 [myid:] - INFO [main:Environment@97] - Server environment:os.arch=i386
[junit] 2010-09-14 10:53:48,048 [myid:] - INFO [main:Environment@97] - Server environment:os.version=2.6.28-18-generic
[junit] 2010-09-14 10:53:48,048 [myid:] - INFO [main:Environment@97] - Server environment:user.name=hudson
[junit] 2010-09-14 10:53:48,049 [myid:] - INFO [main:Environment@97] - Server environment:user.home=/homes/hudson
[junit] 2010-09-14 10:53:48,049 [myid:] - INFO [main:Environment@97] - Server environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-14 10:53:48,074 [myid:] - INFO [main:ZooKeeperServer@153] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test5329238517091598137.junit.dir/version-2> snapdir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test5329238517091598137.junit.dir/version-2>
[junit] 2010-09-14 10:53:48,090 [myid:] - INFO [main:NIOServerCnxnFactory@93] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2010-09-14 10:53:48,097 [myid:] - INFO [main:FileTxnSnapLog@208] - Snapshotting: 0
[junit] 2010-09-14 10:53:48,134 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] 2010-09-14 10:53:48,137 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:50054
[junit] 2010-09-14 10:53:48,148 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:50054
[junit] 2010-09-14 10:53:48,150 [myid:] - INFO [Thread-3:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-14 10:53:48,152 [myid:] - INFO [Thread-3:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:50054 (no session established for client)
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2010-09-14 10:53:48,158 [myid:] - INFO [main:ClientBase@421] - Client test setup finished
[junit] 2010-09-14 10:53:48,158 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testQuota
[junit] 2010-09-14 10:53:48,165 [myid:] - INFO [main:Environment@97] - Client environment:zookeeper.version=3.4.0-996819, built on 09/14/2010 10:37 GMT
[junit] 2010-09-14 10:53:48,166 [myid:] - INFO [main:Environment@97] - Client environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-14 10:53:48,166 [myid:] - INFO [main:Environment@97] - Client environment:java.version=1.6.0_11
[junit] 2010-09-14 10:53:48,167 [myid:] - INFO [main:Environment@97] - Client environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-14 10:53:48,167 [myid:] - INFO [main:Environment@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-14 10:53:48,168 [myid:] - INFO [main:Environment@97] - Client environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-14 10:53:48,168 [myid:] - INFO [main:Environment@97] - Client environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-14 10:53:48,169 [myid:] - INFO [main:Environment@97] - Client environment:java.io.tmpdir=/tmp
[junit] 2010-09-14 10:53:48,169 [myid:] - INFO [main:Environment@97] - Client environment:java.compiler=<NA>
[junit] 2010-09-14 10:53:48,170 [myid:] - INFO [main:Environment@97] - Client environment:os.name=Linux
[junit] 2010-09-14 10:53:48,170 [myid:] - INFO [main:Environment@97] - Client environment:os.arch=i386
[junit] 2010-09-14 10:53:48,171 [myid:] - INFO [main:Environment@97] - Client environment:os.version=2.6.28-18-generic
[junit] 2010-09-14 10:53:48,171 [myid:] - INFO [main:Environment@97] - Client environment:user.name=hudson
[junit] 2010-09-14 10:53:48,172 [myid:] - INFO [main:Environment@97] - Client environment:user.home=/homes/hudson
[junit] 2010-09-14 10:53:48,172 [myid:] - INFO [main:Environment@97] - Client environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-14 10:53:48,174 [myid:] - INFO [main:ZooKeeper@375] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@157ea4a
[junit] 2010-09-14 10:53:48,190 [myid:] - INFO [main-SendThread():ClientCnxn$SendThread@1021] - Opening socket connection to server /127.0.0.1:11221
[junit] 2010-09-14 10:53:48,192 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:50055
[junit] 2010-09-14 10:53:48,192 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@929] - Socket connection established to localhost/127.0.0.1:11221, initiating session
[junit] 2010-09-14 10:53:48,196 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:ZooKeeperServer@801] - Client attempting to establish new session at /127.0.0.1:50055
[junit] 2010-09-14 10:53:48,202 [myid:] - INFO [SyncThread:0:FileTxnLog@197] - Creating new log file: log.1
[junit] 2010-09-14 10:53:48,229 [myid:] - INFO [SyncThread:0:ZooKeeperServer@577] - Established session 0x12b0fe10acb0000 with negotiated timeout 30000 for client /127.0.0.1:50055
[junit] 2010-09-14 10:53:48,229 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@722] - Session establishment complete on server localhost/127.0.0.1:11221, sessionid = 0x12b0fe10acb0000, negotiated timeout = 30000
[junit] expect:0x12b0fe10acb0000
[junit] found:0x12b0fe10acb0000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12b0fe10acb0000
[junit] 2010-09-14 10:53:48,284 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testQuota
[junit] 2010-09-14 10:53:48,284 [myid:] - INFO [main:ClientBase@465] - tearDown starting
[junit] 2010-09-14 10:53:48,285 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@386] - Processed session termination for sessionid: 0x12b0fe10acb0000
[junit] 2010-09-14 10:53:48,286 [myid:] - INFO [main:ZooKeeper@540] - Session: 0x12b0fe10acb0000 closed
[junit] 2010-09-14 10:53:48,286 [myid:] - INFO [main:ClientBase@432] - STOPPING server
[junit] 2010-09-14 10:53:48,286 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:50055 which had sessionid 0x12b0fe10acb0000
[junit] 2010-09-14 10:53:48,288 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@223] - NIOServerCnxn factory exited run method
[junit] 2010-09-14 10:53:48,288 [myid:] - INFO [main:ZooKeeperServer@405] - shutting down
[junit] 2010-09-14 10:53:48,289 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2010-09-14 10:53:48,289 [myid:] - INFO [main:PrepRequestProcessor@509] - Shutting down
[junit] 2010-09-14 10:53:48,289 [myid:] - INFO [main:SyncRequestProcessor@169] - Shutting down
[junit] 2010-09-14 10:53:48,289 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@120] - PrepRequestProcessor exited loop!
[junit] 2010-09-14 10:53:48,290 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-09-14 10:53:48,290 [myid:] - INFO [main:FinalRequestProcessor@373] - shutdown of request processor complete
[junit] 2010-09-14 10:53:48,291 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] ensureOnly:[]
[junit] 2010-09-14 10:53:48,294 [myid:] - INFO [main:ClientBase@493] - fdcount after test is: 34 at start it was 25
[junit] 2010-09-14 10:53:48,295 [myid:] - INFO [main:ClientBase@495] - sleeping for 20 secs
[junit] 2010-09-14 10:53:48,295 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testQuota
[junit] 2010-09-14 10:53:48,296 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.51 sec
BUILD FAILED
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:935: The following error occurred while executing this line:
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:846: Tests failed!
Total time: 16 minutes 24 seconds
[WARNINGS] Skipping publisher since build result is FAILURE
[FINDBUGS] Skipping publisher since build result is FAILURE
Publishing Javadoc
Recording fingerprints
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Build failed in Hudson: ZooKeeper-trunk #934
Posted by Apache Hudson Server <hu...@hudson.apache.org>.
See <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/934/>
------------------------------------------
[...truncated 168605 lines...]
[junit] 2010-09-13 10:52:44,182 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11237 is accepting client connections
[junit] 2010-09-13 10:52:44,182 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11238
[junit] 2010-09-13 10:52:44,183 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:44910
[junit] 2010-09-13 10:52:44,183 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:44910
[junit] 2010-09-13 10:52:44,183 [myid:] - INFO [Thread-359:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-13 10:52:44,184 [myid:] - INFO [Thread-359:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:44910 (no session established for client)
[junit] 2010-09-13 10:52:44,184 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11238 is accepting client connections
[junit] 2010-09-13 10:52:44,184 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-13 10:52:44,185 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:46017
[junit] 2010-09-13 10:52:44,185 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:46017
[junit] 2010-09-13 10:52:44,201 [myid:] - INFO [Thread-360:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:46017 (no session established for client)
[junit] 2010-09-13 10:52:44,452 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-13 10:52:44,452 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:46018
[junit] 2010-09-13 10:52:44,452 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:46018
[junit] 2010-09-13 10:52:44,453 [myid:] - INFO [Thread-361:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:46018 (no session established for client)
[junit] 2010-09-13 10:52:44,703 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-13 10:52:44,703 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:46019
[junit] 2010-09-13 10:52:44,704 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:46019
[junit] 2010-09-13 10:52:44,704 [myid:] - INFO [Thread-362:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:46019 (no session established for client)
[junit] 2010-09-13 10:52:44,954 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-13 10:52:44,955 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:46020
[junit] 2010-09-13 10:52:44,955 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:46020
[junit] 2010-09-13 10:52:44,955 [myid:] - INFO [Thread-363:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:46020 (no session established for client)
[junit] 2010-09-13 10:52:45,206 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-13 10:52:45,206 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:46021
[junit] 2010-09-13 10:52:45,206 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:46021
[junit] 2010-09-13 10:52:45,207 [myid:] - INFO [Thread-364:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:46021 (no session established for client)
[junit] 2010-09-13 10:52:45,457 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-13 10:52:45,457 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:46022
[junit] 2010-09-13 10:52:45,457 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:46022
[junit] 2010-09-13 10:52:45,458 [myid:] - INFO [Thread-365:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-13 10:52:45,458 [myid:] - INFO [Thread-365:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:46022 (no session established for client)
[junit] 2010-09-13 10:52:45,459 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11239 is accepting client connections
[junit] JMXEnv.dump() follows
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower,name3=InMemoryDataTree
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1,name2=
[junit] found:name0=ReplicatedServer_id1,name1=replica.1,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2,name2=
[junit] found:name0=ReplicatedServer_id2,name1=replica.2,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3,name2=
[junit] found:name0=ReplicatedServer_id3,name1=replica.3,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4,name2=
[junit] found:name0=ReplicatedServer_id4,name1=replica.4,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5,name2=
[junit] found:name0=ReplicatedServer_id5,name1=replica.5,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1
[junit] found:name0=ReplicatedServer_id1,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.2
[junit] found:name0=ReplicatedServer_id1,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] expect:name0=ReplicatedServer_id1,name1=replica.3
[junit] found:name0=ReplicatedServer_id1,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] expect:name0=ReplicatedServer_id1,name1=replica.4
[junit] found:name0=ReplicatedServer_id1,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] expect:name0=ReplicatedServer_id1,name1=replica.5
[junit] found:name0=ReplicatedServer_id1,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] expect:name0=ReplicatedServer_id2,name1=replica.1
[junit] found:name0=ReplicatedServer_id2,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2
[junit] found:name0=ReplicatedServer_id2,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] expect:name0=ReplicatedServer_id2,name1=replica.3
[junit] found:name0=ReplicatedServer_id2,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] 2010-09-13 10:52:45,469 [myid:] - INFO [main:QuorumBase@109] - Initial fdcount is: 109
[junit] expect:name0=ReplicatedServer_id2,name1=replica.4
[junit] found:name0=ReplicatedServer_id2,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] expect:name0=ReplicatedServer_id2,name1=replica.5
[junit] found:name0=ReplicatedServer_id2,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] expect:name0=ReplicatedServer_id3,name1=replica.1
[junit] 2010-09-13 10:52:45,470 [myid:] - INFO [main:QuorumBase@113] - Setup finished
[junit] found:name0=ReplicatedServer_id3,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] expect:name0=ReplicatedServer_id3,name1=replica.2
[junit] found:name0=ReplicatedServer_id3,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3
[junit] found:name0=ReplicatedServer_id3,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] expect:name0=ReplicatedServer_id3,name1=replica.4
[junit] found:name0=ReplicatedServer_id3,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] expect:name0=ReplicatedServer_id3,name1=replica.5
[junit] found:name0=ReplicatedServer_id3,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] expect:name0=ReplicatedServer_id4,name1=replica.1
[junit] found:name0=ReplicatedServer_id4,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] expect:name0=ReplicatedServer_id4,name1=replica.2
[junit] found:name0=ReplicatedServer_id4,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] expect:name0=ReplicatedServer_id4,name1=replica.3
[junit] found:name0=ReplicatedServer_id4,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4
[junit] found:name0=ReplicatedServer_id4,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] expect:name0=ReplicatedServer_id4,name1=replica.5
[junit] found:name0=ReplicatedServer_id4,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] expect:name0=ReplicatedServer_id5,name1=replica.1
[junit] found:name0=ReplicatedServer_id5,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] expect:name0=ReplicatedServer_id5,name1=replica.2
[junit] found:name0=ReplicatedServer_id5,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] expect:name0=ReplicatedServer_id5,name1=replica.3
[junit] found:name0=ReplicatedServer_id5,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] expect:name0=ReplicatedServer_id5,name1=replica.4
[junit] found:name0=ReplicatedServer_id5,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5
[junit] found:name0=ReplicatedServer_id5,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] expect:name0=ReplicatedServer_id1
[junit] found:name0=ReplicatedServer_id1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2
[junit] found:name0=ReplicatedServer_id2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] expect:name0=ReplicatedServer_id3
[junit] found:name0=ReplicatedServer_id3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] expect:name0=ReplicatedServer_id4
[junit] 2010-09-13 10:52:45,470 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testNull
[junit] found:name0=ReplicatedServer_id4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] expect:name0=ReplicatedServer_id5
[junit] found:name0=ReplicatedServer_id5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] 2010-09-13 10:52:45,471 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testNull
[junit] 2010-09-13 10:52:45,471 [myid:] - INFO [main:ZkDatabaseCorruptionTest@56] - STOPPING quorum org.apache.zookeeper.test.ZkDatabaseCorruptionTest
[junit] 2010-09-13 10:52:45,471 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testNull
[junit] 2010-09-13 10:52:45,471 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testNull
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 29.701 sec
[junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest
[junit] 2010-09-13 10:52:46,261 [myid:] - INFO [main:PortAssignment@31] - assigning port 11221
[junit] 2010-09-13 10:52:46,266 [myid:] - INFO [main:ZKTestCase$1@49] - STARTING testQuota
[junit] 2010-09-13 10:52:46,269 [myid:] - INFO [main:ClientBase@407] - Initial fdcount is: 25
[junit] 2010-09-13 10:52:46,456 [myid:] - INFO [main:ClientBase@425] - STARTING server
[junit] 2010-09-13 10:52:46,468 [myid:] - INFO [main:Environment@97] - Server environment:zookeeper.version=3.4.0-996484, built on 09/13/2010 10:35 GMT
[junit] 2010-09-13 10:52:46,469 [myid:] - INFO [main:Environment@97] - Server environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-13 10:52:46,469 [myid:] - INFO [main:Environment@97] - Server environment:java.version=1.6.0_11
[junit] 2010-09-13 10:52:46,470 [myid:] - INFO [main:Environment@97] - Server environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-13 10:52:46,470 [myid:] - INFO [main:Environment@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-13 10:52:46,471 [myid:] - INFO [main:Environment@97] - Server environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-13 10:52:46,472 [myid:] - INFO [main:Environment@97] - Server environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-13 10:52:46,472 [myid:] - INFO [main:Environment@97] - Server environment:java.io.tmpdir=/tmp
[junit] 2010-09-13 10:52:46,473 [myid:] - INFO [main:Environment@97] - Server environment:java.compiler=<NA>
[junit] 2010-09-13 10:52:46,473 [myid:] - INFO [main:Environment@97] - Server environment:os.name=Linux
[junit] 2010-09-13 10:52:46,474 [myid:] - INFO [main:Environment@97] - Server environment:os.arch=i386
[junit] 2010-09-13 10:52:46,474 [myid:] - INFO [main:Environment@97] - Server environment:os.version=2.6.28-18-generic
[junit] 2010-09-13 10:52:46,475 [myid:] - INFO [main:Environment@97] - Server environment:user.name=hudson
[junit] 2010-09-13 10:52:46,475 [myid:] - INFO [main:Environment@97] - Server environment:user.home=/homes/hudson
[junit] 2010-09-13 10:52:46,476 [myid:] - INFO [main:Environment@97] - Server environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-13 10:52:46,500 [myid:] - INFO [main:ZooKeeperServer@153] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2946703330899569958.junit.dir/version-2> snapdir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2946703330899569958.junit.dir/version-2>
[junit] 2010-09-13 10:52:46,516 [myid:] - INFO [main:NIOServerCnxnFactory@93] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2010-09-13 10:52:46,523 [myid:] - INFO [main:FileTxnSnapLog@208] - Snapshotting: 0
[junit] 2010-09-13 10:52:46,560 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] 2010-09-13 10:52:46,563 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:56834
[junit] 2010-09-13 10:52:46,572 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:56834
[junit] 2010-09-13 10:52:46,574 [myid:] - INFO [Thread-3:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-13 10:52:46,576 [myid:] - INFO [Thread-3:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:56834 (no session established for client)
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2010-09-13 10:52:46,581 [myid:] - INFO [main:ClientBase@421] - Client test setup finished
[junit] 2010-09-13 10:52:46,582 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testQuota
[junit] 2010-09-13 10:52:46,589 [myid:] - INFO [main:Environment@97] - Client environment:zookeeper.version=3.4.0-996484, built on 09/13/2010 10:35 GMT
[junit] 2010-09-13 10:52:46,590 [myid:] - INFO [main:Environment@97] - Client environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-13 10:52:46,590 [myid:] - INFO [main:Environment@97] - Client environment:java.version=1.6.0_11
[junit] 2010-09-13 10:52:46,591 [myid:] - INFO [main:Environment@97] - Client environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-13 10:52:46,591 [myid:] - INFO [main:Environment@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-13 10:52:46,592 [myid:] - INFO [main:Environment@97] - Client environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-13 10:52:46,592 [myid:] - INFO [main:Environment@97] - Client environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-13 10:52:46,593 [myid:] - INFO [main:Environment@97] - Client environment:java.io.tmpdir=/tmp
[junit] 2010-09-13 10:52:46,593 [myid:] - INFO [main:Environment@97] - Client environment:java.compiler=<NA>
[junit] 2010-09-13 10:52:46,594 [myid:] - INFO [main:Environment@97] - Client environment:os.name=Linux
[junit] 2010-09-13 10:52:46,594 [myid:] - INFO [main:Environment@97] - Client environment:os.arch=i386
[junit] 2010-09-13 10:52:46,595 [myid:] - INFO [main:Environment@97] - Client environment:os.version=2.6.28-18-generic
[junit] 2010-09-13 10:52:46,595 [myid:] - INFO [main:Environment@97] - Client environment:user.name=hudson
[junit] 2010-09-13 10:52:46,596 [myid:] - INFO [main:Environment@97] - Client environment:user.home=/homes/hudson
[junit] 2010-09-13 10:52:46,596 [myid:] - INFO [main:Environment@97] - Client environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-13 10:52:46,598 [myid:] - INFO [main:ZooKeeper@375] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@d3c65d
[junit] 2010-09-13 10:52:46,614 [myid:] - INFO [main-SendThread():ClientCnxn$SendThread@1021] - Opening socket connection to server /127.0.0.1:11221
[junit] 2010-09-13 10:52:46,615 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:56835
[junit] 2010-09-13 10:52:46,616 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@929] - Socket connection established to localhost/127.0.0.1:11221, initiating session
[junit] 2010-09-13 10:52:46,619 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:ZooKeeperServer@801] - Client attempting to establish new session at /127.0.0.1:56835
[junit] 2010-09-13 10:52:46,627 [myid:] - INFO [SyncThread:0:FileTxnLog@197] - Creating new log file: log.1
[junit] 2010-09-13 10:52:46,652 [myid:] - INFO [SyncThread:0:ZooKeeperServer@577] - Established session 0x12b0ab9be450000 with negotiated timeout 30000 for client /127.0.0.1:56835
[junit] 2010-09-13 10:52:46,653 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@722] - Session establishment complete on server localhost/127.0.0.1:11221, sessionid = 0x12b0ab9be450000, negotiated timeout = 30000
[junit] expect:0x12b0ab9be450000
[junit] found:0x12b0ab9be450000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12b0ab9be450000
[junit] 2010-09-13 10:52:46,704 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testQuota
[junit] 2010-09-13 10:52:46,705 [myid:] - INFO [main:ClientBase@465] - tearDown starting
[junit] 2010-09-13 10:52:46,706 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@386] - Processed session termination for sessionid: 0x12b0ab9be450000
[junit] 2010-09-13 10:52:46,707 [myid:] - INFO [main:ZooKeeper@540] - Session: 0x12b0ab9be450000 closed
[junit] 2010-09-13 10:52:46,707 [myid:] - INFO [main:ClientBase@432] - STOPPING server
[junit] 2010-09-13 10:52:46,707 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:56835 which had sessionid 0x12b0ab9be450000
[junit] 2010-09-13 10:52:46,709 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@223] - NIOServerCnxn factory exited run method
[junit] 2010-09-13 10:52:46,709 [myid:] - INFO [main:ZooKeeperServer@405] - shutting down
[junit] 2010-09-13 10:52:46,710 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2010-09-13 10:52:46,710 [myid:] - INFO [main:PrepRequestProcessor@509] - Shutting down
[junit] 2010-09-13 10:52:46,710 [myid:] - INFO [main:SyncRequestProcessor@169] - Shutting down
[junit] 2010-09-13 10:52:46,710 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@120] - PrepRequestProcessor exited loop!
[junit] 2010-09-13 10:52:46,711 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-09-13 10:52:46,711 [myid:] - INFO [main:FinalRequestProcessor@373] - shutdown of request processor complete
[junit] 2010-09-13 10:52:46,712 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] ensureOnly:[]
[junit] 2010-09-13 10:52:46,715 [myid:] - INFO [main:ClientBase@493] - fdcount after test is: 34 at start it was 25
[junit] 2010-09-13 10:52:46,715 [myid:] - INFO [main:ClientBase@495] - sleeping for 20 secs
[junit] 2010-09-13 10:52:46,716 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testQuota
[junit] 2010-09-13 10:52:46,716 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.51 sec
BUILD FAILED
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:935: The following error occurred while executing this line:
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:846: Tests failed!
Total time: 17 minutes 23 seconds
[WARNINGS] Skipping publisher since build result is FAILURE
[FINDBUGS] Skipping publisher since build result is FAILURE
Publishing Javadoc
Recording fingerprints
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Build failed in Hudson: ZooKeeper-trunk #933
Posted by Apache Hudson Server <hu...@hudson.apache.org>.
See <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/933/>
------------------------------------------
[...truncated 165187 lines...]
[junit] 2010-09-12 10:52:41,803 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11237:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:57244
[junit] 2010-09-12 10:52:41,803 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11237:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:57244
[junit] 2010-09-12 10:52:41,803 [myid:] - INFO [Thread-282:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-12 10:52:41,804 [myid:] - INFO [Thread-282:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:57244 (no session established for client)
[junit] 2010-09-12 10:52:41,804 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11237 is accepting client connections
[junit] 2010-09-12 10:52:41,804 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11238
[junit] 2010-09-12 10:52:41,805 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:49747
[junit] 2010-09-12 10:52:41,805 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:49747
[junit] 2010-09-12 10:52:41,806 [myid:] - INFO [Thread-283:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-12 10:52:41,806 [myid:] - INFO [Thread-283:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:49747 (no session established for client)
[junit] 2010-09-12 10:52:41,807 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11238 is accepting client connections
[junit] 2010-09-12 10:52:41,807 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-12 10:52:41,807 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35060
[junit] 2010-09-12 10:52:41,807 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35060
[junit] 2010-09-12 10:52:41,808 [myid:] - INFO [Thread-284:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35060 (no session established for client)
[junit] 2010-09-12 10:52:42,058 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-12 10:52:42,059 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35061
[junit] 2010-09-12 10:52:42,059 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35061
[junit] 2010-09-12 10:52:42,059 [myid:] - INFO [Thread-285:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35061 (no session established for client)
[junit] 2010-09-12 10:52:42,310 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-12 10:52:42,310 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35062
[junit] 2010-09-12 10:52:42,310 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35062
[junit] 2010-09-12 10:52:42,311 [myid:] - INFO [Thread-286:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35062 (no session established for client)
[junit] 2010-09-12 10:52:42,561 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-12 10:52:42,561 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35063
[junit] 2010-09-12 10:52:42,561 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35063
[junit] 2010-09-12 10:52:42,562 [myid:] - INFO [Thread-287:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35063 (no session established for client)
[junit] 2010-09-12 10:52:42,812 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-12 10:52:42,813 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:35064
[junit] 2010-09-12 10:52:42,813 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:35064
[junit] 2010-09-12 10:52:42,813 [myid:] - INFO [Thread-288:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-12 10:52:42,814 [myid:] - INFO [Thread-288:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:35064 (no session established for client)
[junit] JMXEnv.dump() follows
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] 2010-09-12 10:52:42,814 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11239 is accepting client connections
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower,name3=InMemoryDataTree
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1,name2=
[junit] found:name0=ReplicatedServer_id1,name1=replica.1,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2,name2=
[junit] found:name0=ReplicatedServer_id2,name1=replica.2,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3,name2=
[junit] found:name0=ReplicatedServer_id3,name1=replica.3,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4,name2=
[junit] found:name0=ReplicatedServer_id4,name1=replica.4,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5,name2=
[junit] found:name0=ReplicatedServer_id5,name1=replica.5,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1
[junit] found:name0=ReplicatedServer_id1,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.2
[junit] found:name0=ReplicatedServer_id1,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] expect:name0=ReplicatedServer_id1,name1=replica.3
[junit] found:name0=ReplicatedServer_id1,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] expect:name0=ReplicatedServer_id1,name1=replica.4
[junit] found:name0=ReplicatedServer_id1,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] expect:name0=ReplicatedServer_id1,name1=replica.5
[junit] found:name0=ReplicatedServer_id1,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] expect:name0=ReplicatedServer_id2,name1=replica.1
[junit] found:name0=ReplicatedServer_id2,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2
[junit] found:name0=ReplicatedServer_id2,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] expect:name0=ReplicatedServer_id2,name1=replica.3
[junit] found:name0=ReplicatedServer_id2,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] expect:name0=ReplicatedServer_id2,name1=replica.4
[junit] found:name0=ReplicatedServer_id2,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] expect:name0=ReplicatedServer_id2,name1=replica.5
[junit] found:name0=ReplicatedServer_id2,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] expect:name0=ReplicatedServer_id3,name1=replica.1
[junit] found:name0=ReplicatedServer_id3,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] expect:name0=ReplicatedServer_id3,name1=replica.2
[junit] found:name0=ReplicatedServer_id3,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3
[junit] found:name0=ReplicatedServer_id3,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] expect:name0=ReplicatedServer_id3,name1=replica.4
[junit] found:name0=ReplicatedServer_id3,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] expect:name0=ReplicatedServer_id3,name1=replica.5
[junit] found:name0=ReplicatedServer_id3,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] expect:name0=ReplicatedServer_id4,name1=replica.1
[junit] found:name0=ReplicatedServer_id4,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] expect:name0=ReplicatedServer_id4,name1=replica.2
[junit] found:name0=ReplicatedServer_id4,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] expect:name0=ReplicatedServer_id4,name1=replica.3
[junit] found:name0=ReplicatedServer_id4,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4
[junit] found:name0=ReplicatedServer_id4,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] expect:name0=ReplicatedServer_id4,name1=replica.5
[junit] found:name0=ReplicatedServer_id4,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] expect:name0=ReplicatedServer_id5,name1=replica.1
[junit] found:name0=ReplicatedServer_id5,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] expect:name0=ReplicatedServer_id5,name1=replica.2
[junit] found:name0=ReplicatedServer_id5,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] expect:name0=ReplicatedServer_id5,name1=replica.3
[junit] found:name0=ReplicatedServer_id5,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] expect:name0=ReplicatedServer_id5,name1=replica.4
[junit] found:name0=ReplicatedServer_id5,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5
[junit] found:name0=ReplicatedServer_id5,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] expect:name0=ReplicatedServer_id1
[junit] found:name0=ReplicatedServer_id1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2
[junit] found:name0=ReplicatedServer_id2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] expect:name0=ReplicatedServer_id3
[junit] found:name0=ReplicatedServer_id3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] expect:name0=ReplicatedServer_id4
[junit] found:name0=ReplicatedServer_id4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] expect:name0=ReplicatedServer_id5
[junit] found:name0=ReplicatedServer_id5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] 2010-09-12 10:52:42,825 [myid:] - INFO [main:QuorumBase@109] - Initial fdcount is: 95
[junit] 2010-09-12 10:52:42,825 [myid:] - INFO [main:QuorumBase@113] - Setup finished
[junit] 2010-09-12 10:52:42,825 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testNull
[junit] 2010-09-12 10:52:42,825 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testNull
[junit] 2010-09-12 10:52:42,825 [myid:] - INFO [main:ZkDatabaseCorruptionTest@56] - STOPPING quorum org.apache.zookeeper.test.ZkDatabaseCorruptionTest
[junit] 2010-09-12 10:52:42,826 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testNull
[junit] 2010-09-12 10:52:42,826 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testNull
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 37.461 sec
[junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest
[junit] 2010-09-12 10:52:43,620 [myid:] - INFO [main:PortAssignment@31] - assigning port 11221
[junit] 2010-09-12 10:52:43,625 [myid:] - INFO [main:ZKTestCase$1@49] - STARTING testQuota
[junit] 2010-09-12 10:52:43,628 [myid:] - INFO [main:ClientBase@407] - Initial fdcount is: 25
[junit] 2010-09-12 10:52:43,805 [myid:] - INFO [main:ClientBase@425] - STARTING server
[junit] 2010-09-12 10:52:43,817 [myid:] - INFO [main:Environment@97] - Server environment:zookeeper.version=3.4.0-996286, built on 09/12/2010 10:35 GMT
[junit] 2010-09-12 10:52:43,817 [myid:] - INFO [main:Environment@97] - Server environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-12 10:52:43,818 [myid:] - INFO [main:Environment@97] - Server environment:java.version=1.6.0_11
[junit] 2010-09-12 10:52:43,818 [myid:] - INFO [main:Environment@97] - Server environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-12 10:52:43,819 [myid:] - INFO [main:Environment@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-12 10:52:43,819 [myid:] - INFO [main:Environment@97] - Server environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-12 10:52:43,820 [myid:] - INFO [main:Environment@97] - Server environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-12 10:52:43,821 [myid:] - INFO [main:Environment@97] - Server environment:java.io.tmpdir=/tmp
[junit] 2010-09-12 10:52:43,821 [myid:] - INFO [main:Environment@97] - Server environment:java.compiler=<NA>
[junit] 2010-09-12 10:52:43,822 [myid:] - INFO [main:Environment@97] - Server environment:os.name=Linux
[junit] 2010-09-12 10:52:43,822 [myid:] - INFO [main:Environment@97] - Server environment:os.arch=i386
[junit] 2010-09-12 10:52:43,823 [myid:] - INFO [main:Environment@97] - Server environment:os.version=2.6.28-18-generic
[junit] 2010-09-12 10:52:43,823 [myid:] - INFO [main:Environment@97] - Server environment:user.name=hudson
[junit] 2010-09-12 10:52:43,824 [myid:] - INFO [main:Environment@97] - Server environment:user.home=/homes/hudson
[junit] 2010-09-12 10:52:43,824 [myid:] - INFO [main:Environment@97] - Server environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-12 10:52:43,849 [myid:] - INFO [main:ZooKeeperServer@153] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4212548700305590701.junit.dir/version-2> snapdir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4212548700305590701.junit.dir/version-2>
[junit] 2010-09-12 10:52:43,865 [myid:] - INFO [main:NIOServerCnxnFactory@93] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2010-09-12 10:52:43,882 [myid:] - INFO [main:FileTxnSnapLog@208] - Snapshotting: 0
[junit] 2010-09-12 10:52:43,918 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] 2010-09-12 10:52:43,921 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:39355
[junit] 2010-09-12 10:52:43,930 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:39355
[junit] 2010-09-12 10:52:43,932 [myid:] - INFO [Thread-3:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-12 10:52:43,934 [myid:] - INFO [Thread-3:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:39355 (no session established for client)
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2010-09-12 10:52:43,940 [myid:] - INFO [main:ClientBase@421] - Client test setup finished
[junit] 2010-09-12 10:52:43,940 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testQuota
[junit] 2010-09-12 10:52:43,948 [myid:] - INFO [main:Environment@97] - Client environment:zookeeper.version=3.4.0-996286, built on 09/12/2010 10:35 GMT
[junit] 2010-09-12 10:52:43,948 [myid:] - INFO [main:Environment@97] - Client environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-12 10:52:43,949 [myid:] - INFO [main:Environment@97] - Client environment:java.version=1.6.0_11
[junit] 2010-09-12 10:52:43,949 [myid:] - INFO [main:Environment@97] - Client environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-12 10:52:43,950 [myid:] - INFO [main:Environment@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-12 10:52:43,950 [myid:] - INFO [main:Environment@97] - Client environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-12 10:52:43,951 [myid:] - INFO [main:Environment@97] - Client environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-12 10:52:43,951 [myid:] - INFO [main:Environment@97] - Client environment:java.io.tmpdir=/tmp
[junit] 2010-09-12 10:52:43,952 [myid:] - INFO [main:Environment@97] - Client environment:java.compiler=<NA>
[junit] 2010-09-12 10:52:43,952 [myid:] - INFO [main:Environment@97] - Client environment:os.name=Linux
[junit] 2010-09-12 10:52:43,953 [myid:] - INFO [main:Environment@97] - Client environment:os.arch=i386
[junit] 2010-09-12 10:52:43,953 [myid:] - INFO [main:Environment@97] - Client environment:os.version=2.6.28-18-generic
[junit] 2010-09-12 10:52:43,954 [myid:] - INFO [main:Environment@97] - Client environment:user.name=hudson
[junit] 2010-09-12 10:52:43,954 [myid:] - INFO [main:Environment@97] - Client environment:user.home=/homes/hudson
[junit] 2010-09-12 10:52:43,955 [myid:] - INFO [main:Environment@97] - Client environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-12 10:52:43,956 [myid:] - INFO [main:ZooKeeper@375] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@11db6bb
[junit] 2010-09-12 10:52:43,972 [myid:] - INFO [main-SendThread():ClientCnxn$SendThread@1021] - Opening socket connection to server /127.0.0.1:11221
[junit] 2010-09-12 10:52:43,973 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:39356
[junit] 2010-09-12 10:52:43,973 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@929] - Socket connection established to localhost/127.0.0.1:11221, initiating session
[junit] 2010-09-12 10:52:43,978 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:ZooKeeperServer@801] - Client attempting to establish new session at /127.0.0.1:39356
[junit] 2010-09-12 10:52:43,988 [myid:] - INFO [SyncThread:0:FileTxnLog@197] - Creating new log file: log.1
[junit] 2010-09-12 10:52:44,010 [myid:] - INFO [SyncThread:0:ZooKeeperServer@577] - Established session 0x12b059357f40000 with negotiated timeout 30000 for client /127.0.0.1:39356
[junit] 2010-09-12 10:52:44,011 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@722] - Session establishment complete on server localhost/127.0.0.1:11221, sessionid = 0x12b059357f40000, negotiated timeout = 30000
[junit] expect:0x12b059357f40000
[junit] found:0x12b059357f40000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12b059357f40000
[junit] 2010-09-12 10:52:44,065 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testQuota
[junit] 2010-09-12 10:52:44,066 [myid:] - INFO [main:ClientBase@465] - tearDown starting
[junit] 2010-09-12 10:52:44,068 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@386] - Processed session termination for sessionid: 0x12b059357f40000
[junit] 2010-09-12 10:52:44,069 [myid:] - INFO [main:ZooKeeper@540] - Session: 0x12b059357f40000 closed
[junit] 2010-09-12 10:52:44,069 [myid:] - INFO [main:ClientBase@432] - STOPPING server
[junit] 2010-09-12 10:52:44,070 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:39356 which had sessionid 0x12b059357f40000
[junit] 2010-09-12 10:52:44,071 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@223] - NIOServerCnxn factory exited run method
[junit] 2010-09-12 10:52:44,072 [myid:] - INFO [main:ZooKeeperServer@405] - shutting down
[junit] 2010-09-12 10:52:44,072 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2010-09-12 10:52:44,072 [myid:] - INFO [main:PrepRequestProcessor@509] - Shutting down
[junit] 2010-09-12 10:52:44,073 [myid:] - INFO [main:SyncRequestProcessor@169] - Shutting down
[junit] 2010-09-12 10:52:44,073 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@120] - PrepRequestProcessor exited loop!
[junit] 2010-09-12 10:52:44,073 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-09-12 10:52:44,074 [myid:] - INFO [main:FinalRequestProcessor@373] - shutdown of request processor complete
[junit] 2010-09-12 10:52:44,075 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] ensureOnly:[]
[junit] 2010-09-12 10:52:44,078 [myid:] - INFO [main:ClientBase@493] - fdcount after test is: 34 at start it was 25
[junit] 2010-09-12 10:52:44,078 [myid:] - INFO [main:ClientBase@495] - sleeping for 20 secs
[junit] 2010-09-12 10:52:44,079 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testQuota
[junit] 2010-09-12 10:52:44,079 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.513 sec
BUILD FAILED
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:935: The following error occurred while executing this line:
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:846: Tests failed!
Total time: 17 minutes 31 seconds
[WARNINGS] Skipping publisher since build result is FAILURE
[FINDBUGS] Skipping publisher since build result is FAILURE
Publishing Javadoc
Recording fingerprints
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Build failed in Hudson: ZooKeeper-trunk #932
Posted by Apache Hudson Server <hu...@hudson.apache.org>.
See <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/932/changes>
Changes:
[breed] ZOOKEEPER-785. Zookeeper 3.3.1 shouldn't infinite loop if someone creates a server.0 line
------------------------------------------
[...truncated 167309 lines...]
[junit] 2010-09-11 10:53:28,587 [myid:] - INFO [Thread-313:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-11 10:53:28,588 [myid:] - INFO [Thread-313:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:38623 (no session established for client)
[junit] 2010-09-11 10:53:28,588 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11236 is accepting client connections
[junit] 2010-09-11 10:53:28,588 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11237
[junit] 2010-09-11 10:53:28,589 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11237:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:49696
[junit] 2010-09-11 10:53:28,589 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11237:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:49696
[junit] 2010-09-11 10:53:28,589 [myid:] - INFO [Thread-314:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-11 10:53:28,590 [myid:] - INFO [Thread-314:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:49696 (no session established for client)
[junit] 2010-09-11 10:53:28,590 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11237 is accepting client connections
[junit] 2010-09-11 10:53:28,591 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11238
[junit] 2010-09-11 10:53:28,591 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:43167
[junit] 2010-09-11 10:53:28,591 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11238:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:43167
[junit] 2010-09-11 10:53:28,592 [myid:] - INFO [Thread-315:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-11 10:53:28,592 [myid:] - INFO [Thread-315:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:43167 (no session established for client)
[junit] 2010-09-11 10:53:28,592 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11238 is accepting client connections
[junit] 2010-09-11 10:53:28,593 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-11 10:53:28,593 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:53841
[junit] 2010-09-11 10:53:28,593 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:53841
[junit] 2010-09-11 10:53:28,594 [myid:] - INFO [Thread-316:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:53841 (no session established for client)
[junit] 2010-09-11 10:53:28,844 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-11 10:53:28,845 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:53842
[junit] 2010-09-11 10:53:28,845 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:53842
[junit] 2010-09-11 10:53:28,845 [myid:] - INFO [Thread-317:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:53842 (no session established for client)
[junit] 2010-09-11 10:53:29,096 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-11 10:53:29,096 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:53843
[junit] 2010-09-11 10:53:29,096 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:53843
[junit] 2010-09-11 10:53:29,097 [myid:] - INFO [Thread-318:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:53843 (no session established for client)
[junit] 2010-09-11 10:53:29,347 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11239
[junit] 2010-09-11 10:53:29,348 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:53844
[junit] 2010-09-11 10:53:29,348 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11239:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:53844
[junit] 2010-09-11 10:53:29,348 [myid:] - INFO [Thread-319:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-11 10:53:29,349 [myid:] - INFO [Thread-319:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:53844 (no session established for client)
[junit] JMXEnv.dump() follows
[junit] 2010-09-11 10:53:29,349 [myid:] - INFO [main:QuorumBase@195] - 127.0.0.1:11239 is accepting client connections
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id5
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] bean:org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower,name3=InMemoryDataTree
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1,name2=
[junit] found:name0=ReplicatedServer_id1,name1=replica.1,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2,name2=
[junit] found:name0=ReplicatedServer_id2,name1=replica.2,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3,name2=
[junit] found:name0=ReplicatedServer_id3,name1=replica.3,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3,name2=Follower
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4,name2=
[junit] found:name0=ReplicatedServer_id4,name1=replica.4,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5,name2=
[junit] found:name0=ReplicatedServer_id5,name1=replica.5,name2= org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.1
[junit] found:name0=ReplicatedServer_id1,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=InMemoryDataTree
[junit] expect:name0=ReplicatedServer_id1,name1=replica.2
[junit] found:name0=ReplicatedServer_id1,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2
[junit] expect:name0=ReplicatedServer_id1,name1=replica.3
[junit] found:name0=ReplicatedServer_id1,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.3
[junit] expect:name0=ReplicatedServer_id1,name1=replica.4
[junit] found:name0=ReplicatedServer_id1,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.4
[junit] expect:name0=ReplicatedServer_id1,name1=replica.5
[junit] found:name0=ReplicatedServer_id1,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] expect:name0=ReplicatedServer_id2,name1=replica.1
[junit] found:name0=ReplicatedServer_id2,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2,name1=replica.2
[junit] found:name0=ReplicatedServer_id2,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] expect:name0=ReplicatedServer_id2,name1=replica.3
[junit] found:name0=ReplicatedServer_id2,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] expect:name0=ReplicatedServer_id2,name1=replica.4
[junit] found:name0=ReplicatedServer_id2,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.4
[junit] expect:name0=ReplicatedServer_id2,name1=replica.5
[junit] found:name0=ReplicatedServer_id2,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.5
[junit] expect:name0=ReplicatedServer_id3,name1=replica.1
[junit] found:name0=ReplicatedServer_id3,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1
[junit] expect:name0=ReplicatedServer_id3,name1=replica.2
[junit] found:name0=ReplicatedServer_id3,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2
[junit] expect:name0=ReplicatedServer_id3,name1=replica.3
[junit] found:name0=ReplicatedServer_id3,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3
[junit] expect:name0=ReplicatedServer_id3,name1=replica.4
[junit] found:name0=ReplicatedServer_id3,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4
[junit] expect:name0=ReplicatedServer_id3,name1=replica.5
[junit] found:name0=ReplicatedServer_id3,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5
[junit] expect:name0=ReplicatedServer_id4,name1=replica.1
[junit] found:name0=ReplicatedServer_id4,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1
[junit] expect:name0=ReplicatedServer_id4,name1=replica.2
[junit] found:name0=ReplicatedServer_id4,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2
[junit] expect:name0=ReplicatedServer_id4,name1=replica.3
[junit] found:name0=ReplicatedServer_id4,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3
[junit] expect:name0=ReplicatedServer_id4,name1=replica.4
[junit] found:name0=ReplicatedServer_id4,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4
[junit] expect:name0=ReplicatedServer_id4,name1=replica.5
[junit] found:name0=ReplicatedServer_id4,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5
[junit] expect:name0=ReplicatedServer_id5,name1=replica.1
[junit] found:name0=ReplicatedServer_id5,name1=replica.1 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1
[junit] expect:name0=ReplicatedServer_id5,name1=replica.2
[junit] found:name0=ReplicatedServer_id5,name1=replica.2 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2
[junit] expect:name0=ReplicatedServer_id5,name1=replica.3
[junit] found:name0=ReplicatedServer_id5,name1=replica.3 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3
[junit] expect:name0=ReplicatedServer_id5,name1=replica.4
[junit] found:name0=ReplicatedServer_id5,name1=replica.4 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4
[junit] expect:name0=ReplicatedServer_id5,name1=replica.5
[junit] found:name0=ReplicatedServer_id5,name1=replica.5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5
[junit] expect:name0=ReplicatedServer_id1
[junit] found:name0=ReplicatedServer_id1 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] expect:name0=ReplicatedServer_id2
[junit] found:name0=ReplicatedServer_id2 org.apache.ZooKeeperService:name0=ReplicatedServer_id2
[junit] expect:name0=ReplicatedServer_id3
[junit] found:name0=ReplicatedServer_id3 org.apache.ZooKeeperService:name0=ReplicatedServer_id3
[junit] expect:name0=ReplicatedServer_id4
[junit] found:name0=ReplicatedServer_id4 org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Follower
[junit] expect:name0=ReplicatedServer_id5
[junit] found:name0=ReplicatedServer_id5 org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader
[junit] 2010-09-11 10:53:29,360 [myid:] - INFO [main:QuorumBase@109] - Initial fdcount is: 93
[junit] 2010-09-11 10:53:29,360 [myid:] - INFO [main:QuorumBase@113] - Setup finished
[junit] 2010-09-11 10:53:29,360 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testNull
[junit] 2010-09-11 10:53:29,361 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testNull
[junit] 2010-09-11 10:53:29,361 [myid:] - INFO [main:ZkDatabaseCorruptionTest@56] - STOPPING quorum org.apache.zookeeper.test.ZkDatabaseCorruptionTest
[junit] 2010-09-11 10:53:29,361 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testNull
[junit] 2010-09-11 10:53:29,361 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testNull
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 37.327 sec
[junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest
[junit] 2010-09-11 10:53:30,176 [myid:] - INFO [main:PortAssignment@31] - assigning port 11221
[junit] 2010-09-11 10:53:30,180 [myid:] - INFO [main:ZKTestCase$1@49] - STARTING testQuota
[junit] 2010-09-11 10:53:30,184 [myid:] - INFO [main:ClientBase@407] - Initial fdcount is: 25
[junit] 2010-09-11 10:53:30,373 [myid:] - INFO [main:ClientBase@425] - STARTING server
[junit] 2010-09-11 10:53:30,385 [myid:] - INFO [main:Environment@97] - Server environment:zookeeper.version=3.4.0-996114, built on 09/11/2010 10:35 GMT
[junit] 2010-09-11 10:53:30,386 [myid:] - INFO [main:Environment@97] - Server environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-11 10:53:30,386 [myid:] - INFO [main:Environment@97] - Server environment:java.version=1.6.0_11
[junit] 2010-09-11 10:53:30,387 [myid:] - INFO [main:Environment@97] - Server environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-11 10:53:30,387 [myid:] - INFO [main:Environment@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-11 10:53:30,388 [myid:] - INFO [main:Environment@97] - Server environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-11 10:53:30,389 [myid:] - INFO [main:Environment@97] - Server environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-11 10:53:30,389 [myid:] - INFO [main:Environment@97] - Server environment:java.io.tmpdir=/tmp
[junit] 2010-09-11 10:53:30,390 [myid:] - INFO [main:Environment@97] - Server environment:java.compiler=<NA>
[junit] 2010-09-11 10:53:30,390 [myid:] - INFO [main:Environment@97] - Server environment:os.name=Linux
[junit] 2010-09-11 10:53:30,391 [myid:] - INFO [main:Environment@97] - Server environment:os.arch=i386
[junit] 2010-09-11 10:53:30,391 [myid:] - INFO [main:Environment@97] - Server environment:os.version=2.6.28-18-generic
[junit] 2010-09-11 10:53:30,391 [myid:] - INFO [main:Environment@97] - Server environment:user.name=hudson
[junit] 2010-09-11 10:53:30,392 [myid:] - INFO [main:Environment@97] - Server environment:user.home=/homes/hudson
[junit] 2010-09-11 10:53:30,392 [myid:] - INFO [main:Environment@97] - Server environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-11 10:53:30,417 [myid:] - INFO [main:ZooKeeperServer@153] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test6610814999677570785.junit.dir/version-2> snapdir <https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test6610814999677570785.junit.dir/version-2>
[junit] 2010-09-11 10:53:30,433 [myid:] - INFO [main:NIOServerCnxnFactory@93] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2010-09-11 10:53:30,440 [myid:] - INFO [main:FileTxnSnapLog@208] - Snapshotting: 0
[junit] 2010-09-11 10:53:30,477 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] 2010-09-11 10:53:30,480 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:43105
[junit] 2010-09-11 10:53:30,489 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@791] - Processing stat command from /127.0.0.1:43105
[junit] 2010-09-11 10:53:30,491 [myid:] - INFO [Thread-3:NIOServerCnxn$StatCommand@645] - Stat command output
[junit] 2010-09-11 10:53:30,493 [myid:] - INFO [Thread-3:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:43105 (no session established for client)
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2010-09-11 10:53:30,498 [myid:] - INFO [main:ClientBase@421] - Client test setup finished
[junit] 2010-09-11 10:53:30,499 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@49] - RUNNING TEST METHOD testQuota
[junit] 2010-09-11 10:53:30,506 [myid:] - INFO [main:Environment@97] - Client environment:zookeeper.version=3.4.0-996114, built on 09/11/2010 10:35 GMT
[junit] 2010-09-11 10:53:30,507 [myid:] - INFO [main:Environment@97] - Client environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-09-11 10:53:30,507 [myid:] - INFO [main:Environment@97] - Client environment:java.version=1.6.0_11
[junit] 2010-09-11 10:53:30,508 [myid:] - INFO [main:Environment@97] - Client environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-09-11 10:53:30,508 [myid:] - INFO [main:Environment@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-09-11 10:53:30,509 [myid:] - INFO [main:Environment@97] - Client environment:java.class.path=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/accessive.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/netty-3.1.5.GA.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar
[junit] 2010-09-11 10:53:30,509 [myid:] - INFO [main:Environment@97] - Client environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2010-09-11 10:53:30,510 [myid:] - INFO [main:Environment@97] - Client environment:java.io.tmpdir=/tmp
[junit] 2010-09-11 10:53:30,510 [myid:] - INFO [main:Environment@97] - Client environment:java.compiler=<NA>
[junit] 2010-09-11 10:53:30,511 [myid:] - INFO [main:Environment@97] - Client environment:os.name=Linux
[junit] 2010-09-11 10:53:30,511 [myid:] - INFO [main:Environment@97] - Client environment:os.arch=i386
[junit] 2010-09-11 10:53:30,512 [myid:] - INFO [main:Environment@97] - Client environment:os.version=2.6.28-18-generic
[junit] 2010-09-11 10:53:30,512 [myid:] - INFO [main:Environment@97] - Client environment:user.name=hudson
[junit] 2010-09-11 10:53:30,513 [myid:] - INFO [main:Environment@97] - Client environment:user.home=/homes/hudson
[junit] 2010-09-11 10:53:30,513 [myid:] - INFO [main:Environment@97] - Client environment:user.dir=<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-09-11 10:53:30,515 [myid:] - INFO [main:ZooKeeper@375] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@157ea4a
[junit] 2010-09-11 10:53:30,531 [myid:] - INFO [main-SendThread():ClientCnxn$SendThread@1021] - Opening socket connection to server /127.0.0.1:11221
[junit] 2010-09-11 10:53:30,532 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@196] - Accepted socket connection from /127.0.0.1:43106
[junit] 2010-09-11 10:53:30,532 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@929] - Socket connection established to localhost/127.0.0.1:11221, initiating session
[junit] 2010-09-11 10:53:30,536 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:ZooKeeperServer@801] - Client attempting to establish new session at /127.0.0.1:43106
[junit] 2010-09-11 10:53:30,543 [myid:] - INFO [SyncThread:0:FileTxnLog@197] - Creating new log file: log.1
[junit] 2010-09-11 10:53:30,571 [myid:] - INFO [SyncThread:0:ZooKeeperServer@577] - Established session 0x12b006db1d10000 with negotiated timeout 30000 for client /127.0.0.1:43106
[junit] 2010-09-11 10:53:30,571 [myid:] - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@722] - Session establishment complete on server localhost/127.0.0.1:11221, sessionid = 0x12b006db1d10000, negotiated timeout = 30000
[junit] expect:0x12b006db1d10000
[junit] found:0x12b006db1d10000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12b006db1d10000
[junit] 2010-09-11 10:53:30,625 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@56] - FINISHED TEST METHOD testQuota
[junit] 2010-09-11 10:53:30,626 [myid:] - INFO [main:ClientBase@465] - tearDown starting
[junit] 2010-09-11 10:53:30,627 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@386] - Processed session termination for sessionid: 0x12b006db1d10000
[junit] 2010-09-11 10:53:30,628 [myid:] - INFO [main:ZooKeeper@540] - Session: 0x12b006db1d10000 closed
[junit] 2010-09-11 10:53:30,628 [myid:] - INFO [main:ClientBase@432] - STOPPING server
[junit] 2010-09-11 10:53:30,628 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@967] - Closed socket connection for client /127.0.0.1:43106 which had sessionid 0x12b006db1d10000
[junit] 2010-09-11 10:53:30,630 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@223] - NIOServerCnxn factory exited run method
[junit] 2010-09-11 10:53:30,630 [myid:] - INFO [main:ZooKeeperServer@405] - shutting down
[junit] 2010-09-11 10:53:30,631 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2010-09-11 10:53:30,631 [myid:] - INFO [main:PrepRequestProcessor@509] - Shutting down
[junit] 2010-09-11 10:53:30,631 [myid:] - INFO [main:SyncRequestProcessor@169] - Shutting down
[junit] 2010-09-11 10:53:30,631 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@120] - PrepRequestProcessor exited loop!
[junit] 2010-09-11 10:53:30,632 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-09-11 10:53:30,632 [myid:] - INFO [main:FinalRequestProcessor@373] - shutdown of request processor complete
[junit] 2010-09-11 10:53:30,633 [myid:] - INFO [main:ClientBase@225] - connecting to 127.0.0.1 11221
[junit] ensureOnly:[]
[junit] 2010-09-11 10:53:30,636 [myid:] - INFO [main:ClientBase@493] - fdcount after test is: 34 at start it was 25
[junit] 2010-09-11 10:53:30,637 [myid:] - INFO [main:ClientBase@495] - sleeping for 20 secs
[junit] 2010-09-11 10:53:30,637 [myid:] - INFO [main:ZKTestCase$1@59] - SUCCEEDED testQuota
[junit] 2010-09-11 10:53:30,638 [myid:] - INFO [main:ZKTestCase$1@54] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.517 sec
BUILD FAILED
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:935: The following error occurred while executing this line:
<https://hudson.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:846: Tests failed!
Total time: 17 minutes 59 seconds
[WARNINGS] Skipping publisher since build result is FAILURE
[FINDBUGS] Skipping publisher since build result is FAILURE
Publishing Javadoc
Recording fingerprints
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure