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 Jenkins Server <je...@builds.apache.org> on 2016/09/03 16:36:50 UTC
ZooKeeper_branch35_solaris - Build # 233 - Failure
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/233/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 12215 lines...]
[junit] 2016-09-03 16:37:28,722 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 46445
[junit] 2016-09-03 16:37:28,723 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-03 16:37:28,723 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD test300Wide3DeepSerialize
[junit] 2016-09-03 16:37:28,723 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED test300Wide3DeepSerialize
[junit] 2016-09-03 16:37:28,723 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED test300Wide3DeepSerialize
[junit] 2016-09-03 16:37:28,725 [myid:] - INFO [main:ZKTestCase$1@55] - STARTING test10Wide5DeepSerialize
[junit] 2016-09-03 16:37:28,725 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD test10Wide5DeepSerialize
[junit] 2016-09-03 16:37:28,842 [myid:] - INFO [main:SerializationPerfTest@73] - Serialized 11116 nodes in 11 ms (1us/node), depth=5 width=10 datalen=20
[junit] 2016-09-03 16:37:28,843 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 7310
[junit] 2016-09-03 16:37:28,843 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-03 16:37:28,843 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD test10Wide5DeepSerialize
[junit] 2016-09-03 16:37:28,843 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED test10Wide5DeepSerialize
[junit] 2016-09-03 16:37:28,843 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED test10Wide5DeepSerialize
[junit] 2016-09-03 16:37:28,844 [myid:] - INFO [main:ZKTestCase$1@55] - STARTING test15Wide5DeepSerialize
[junit] 2016-09-03 16:37:28,844 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD test15Wide5DeepSerialize
[junit] 2016-09-03 16:37:29,686 [myid:] - INFO [main:SerializationPerfTest@73] - Serialized 54246 nodes in 68 ms (1us/node), depth=5 width=15 datalen=20
[junit] 2016-09-03 16:37:29,686 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 31923
[junit] 2016-09-03 16:37:29,686 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-03 16:37:29,686 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD test15Wide5DeepSerialize
[junit] 2016-09-03 16:37:29,687 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED test15Wide5DeepSerialize
[junit] 2016-09-03 16:37:29,687 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED test15Wide5DeepSerialize
[junit] 2016-09-03 16:37:29,687 [myid:] - INFO [main:ZKTestCase$1@55] - STARTING testSingleSerialize
[junit] 2016-09-03 16:37:29,687 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD testSingleSerialize
[junit] 2016-09-03 16:37:29,710 [myid:] - INFO [main:SerializationPerfTest@73] - Serialized 6 nodes in 0 ms (9us/node), depth=1 width=0 datalen=20
[junit] 2016-09-03 16:37:29,710 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 3565
[junit] 2016-09-03 16:37:29,710 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-03 16:37:29,710 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testSingleSerialize
[junit] 2016-09-03 16:37:29,710 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testSingleSerialize
[junit] 2016-09-03 16:37:29,710 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testSingleSerialize
[junit] 2016-09-03 16:37:29,711 [myid:] - INFO [main:ZKTestCase$1@55] - STARTING test25Wide4DeepSerialize
[junit] 2016-09-03 16:37:29,711 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD test25Wide4DeepSerialize
[junit] 2016-09-03 16:37:30,239 [myid:] - INFO [main:SerializationPerfTest@73] - Serialized 16281 nodes in 17 ms (1us/node), depth=4 width=25 datalen=20
[junit] 2016-09-03 16:37:30,239 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 11288
[junit] 2016-09-03 16:37:30,239 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-03 16:37:30,239 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD test25Wide4DeepSerialize
[junit] 2016-09-03 16:37:30,240 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED test25Wide4DeepSerialize
[junit] 2016-09-03 16:37:30,240 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED test25Wide4DeepSerialize
[junit] 2016-09-03 16:37:30,240 [myid:] - INFO [main:ZKTestCase$1@55] - STARTING testWideSerialize
[junit] 2016-09-03 16:37:30,240 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD testWideSerialize
[junit] 2016-09-03 16:37:30,850 [myid:] - INFO [main:SerializationPerfTest@73] - Serialized 10006 nodes in 8 ms (0us/node), depth=2 width=10000 datalen=20
[junit] 2016-09-03 16:37:30,850 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 6649
[junit] 2016-09-03 16:37:30,850 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-03 16:37:30,850 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testWideSerialize
[junit] 2016-09-03 16:37:30,850 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testWideSerialize
[junit] 2016-09-03 16:37:30,850 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testWideSerialize
[junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 10.542 sec
[junit] Running org.apache.zookeeper.server.SessionTrackerTest
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1219: Process fork failed.
Total time: 17 minutes 20 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
1 tests failed.
FAILED: org.apache.zookeeper.server.SessionTrackerTest.unknown
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
ZooKeeper_branch35_solaris - Build # 252 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/252/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 455090 lines...]
[junit] 2016-09-16 17:26:27,416 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-16 17:26:27,416 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-16 17:26:27,416 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-16 17:26:27,417 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-16 17:26:27,417 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-16 17:26:27,417 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-16 17:26:27,418 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test2389261188333985316.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test2389261188333985316.junit.dir/version-2
[junit] 2016-09-16 17:26:27,418 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test2389261188333985316.junit.dir/version-2/snapshot.b
[junit] 2016-09-16 17:26:27,420 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test2389261188333985316.junit.dir/version-2/snapshot.b
[junit] 2016-09-16 17:26:27,421 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-16 17:26:27,422 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-16 17:26:27,422 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:61899
[junit] 2016-09-16 17:26:27,423 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:61899
[junit] 2016-09-16 17:26:27,423 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-16 17:26:27,423 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:61899 (no session established for client)
[junit] 2016-09-16 17:26:27,423 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-16 17:26:27,424 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-16 17:26:27,425 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-16 17:26:27,425 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-16 17:26:27,425 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-16 17:26:27,425 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17780
[junit] 2016-09-16 17:26:27,425 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-16 17:26:27,425 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-16 17:26:27,426 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-16 17:26:27,502 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x123b10449db0000 closed
[junit] 2016-09-16 17:26:27,502 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x123b10449db0000
[junit] 2016-09-16 17:26:27,502 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-16 17:26:27,503 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-16 17:26:27,503 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-16 17:26:27,503 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-16 17:26:27,503 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-16 17:26:27,503 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-16 17:26:27,503 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-16 17:26:27,504 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-16 17:26:27,504 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-16 17:26:27,504 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-16 17:26:27,504 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-16 17:26:27,504 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-16 17:26:27,504 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-16 17:26:27,505 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-16 17:26:27,505 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-16 17:26:27,505 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-16 17:26:27,505 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-16 17:26:27,509 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-16 17:26:27,509 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-16 17:26:27,510 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-16 17:26:27,511 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 2.099 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1233: Tests failed!
Total time: 75 minutes 35 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
2 tests failed.
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 251 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/251/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 433671 lines...]
[junit] 2016-09-15 17:17:58,470 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-15 17:17:58,471 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-15 17:17:58,471 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-15 17:17:58,472 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-15 17:17:58,472 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-15 17:17:58,472 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-15 17:17:58,473 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test1482490038538531999.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test1482490038538531999.junit.dir/version-2
[junit] 2016-09-15 17:17:58,473 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test1482490038538531999.junit.dir/version-2/snapshot.b
[junit] 2016-09-15 17:17:58,475 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test1482490038538531999.junit.dir/version-2/snapshot.b
[junit] 2016-09-15 17:17:58,477 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-15 17:17:58,477 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-15 17:17:58,477 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:51756
[junit] 2016-09-15 17:17:58,478 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:51756
[junit] 2016-09-15 17:17:58,478 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-15 17:17:58,478 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:51756 (no session established for client)
[junit] 2016-09-15 17:17:58,479 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-15 17:17:58,480 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-15 17:17:58,480 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-15 17:17:58,480 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-15 17:17:58,480 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-15 17:17:58,481 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17796
[junit] 2016-09-15 17:17:58,481 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-15 17:17:58,481 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-15 17:17:58,481 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-15 17:17:58,562 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x123abd636c60000 closed
[junit] 2016-09-15 17:17:58,562 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-15 17:17:58,563 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-15 17:17:58,563 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-15 17:17:58,563 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-15 17:17:58,563 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-15 17:17:58,563 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x123abd636c60000
[junit] 2016-09-15 17:17:58,563 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-15 17:17:58,564 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-15 17:17:58,564 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-15 17:17:58,564 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-15 17:17:58,564 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-15 17:17:58,564 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-15 17:17:58,573 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-15 17:17:58,573 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-15 17:17:58,573 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-15 17:17:58,574 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-15 17:17:58,574 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-15 17:17:58,574 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-15 17:17:58,579 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-15 17:17:58,579 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-15 17:17:58,580 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-15 17:17:58,580 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.811 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1233: Tests failed!
Total time: 68 minutes 32 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
10 tests failed.
FAILED: org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig
Error Message:
waiting for server 3 being up
Stack Trace:
junit.framework.AssertionFailedError: waiting for server 3 being up
at org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig(ReconfigRecoveryTest.java:217)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.WatcherTest.testWatchAutoResetWithPending
Error Message:
Unexpected bean exists! expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: Unexpected bean exists! expected:<0> but was:<1>
at org.apache.zookeeper.test.ClientBase.verifyUnexpectedBeans(ClientBase.java:498)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:477)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at org.apache.zookeeper.test.WatcherTest.setUp(WatcherTest.java:73)
FAILED: org.apache.zookeeper.test.WatcherTest.testWatcherCorrectness
Error Message:
Unexpected bean exists! expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: Unexpected bean exists! expected:<0> but was:<1>
at org.apache.zookeeper.test.ClientBase.verifyUnexpectedBeans(ClientBase.java:498)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:477)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at org.apache.zookeeper.test.WatcherTest.setUp(WatcherTest.java:73)
FAILED: org.apache.zookeeper.test.WatcherTest.testWatcherAutoResetDisabledWithLocal
Error Message:
Unexpected bean exists! expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: Unexpected bean exists! expected:<0> but was:<1>
at org.apache.zookeeper.test.ClientBase.verifyUnexpectedBeans(ClientBase.java:498)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:477)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at org.apache.zookeeper.test.WatcherTest.setUp(WatcherTest.java:73)
FAILED: org.apache.zookeeper.test.WatcherTest.testWatcherAutoResetWithGlobal
Error Message:
Unexpected bean exists! expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: Unexpected bean exists! expected:<0> but was:<1>
at org.apache.zookeeper.test.ClientBase.verifyUnexpectedBeans(ClientBase.java:498)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:477)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at org.apache.zookeeper.test.WatcherTest.setUp(WatcherTest.java:73)
FAILED: org.apache.zookeeper.test.WatcherTest.testWatcherCount
Error Message:
Unexpected bean exists! expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: Unexpected bean exists! expected:<0> but was:<1>
at org.apache.zookeeper.test.ClientBase.verifyUnexpectedBeans(ClientBase.java:498)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:477)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at org.apache.zookeeper.test.WatcherTest.setUp(WatcherTest.java:73)
FAILED: org.apache.zookeeper.test.WatcherTest.testWatcherAutoResetDisabledWithGlobal
Error Message:
Unexpected bean exists! expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: Unexpected bean exists! expected:<0> but was:<1>
at org.apache.zookeeper.test.ClientBase.verifyUnexpectedBeans(ClientBase.java:498)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:477)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at org.apache.zookeeper.test.WatcherTest.setUp(WatcherTest.java:73)
FAILED: org.apache.zookeeper.test.WatcherTest.testWatcherAutoResetWithLocal
Error Message:
Unexpected bean exists! expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: Unexpected bean exists! expected:<0> but was:<1>
at org.apache.zookeeper.test.ClientBase.verifyUnexpectedBeans(ClientBase.java:498)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:477)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at org.apache.zookeeper.test.WatcherTest.setUp(WatcherTest.java:73)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 250 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/250/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 435869 lines...]
[junit] 2016-09-14 17:23:18,043 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-14 17:23:18,043 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-14 17:23:18,044 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-14 17:23:18,045 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-14 17:23:18,045 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-14 17:23:18,045 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-14 17:23:18,045 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test2511093430055494849.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test2511093430055494849.junit.dir/version-2
[junit] 2016-09-14 17:23:18,046 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test2511093430055494849.junit.dir/version-2/snapshot.b
[junit] 2016-09-14 17:23:18,048 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test2511093430055494849.junit.dir/version-2/snapshot.b
[junit] 2016-09-14 17:23:18,049 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-14 17:23:18,049 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-14 17:23:18,050 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:42038
[junit] 2016-09-14 17:23:18,050 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:42038
[junit] 2016-09-14 17:23:18,051 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-14 17:23:18,051 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:42038 (no session established for client)
[junit] 2016-09-14 17:23:18,051 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-14 17:23:18,052 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-14 17:23:18,053 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-14 17:23:18,053 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-14 17:23:18,053 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-14 17:23:18,053 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17791
[junit] 2016-09-14 17:23:18,053 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-14 17:23:18,053 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-14 17:23:18,054 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-14 17:23:18,132 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x123a6b4bac50000 closed
[junit] 2016-09-14 17:23:18,132 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-14 17:23:18,132 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x123a6b4bac50000
[junit] 2016-09-14 17:23:18,132 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-14 17:23:18,132 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-14 17:23:18,132 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-14 17:23:18,132 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-14 17:23:18,133 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-14 17:23:18,133 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-14 17:23:18,134 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-14 17:23:18,134 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-14 17:23:18,134 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-14 17:23:18,134 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-14 17:23:18,134 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-14 17:23:18,134 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-14 17:23:18,135 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-14 17:23:18,135 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-14 17:23:18,135 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-14 17:23:18,135 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-14 17:23:18,140 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-14 17:23:18,140 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-14 17:23:18,141 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-14 17:23:18,141 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.868 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1233: Tests failed!
Total time: 74 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
5 tests failed.
FAILED: org.apache.zookeeper.server.quorum.ReconfigBackupTest.testReconfigCreateNewVersionFile
Error Message:
waiting for server 4 being up
Stack Trace:
junit.framework.AssertionFailedError: waiting for server 4 being up
at org.apache.zookeeper.server.quorum.ReconfigBackupTest.testReconfigCreateNewVersionFile(ReconfigBackupTest.java:199)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.FLEZeroWeightTest.testZeroWeightQuorum
Error Message:
Threads didn't join
Stack Trace:
junit.framework.AssertionFailedError: Threads didn't join
at org.apache.zookeeper.test.FLEZeroWeightTest.testZeroWeightQuorum(FLEZeroWeightTest.java:167)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 249 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/249/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 118631 lines...]
[junit] 2016-09-13 16:49:11,370 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11252)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1]
[junit] 2016-09-13 16:49:11,370 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11252)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2]
[junit] 2016-09-13 16:49:11,370 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11252)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3]
[junit] 2016-09-13 16:49:11,370 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11252)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5]
[junit] 2016-09-13 16:49:11,370 [myid:] - INFO [/127.0.0.1:11247:QuorumCnxManager$Listener@659] - Leaving listener
[junit] 2016-09-13 16:49:11,371 [myid:] - INFO [main:QuorumBase@398] - Shutting down leader election QuorumPeer[myid=4](plain=/127.0.0.1:11252)(secure=disabled)
[junit] 2016-09-13 16:49:11,371 [myid:] - INFO [main:QuorumBase@403] - Waiting for QuorumPeer[myid=4](plain=/127.0.0.1:11252)(secure=disabled) to exit thread
[junit] 2016-09-13 16:49:11,371 [myid:] - INFO [main:QuorumBase@394] - Shutting down quorum peer QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled)
[junit] 2016-09-13 16:49:11,371 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-13 16:49:11,372 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:/127.0.0.1:11253:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-13 16:49:11,372 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-13 16:49:11,373 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-13 16:49:11,401 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=LeaderElection]
[junit] 2016-09-13 16:49:11,401 [myid:] - INFO [/127.0.0.1:11248:QuorumCnxManager$Listener@659] - Leaving listener
[junit] 2016-09-13 16:49:11,401 [myid:] - WARN [QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled):QuorumPeer@1089] - Unexpected exception
[junit] java.lang.InterruptedException
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2095)
[junit] at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection.lookForLeader(FastLeaderElection.java:908)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:1087)
[junit] 2016-09-13 16:49:11,402 [myid:] - WARN [QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled):QuorumPeer@1140] - QuorumPeer main thread exited
[junit] 2016-09-13 16:49:11,402 [myid:] - INFO [main:QuorumBase@398] - Shutting down leader election QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled)
[junit] 2016-09-13 16:49:11,402 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5]
[junit] 2016-09-13 16:49:11,402 [myid:] - INFO [main:QuorumBase@403] - Waiting for QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled) to exit thread
[junit] 2016-09-13 16:49:11,402 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5]
[junit] 2016-09-13 16:49:11,403 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1]
[junit] 2016-09-13 16:49:11,403 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2]
[junit] 2016-09-13 16:49:11,403 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3]
[junit] 2016-09-13 16:49:11,403 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11253)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4]
[junit] 2016-09-13 16:49:11,403 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11249
[junit] 2016-09-13 16:49:11,403 [myid:] - INFO [main:QuorumBase@376] - 127.0.0.1:11249 is no longer accepting client connections
[junit] 2016-09-13 16:49:11,404 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11250
[junit] 2016-09-13 16:49:11,404 [myid:] - INFO [main:QuorumBase@376] - 127.0.0.1:11250 is no longer accepting client connections
[junit] 2016-09-13 16:49:11,404 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11251
[junit] 2016-09-13 16:49:11,404 [myid:] - INFO [main:QuorumBase@376] - 127.0.0.1:11251 is no longer accepting client connections
[junit] 2016-09-13 16:49:11,404 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11252
[junit] 2016-09-13 16:49:11,404 [myid:] - INFO [main:QuorumBase@376] - 127.0.0.1:11252 is no longer accepting client connections
[junit] 2016-09-13 16:49:11,404 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11253
[junit] 2016-09-13 16:49:11,405 [myid:] - INFO [main:QuorumBase@376] - 127.0.0.1:11253 is no longer accepting client connections
[junit] 2016-09-13 16:49:11,406 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 91785
[junit] 2016-09-13 16:49:11,406 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 20
[junit] 2016-09-13 16:49:11,406 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testObserversHammer
[junit] 2016-09-13 16:49:11,406 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testObserversHammer
[junit] 2016-09-13 16:49:11,407 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testObserversHammer
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 77.203 sec
[junit] Running org.apache.zookeeper.test.AsyncOpsTest
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1219: Process fork failed.
Total time: 38 minutes 14 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
2 tests failed.
FAILED: org.apache.zookeeper.server.admin.JettyAdminServerTest.testQuorum
Error Message:
Connection refused
Stack Trace:
java.net.ConnectException: Connection refused
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
at java.net.Socket.connect(Socket.java:579)
at java.net.Socket.connect(Socket.java:528)
at sun.net.NetworkClient.doConnect(NetworkClient.java:180)
at sun.net.www.http.HttpClient.openServer(HttpClient.java:432)
at sun.net.www.http.HttpClient.openServer(HttpClient.java:527)
at sun.net.www.http.HttpClient.<init>(HttpClient.java:211)
at sun.net.www.http.HttpClient.New(HttpClient.java:308)
at sun.net.www.http.HttpClient.New(HttpClient.java:326)
at sun.net.www.protocol.http.HttpURLConnection.getNewHttpClient(HttpURLConnection.java:997)
at sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:933)
at sun.net.www.protocol.http.HttpURLConnection.connect(HttpURLConnection.java:851)
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1301)
at java.net.URL.openStream(URL.java:1037)
at org.apache.zookeeper.server.admin.JettyAdminServerTest.queryAdminServer(JettyAdminServerTest.java:158)
at org.apache.zookeeper.server.admin.JettyAdminServerTest.queryAdminServer(JettyAdminServerTest.java:150)
at org.apache.zookeeper.server.admin.JettyAdminServerTest.testQuorum(JettyAdminServerTest.java:131)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.AsyncOpsTest.unknown
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
ZooKeeper_branch35_solaris - Build # 248 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/248/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 433329 lines...]
[junit] 2016-09-12 17:21:59,196 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-12 17:21:59,196 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-12 17:21:59,197 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-12 17:21:59,197 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-12 17:21:59,198 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-12 17:21:59,198 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-12 17:21:59,198 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test5033281499837489429.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test5033281499837489429.junit.dir/version-2
[junit] 2016-09-12 17:21:59,199 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test5033281499837489429.junit.dir/version-2/snapshot.b
[junit] 2016-09-12 17:21:59,201 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test5033281499837489429.junit.dir/version-2/snapshot.b
[junit] 2016-09-12 17:21:59,202 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-12 17:21:59,202 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-12 17:21:59,203 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:65037
[junit] 2016-09-12 17:21:59,203 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:65037
[junit] 2016-09-12 17:21:59,203 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-12 17:21:59,204 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:65037 (no session established for client)
[junit] 2016-09-12 17:21:59,204 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-12 17:21:59,205 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-12 17:21:59,205 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-12 17:21:59,206 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-12 17:21:59,206 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-12 17:21:59,206 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17797
[junit] 2016-09-12 17:21:59,206 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-12 17:21:59,206 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-12 17:21:59,206 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-12 17:21:59,282 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x1239c66d6e10000 closed
[junit] 2016-09-12 17:21:59,282 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-12 17:21:59,282 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1239c66d6e10000
[junit] 2016-09-12 17:21:59,282 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-12 17:21:59,282 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-12 17:21:59,282 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-12 17:21:59,282 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-12 17:21:59,283 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-12 17:21:59,283 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-12 17:21:59,283 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-12 17:21:59,283 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-12 17:21:59,283 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-12 17:21:59,283 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-12 17:21:59,284 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-12 17:21:59,284 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-12 17:21:59,284 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-12 17:21:59,284 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-12 17:21:59,285 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-12 17:21:59,285 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-12 17:21:59,289 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-12 17:21:59,289 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-12 17:21:59,290 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-12 17:21:59,290 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.821 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1233: Tests failed!
Total time: 71 minutes 38 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
3 tests failed.
FAILED: org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig
Error Message:
waiting for server 4 being up
Stack Trace:
junit.framework.AssertionFailedError: waiting for server 4 being up
at org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig(ReconfigRecoveryTest.java:217)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 247 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/247/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 450178 lines...]
[junit] 2016-09-12 07:28:26,260 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-12 07:28:26,260 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-12 07:28:26,261 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-12 07:28:26,262 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-12 07:28:26,262 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-12 07:28:26,262 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-12 07:28:26,263 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test780369619465039443.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test780369619465039443.junit.dir/version-2
[junit] 2016-09-12 07:28:26,263 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test780369619465039443.junit.dir/version-2/snapshot.b
[junit] 2016-09-12 07:28:26,265 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test780369619465039443.junit.dir/version-2/snapshot.b
[junit] 2016-09-12 07:28:26,267 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-12 07:28:26,267 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-12 07:28:26,267 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:55925
[junit] 2016-09-12 07:28:26,268 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:55925
[junit] 2016-09-12 07:28:26,268 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-12 07:28:26,269 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:55925 (no session established for client)
[junit] 2016-09-12 07:28:26,269 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-12 07:28:26,270 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-12 07:28:26,270 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-12 07:28:26,270 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-12 07:28:26,271 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-12 07:28:26,271 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17789
[junit] 2016-09-12 07:28:26,271 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-12 07:28:26,271 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-12 07:28:26,271 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-12 07:28:26,332 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x1239a4774520000 closed
[junit] 2016-09-12 07:28:26,332 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1239a4774520000
[junit] 2016-09-12 07:28:26,332 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-12 07:28:26,333 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-12 07:28:26,333 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-12 07:28:26,333 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-12 07:28:26,333 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-12 07:28:26,333 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-12 07:28:26,334 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-12 07:28:26,334 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-12 07:28:26,334 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-12 07:28:26,334 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-12 07:28:26,334 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-12 07:28:26,334 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-12 07:28:26,335 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-12 07:28:26,335 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-12 07:28:26,335 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-12 07:28:26,335 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-12 07:28:26,336 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-12 07:28:26,340 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-12 07:28:26,340 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-12 07:28:26,342 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-12 07:28:26,342 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.109 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1233: Tests failed!
Total time: 74 minutes 42 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
3 tests failed.
FAILED: org.apache.zookeeper.test.NioNettySuiteTest.testRemoveAddTwo
Error Message:
Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
Stack Trace:
junit.framework.AssertionFailedError: Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 246 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/246/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 436189 lines...]
[junit] 2016-09-11 17:24:13,386 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-11 17:24:13,386 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-11 17:24:13,387 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-11 17:24:13,388 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-11 17:24:13,388 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-11 17:24:13,389 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-11 17:24:13,389 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2481539315367179239.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2481539315367179239.junit.dir/version-2
[junit] 2016-09-11 17:24:13,389 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2481539315367179239.junit.dir/version-2/snapshot.b
[junit] 2016-09-11 17:24:13,391 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2481539315367179239.junit.dir/version-2/snapshot.b
[junit] 2016-09-11 17:24:13,393 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-11 17:24:13,393 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-11 17:24:13,394 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:56246
[junit] 2016-09-11 17:24:13,398 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:56246
[junit] 2016-09-11 17:24:13,398 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-11 17:24:13,399 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:56246 (no session established for client)
[junit] 2016-09-11 17:24:13,401 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-11 17:24:13,402 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-11 17:24:13,402 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-11 17:24:13,402 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-11 17:24:13,403 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-11 17:24:13,403 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 18014
[junit] 2016-09-11 17:24:13,403 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-11 17:24:13,403 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-11 17:24:13,403 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-11 17:24:13,472 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x12397428eb60000 closed
[junit] 2016-09-11 17:24:13,472 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-11 17:24:13,472 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x12397428eb60000
[junit] 2016-09-11 17:24:13,472 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-11 17:24:13,472 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-11 17:24:13,472 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-11 17:24:13,472 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-11 17:24:13,473 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-11 17:24:13,473 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-11 17:24:13,473 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-11 17:24:13,473 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-11 17:24:13,473 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-11 17:24:13,473 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-11 17:24:13,474 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-11 17:24:13,474 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-11 17:24:13,474 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-11 17:24:13,474 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-11 17:24:13,475 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-11 17:24:13,475 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-11 17:24:13,479 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-11 17:24:13,479 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-11 17:24:13,480 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-11 17:24:13,480 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.933 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1233: Tests failed!
Total time: 70 minutes 54 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
2 tests failed.
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 245 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/245/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 27540 lines...]
[junit] 2016-09-10 16:30:12,915 [myid:] - INFO [main:ProposalRequestProcessor@88] - Shutting down
[junit] 2016-09-10 16:30:12,915 [myid:2] - INFO [ProcessThread(sid:2 cport:-1)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-10 16:30:12,916 [myid:] - INFO [main:CommitProcessor@358] - Shutting down
[junit] 2016-09-10 16:30:12,916 [myid:2] - INFO [CommitProcessor:2:CommitProcessor@193] - CommitProcessor exited loop!
[junit] 2016-09-10 16:30:12,916 [myid:] - INFO [main:Leader$ToBeAppliedRequestProcessor@924] - Shutting down
[junit] 2016-09-10 16:30:12,916 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-10 16:30:12,916 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-10 16:30:12,917 [myid:2] - INFO [SyncThread:2:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-10 16:30:12,917 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Leader,name3=InMemoryDataTree]
[junit] 2016-09-10 16:30:12,918 [myid:2] - WARN [LearnerHandler-/127.0.0.1:59915:LearnerHandler@619] - ******* GOODBYE /127.0.0.1:59915 ********
[junit] 2016-09-10 16:30:12,918 [myid:2] - WARN [LearnerHandler-/127.0.0.1:59915:LearnerHandler@903] - Ignoring unexpected exception
[junit] java.lang.InterruptedException
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219)
[junit] at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340)
[junit] at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338)
[junit] at org.apache.zookeeper.server.quorum.LearnerHandler.shutdown(LearnerHandler.java:901)
[junit] at org.apache.zookeeper.server.quorum.LearnerHandler.run(LearnerHandler.java:622)
[junit] 2016-09-10 16:30:12,918 [myid:2] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-10 16:30:12,918 [myid:2] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-10 16:30:12,918 [myid:2] - INFO [NIOServerCxnFactory.AcceptThread:/0.0.0.0:11225:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-10 16:30:12,918 [myid:2] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-10 16:30:12,921 [myid:] - INFO [main:AbstractConnector@306] - Stopped ServerConnector@b2d899{HTTP/1.1}{0.0.0.0:11227}
[junit] 2016-09-10 16:30:12,922 [myid:] - INFO [main:ContextHandler@865] - Stopped o.e.j.s.ServletContextHandler@6f40bd{/,null,UNAVAILABLE}
[junit] 2016-09-10 16:30:12,923 [myid:2] - INFO [/127.0.0.1:11231:QuorumCnxManager$Listener@659] - Leaving listener
[junit] 2016-09-10 16:30:12,923 [myid:2] - INFO [QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Leader]
[junit] 2016-09-10 16:30:12,924 [myid:] - INFO [main:QuorumBase@398] - Shutting down leader election QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled)
[junit] 2016-09-10 16:30:12,924 [myid:2] - WARN [QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled):QuorumPeer@1127] - Unexpected exception
[junit] java.lang.InterruptedException
[junit] at java.lang.Object.wait(Native Method)
[junit] at org.apache.zookeeper.server.quorum.Leader.lead(Leader.java:561)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:1124)
[junit] 2016-09-10 16:30:12,924 [myid:] - INFO [main:QuorumBase@403] - Waiting for QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled) to exit thread
[junit] 2016-09-10 16:30:12,924 [myid:2] - INFO [QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled):Leader@623] - Shutting down
[junit] 2016-09-10 16:30:12,924 [myid:2] - WARN [QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled):QuorumPeer@1158] - PeerState set to LOOKING
[junit] 2016-09-10 16:30:12,924 [myid:2] - WARN [QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled):QuorumPeer@1140] - QuorumPeer main thread exited
[junit] 2016-09-10 16:30:12,925 [myid:2] - INFO [QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2]
[junit] 2016-09-10 16:30:12,925 [myid:2] - INFO [QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2]
[junit] 2016-09-10 16:30:12,925 [myid:2] - INFO [QuorumPeer[myid=2](plain=/0.0.0.0:11225)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1]
[junit] 2016-09-10 16:30:12,925 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11224
[junit] 2016-09-10 16:30:12,926 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11225
[junit] 2016-09-10 16:30:12,926 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 20923
[junit] 2016-09-10 16:30:12,926 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 10
[junit] 2016-09-10 16:30:12,926 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuorum
[junit] 2016-09-10 16:30:12,926 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuorum
[junit] 2016-09-10 16:30:12,926 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuorum
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 17.521 sec
[junit] Running org.apache.zookeeper.server.quorum.CommitProcessorConcurrencyTest
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1219: Process fork failed.
Total time: 17 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
1 tests failed.
FAILED: org.apache.zookeeper.server.quorum.CommitProcessorConcurrencyTest.unknown
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
ZooKeeper_branch35_solaris - Build # 244 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/244/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 170154 lines...]
[junit] 2016-09-09 17:06:27,996 [myid:] - INFO [NIOWorkerThread-4:ZooKeeperServer@995] - Client attempting to establish new session at /127.0.0.1:38194
[junit] 2016-09-09 17:06:27,998 [myid:] - INFO [SyncThread:0:ZooKeeperServer@709] - Established session 0x1238ce59dc10001 with negotiated timeout 30000 for client /127.0.0.1:38194
[junit] 2016-09-09 17:06:27,998 [myid:127.0.0.1:11226] - INFO [Time-limited test-SendThread(127.0.0.1:11226):ClientCnxn$SendThread@1381] - Session establishment complete on server null, sessionid = 0x1238ce59dc10001, negotiated timeout = 30000
[junit] 2016-09-09 17:06:27,999 [myid:] - INFO [Time-limited test:JMXEnv@117] - expect:0x1238ce59dc10001
[junit] 2016-09-09 17:06:27,999 [myid:] - INFO [Time-limited test:JMXEnv@120] - found:0x1238ce59dc10001 org.apache.ZooKeeperService:name0=StandaloneServer_port11226,name1=Connections,name2=127.0.0.1,name3=0x1238ce59dc10001
[junit] 2016-09-09 17:06:28,000 [myid:] - INFO [Time-limited test:FourLetterWordMain@85] - connecting to 127.0.0.1 11226
[junit] 2016-09-09 17:06:28,000 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11226:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:38195
[junit] 2016-09-09 17:06:28,000 [myid:] - INFO [NIOWorkerThread-6:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:38195
[junit] 2016-09-09 17:06:28,001 [myid:] - INFO [NIOWorkerThread-6:StatCommand@49] - Stat command output
[junit] 2016-09-09 17:06:28,001 [myid:] - INFO [NIOWorkerThread-6:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:38195 (no session established for client)
[junit] 2016-09-09 17:06:28,003 [myid:] - INFO [ProcessThread(sid:0 cport:11226)::PrepRequestProcessor@647] - Processed session termination for sessionid: 0x1238ce59dc10000
[junit] 2016-09-09 17:06:28,004 [myid:] - INFO [NIOWorkerThread-8:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11226,name1=Connections,name2=127.0.0.1,name3=0x1238ce59dc10000]
[junit] 2016-09-09 17:06:28,004 [myid:] - INFO [Time-limited test-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1238ce59dc10000
[junit] 2016-09-09 17:06:28,004 [myid:] - INFO [Time-limited test:ZooKeeper@1313] - Session: 0x1238ce59dc10000 closed
[junit] 2016-09-09 17:06:28,005 [myid:] - INFO [NIOWorkerThread-8:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:38193 which had sessionid 0x1238ce59dc10000
[junit] 2016-09-09 17:06:28,006 [myid:] - INFO [ProcessThread(sid:0 cport:11226)::PrepRequestProcessor@647] - Processed session termination for sessionid: 0x1238ce59dc10001
[junit] 2016-09-09 17:06:28,007 [myid:] - INFO [Time-limited test:ZooKeeper@1313] - Session: 0x1238ce59dc10001 closed
[junit] 2016-09-09 17:06:28,007 [myid:] - INFO [Time-limited test-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1238ce59dc10001
[junit] 2016-09-09 17:06:28,007 [myid:] - INFO [Time-limited test:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 10789
[junit] 2016-09-09 17:06:28,007 [myid:] - INFO [NIOWorkerThread-10:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11226,name1=Connections,name2=127.0.0.1,name3=0x1238ce59dc10001]
[junit] 2016-09-09 17:06:28,008 [myid:] - INFO [Time-limited test:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 37
[junit] 2016-09-09 17:06:28,008 [myid:] - INFO [NIOWorkerThread-10:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:38194 which had sessionid 0x1238ce59dc10001
[junit] 2016-09-09 17:06:28,008 [myid:] - INFO [Time-limited test:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testValidateStatOutput
[junit] 2016-09-09 17:06:28,008 [myid:] - INFO [Time-limited test:ClientBase@543] - tearDown starting
[junit] 2016-09-09 17:06:28,008 [myid:] - INFO [Time-limited test:ClientBase@513] - STOPPING server
[junit] 2016-09-09 17:06:28,009 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-09 17:06:28,009 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11226:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-09 17:06:28,009 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-09 17:06:28,010 [myid:] - INFO [Time-limited test:ZooKeeperServer@529] - shutting down
[junit] 2016-09-09 17:06:28,010 [myid:] - ERROR [Time-limited test:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-09 17:06:28,010 [myid:] - INFO [Time-limited test:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-09 17:06:28,010 [myid:] - INFO [Time-limited test:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-09 17:06:28,010 [myid:] - INFO [Time-limited test:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-09 17:06:28,010 [myid:] - INFO [ProcessThread(sid:0 cport:11226)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-09 17:06:28,011 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-09 17:06:28,011 [myid:] - INFO [Time-limited test:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-09 17:06:28,011 [myid:] - INFO [Time-limited test:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11226,name1=InMemoryDataTree]
[junit] 2016-09-09 17:06:28,011 [myid:] - INFO [Time-limited test:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11226]
[junit] 2016-09-09 17:06:28,012 [myid:] - INFO [Time-limited test:FourLetterWordMain@85] - connecting to 127.0.0.1 11226
[junit] 2016-09-09 17:06:28,012 [myid:] - INFO [Time-limited test:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-09 17:06:28,012 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-09 17:06:28,037 [myid:] - INFO [Time-limited test:ClientBase@568] - fdcount after test is: 68 at start it was 60
[junit] 2016-09-09 17:06:28,038 [myid:] - INFO [Time-limited test:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-09 17:06:28,038 [myid:] - INFO [Time-limited test:ZKTestCase$1@65] - SUCCEEDED testValidateStatOutput
[junit] 2016-09-09 17:06:28,038 [myid:] - INFO [Time-limited test:ZKTestCase$1@60] - FINISHED testValidateStatOutput
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 8.716 sec
[junit] Running org.apache.zookeeper.test.GetChildren2Test
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1219: Process fork failed.
Total time: 53 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
3 tests failed.
FAILED: org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentObserverIsParticipantInNewConfig
Error Message:
waiting for server 2 being up
Stack Trace:
junit.framework.AssertionFailedError: waiting for server 2 being up
at org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentObserverIsParticipantInNewConfig(ReconfigRecoveryTest.java:529)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.server.quorum.WatchLeakTest.testWatchesLeak[0]
Error Message:
Session is valid so the watch should be there expected:<1> but was:<0>
Stack Trace:
junit.framework.AssertionFailedError: Session is valid so the watch should be there expected:<1> but was:<0>
at org.apache.zookeeper.server.quorum.WatchLeakTest.testWatchesLeak(WatchLeakTest.java:168)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.GetChildren2Test.unknown
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
ZooKeeper_branch35_solaris - Build # 243 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/243/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 10288 lines...]
[junit] 2016-09-09 02:02:42,475 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-09 02:02:42,475 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11231,name1=InMemoryDataTree
[junit] 2016-09-09 02:02:42,475 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-09 02:02:42,475 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11231
[junit] 2016-09-09 02:02:42,475 [myid:] - INFO [main:ClientBase@462] - Client test setup finished
[junit] 2016-09-09 02:02:42,475 [myid:] - INFO [main:ZooKeeper@855] - Initiating client connection, connectString=127.0.0.1:11231 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@6b80fa
[junit] 2016-09-09 02:02:42,476 [myid:127.0.0.1:11231] - INFO [main-SendThread(127.0.0.1:11231):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:11231. Will not attempt to authenticate using SASL (unknown error)
[junit] 2016-09-09 02:02:42,477 [myid:127.0.0.1:11231] - INFO [main-SendThread(127.0.0.1:11231):ClientCnxn$SendThread@948] - Socket connection established, initiating session, client: null, server: null
[junit] 2016-09-09 02:02:42,477 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11231:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:51783
[junit] 2016-09-09 02:02:42,488 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@995] - Client attempting to establish new session at /127.0.0.1:51783
[junit] 2016-09-09 02:02:42,489 [myid:] - INFO [SyncThread:0:FileTxnLog@204] - Creating new log file: log.1
[junit] 2016-09-09 02:02:42,490 [myid:] - INFO [SyncThread:0:ZooKeeperServer@709] - Established session 0x12389aa335c0000 with negotiated timeout 30000 for client /127.0.0.1:51783
[junit] 2016-09-09 02:02:42,491 [myid:127.0.0.1:11231] - INFO [main-SendThread(127.0.0.1:11231):ClientCnxn$SendThread@1381] - Session establishment complete on server null, sessionid = 0x12389aa335c0000, negotiated timeout = 30000
[junit] 2016-09-09 02:02:42,492 [myid:] - INFO [main:JMXEnv@117] - expect:0x12389aa335c0000
[junit] 2016-09-09 02:02:42,492 [myid:] - INFO [main:JMXEnv@120] - found:0x12389aa335c0000 org.apache.ZooKeeperService:name0=StandaloneServer_port11231,name1=Connections,name2=127.0.0.1,name3=0x12389aa335c0000
[junit] 2016-09-09 02:02:42,492 [myid:] - INFO [Time-limited test:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD testCreate
[junit] 2016-09-09 02:02:42,501 [myid:] - INFO [Time-limited test:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 10188
[junit] 2016-09-09 02:02:42,501 [myid:] - INFO [Time-limited test:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 42
[junit] 2016-09-09 02:02:42,501 [myid:] - INFO [Time-limited test:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testCreate
[junit] 2016-09-09 02:02:42,501 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-09 02:02:42,502 [myid:] - INFO [ProcessThread(sid:0 cport:11231)::PrepRequestProcessor@647] - Processed session termination for sessionid: 0x12389aa335c0000
[junit] 2016-09-09 02:02:42,503 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11231,name1=Connections,name2=127.0.0.1,name3=0x12389aa335c0000]
[junit] 2016-09-09 02:02:42,503 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x12389aa335c0000 closed
[junit] 2016-09-09 02:02:42,503 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-09 02:02:42,503 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x12389aa335c0000
[junit] 2016-09-09 02:02:42,503 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:51783 which had sessionid 0x12389aa335c0000
[junit] 2016-09-09 02:02:42,504 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-09 02:02:42,504 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-09 02:02:42,505 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11231:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-09 02:02:42,506 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-09 02:02:42,506 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-09 02:02:42,506 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-09 02:02:42,506 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-09 02:02:42,506 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-09 02:02:42,506 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-09 02:02:42,506 [myid:] - INFO [ProcessThread(sid:0 cport:11231)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-09 02:02:42,506 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-09 02:02:42,507 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-09 02:02:42,507 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11231,name1=InMemoryDataTree]
[junit] 2016-09-09 02:02:42,507 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11231]
[junit] 2016-09-09 02:02:42,507 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11231
[junit] 2016-09-09 02:02:42,508 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-09 02:02:42,525 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 66 at start it was 66
[junit] 2016-09-09 02:02:42,525 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testCreate
[junit] 2016-09-09 02:02:42,525 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testCreate
[junit] Tests run: 10, Failures: 0, Errors: 0, Time elapsed: 34.346 sec
[junit] Running org.apache.zookeeper.server.DataTreeTest
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1219: Process fork failed.
Total time: 8 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
1 tests failed.
FAILED: org.apache.zookeeper.server.DataTreeTest.unknown
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
ZooKeeper_branch35_solaris - Build # 242 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/242/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 91693 lines...]
[junit] 2016-09-08 21:43:49,866 [myid:2] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-08 21:43:49,866 [myid:2] - WARN [LearnerHandler-/127.0.0.1:59803:LearnerHandler@619] - ******* GOODBYE /127.0.0.1:59803 ********
[junit] 2016-09-08 21:43:49,867 [myid:2] - WARN [LearnerHandler-/127.0.0.1:59804:LearnerHandler@903] - Ignoring unexpected exception
[junit] java.lang.InterruptedException
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219)
[junit] at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340)
[junit] at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338)
[junit] at org.apache.zookeeper.server.quorum.LearnerHandler.shutdown(LearnerHandler.java:901)
[junit] at org.apache.zookeeper.server.quorum.LearnerHandler.run(LearnerHandler.java:622)
[junit] 2016-09-08 21:43:49,867 [myid:2] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-08 21:43:49,867 [myid:2] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-08 21:43:49,867 [myid:2] - INFO [NIOServerCxnFactory.AcceptThread:localhost/127.0.0.1:11247:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-08 21:43:49,867 [myid:2] - WARN [LearnerHandler-/127.0.0.1:59803:LearnerHandler@903] - Ignoring unexpected exception
[junit] java.lang.InterruptedException
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219)
[junit] at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340)
[junit] at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338)
[junit] at org.apache.zookeeper.server.quorum.LearnerHandler.shutdown(LearnerHandler.java:901)
[junit] at org.apache.zookeeper.server.quorum.LearnerHandler.run(LearnerHandler.java:622)
[junit] 2016-09-08 21:43:49,868 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Leader]
[junit] 2016-09-08 21:43:49,868 [myid:2] - INFO [localhost/127.0.0.1:11249:QuorumCnxManager$Listener@659] - Leaving listener
[junit] 2016-09-08 21:43:49,869 [myid:2] - WARN [QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled):QuorumPeer@1127] - Unexpected exception
[junit] java.lang.InterruptedException
[junit] at java.lang.Object.wait(Native Method)
[junit] at org.apache.zookeeper.server.quorum.Leader.lead(Leader.java:561)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:1124)
[junit] 2016-09-08 21:43:49,869 [myid:] - INFO [main:QuorumBase@398] - Shutting down leader election QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled)
[junit] 2016-09-08 21:43:49,869 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled):Leader@623] - Shutting down
[junit] 2016-09-08 21:43:49,869 [myid:] - INFO [main:QuorumBase@403] - Waiting for QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled) to exit thread
[junit] 2016-09-08 21:43:49,869 [myid:2] - WARN [QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled):QuorumPeer@1158] - PeerState set to LOOKING
[junit] 2016-09-08 21:43:49,870 [myid:2] - WARN [QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled):QuorumPeer@1140] - QuorumPeer main thread exited
[junit] 2016-09-08 21:43:49,870 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2]
[junit] 2016-09-08 21:43:49,870 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2]
[junit] 2016-09-08 21:43:49,870 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.0]
[junit] 2016-09-08 21:43:49,870 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11247)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1]
[junit] 2016-09-08 21:43:49,971 [myid:2] - INFO [WorkerSender[myid=2]:FastLeaderElection$Messenger$WorkerSender@470] - WorkerSender is down
[junit] 2016-09-08 21:43:49,971 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x22388bd1bec0000
[junit] 2016-09-08 21:43:49,971 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x22388bd1bec0000 closed
[junit] 2016-09-08 21:43:49,971 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 8748
[junit] 2016-09-08 21:43:49,971 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 9
[junit] 2016-09-08 21:43:49,971 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testConfigFileBackwardCompatibility
[junit] 2016-09-08 21:43:49,971 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testConfigFileBackwardCompatibility
[junit] 2016-09-08 21:43:49,971 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testConfigFileBackwardCompatibility
[junit] 2016-09-08 21:43:50,011 [myid:2] - INFO [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@440] - WorkerReceiver is down
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 63.263 sec
[junit] 2016-09-08 21:43:50,261 [myid:2] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
[junit] Running org.apache.zookeeper.server.quorum.ReconfigRecoveryTest
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1219: Process fork failed.
Total time: 31 minutes 13 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
2 tests failed.
FAILED: org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testInconsistentPeerType
Error Message:
waiting for server 1 being up
Stack Trace:
junit.framework.AssertionFailedError: waiting for server 1 being up
at org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testInconsistentPeerType(QuorumPeerMainTest.java:505)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.unknown
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
ZooKeeper_branch35_solaris - Build # 241 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/241/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 11942 lines...]
[junit] 2016-09-08 18:43:21,634 [myid:] - INFO [main:ZKTestCase$1@55] - STARTING testSnapFilesLessThanToRetain
[junit] 2016-09-08 18:43:21,635 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD testSnapFilesLessThanToRetain
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test4446558386269237548.junit.dir/version-2/log.3
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test4446558386269237548.junit.dir/version-2/log.1
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test4446558386269237548.junit.dir/version-2/snapshot.2
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test4446558386269237548.junit.dir/version-2/snapshot.0
[junit] 2016-09-08 18:43:21,683 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 23016
[junit] 2016-09-08 18:43:21,683 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-08 18:43:21,683 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testSnapFilesLessThanToRetain
[junit] 2016-09-08 18:43:21,723 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testSnapFilesLessThanToRetain
[junit] 2016-09-08 18:43:21,723 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testSnapFilesLessThanToRetain
[junit] 2016-09-08 18:43:21,723 [myid:] - INFO [main:ZKTestCase$1@55] - STARTING testPurgeTxnLogWithDataDir
[junit] 2016-09-08 18:43:21,723 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD testPurgeTxnLogWithDataDir
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.9
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.3
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.4
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.0
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.7
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.5
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.2
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.8
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.6
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataLogDir/version-2/log.1
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.3
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.9
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.4
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.0
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.7
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.5
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.8
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.2
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.6
[junit] Removing file: Sep 8, 2016 6:43:21 PM /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2602326972721541945.junit.dir/dataDir/version-2/snapshot.1
[junit] 2016-09-08 18:43:21,985 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 23454
[junit] 2016-09-08 18:43:21,985 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-08 18:43:21,985 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testPurgeTxnLogWithDataDir
[junit] 2016-09-08 18:43:21,985 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testPurgeTxnLogWithDataDir
[junit] 2016-09-08 18:43:21,985 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testPurgeTxnLogWithDataDir
[junit] 2016-09-08 18:43:21,986 [myid:] - INFO [main:ZKTestCase$1@55] - STARTING testFindNRecentSnapshots
[junit] 2016-09-08 18:43:21,986 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD testFindNRecentSnapshots
[junit] 2016-09-08 18:43:22,005 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 23892
[junit] 2016-09-08 18:43:22,005 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-08 18:43:22,005 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testFindNRecentSnapshots
[junit] 2016-09-08 18:43:22,045 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testFindNRecentSnapshots
[junit] 2016-09-08 18:43:22,045 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testFindNRecentSnapshots
[junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 36.365 sec
[junit] Running org.apache.zookeeper.server.ReferenceCountedACLCacheTest
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1219: Process fork failed.
Total time: 13 minutes 27 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
1 tests failed.
FAILED: org.apache.zookeeper.server.ReferenceCountedACLCacheTest.unknown
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
ZooKeeper_branch35_solaris - Build # 240 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/240/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 437156 lines...]
[junit] 2016-09-08 09:39:33,496 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-08 09:39:33,497 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-08 09:39:33,497 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-08 09:39:33,497 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-08 09:39:33,498 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-08 09:39:33,498 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-08 09:39:33,498 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test6634353654981633660.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test6634353654981633660.junit.dir/version-2
[junit] 2016-09-08 09:39:33,498 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test6634353654981633660.junit.dir/version-2/snapshot.b
[junit] 2016-09-08 09:39:33,500 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test6634353654981633660.junit.dir/version-2/snapshot.b
[junit] 2016-09-08 09:39:33,501 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-08 09:39:33,501 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-08 09:39:33,502 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:39293
[junit] 2016-09-08 09:39:33,508 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:39293
[junit] 2016-09-08 09:39:33,508 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-08 09:39:33,509 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:39293 (no session established for client)
[junit] 2016-09-08 09:39:33,509 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-08 09:39:33,510 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-08 09:39:33,510 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-08 09:39:33,510 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-08 09:39:33,510 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-08 09:39:33,511 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 15702
[junit] 2016-09-08 09:39:33,511 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-08 09:39:33,511 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-08 09:39:33,511 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-08 09:39:33,582 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x123862620ac0000 closed
[junit] 2016-09-08 09:39:33,583 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-08 09:39:33,583 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x123862620ac0000
[junit] 2016-09-08 09:39:33,583 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-08 09:39:33,583 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-08 09:39:33,583 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-08 09:39:33,583 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-08 09:39:33,584 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-08 09:39:33,584 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-08 09:39:33,584 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-08 09:39:33,584 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-08 09:39:33,585 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-08 09:39:33,585 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-08 09:39:33,585 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-08 09:39:33,585 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-08 09:39:33,586 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-08 09:39:33,586 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-08 09:39:33,586 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-08 09:39:33,587 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-08 09:39:33,593 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-08 09:39:33,593 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-08 09:39:33,594 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-08 09:39:33,595 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.101 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1233: Tests failed!
Total time: 65 minutes 27 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
2 tests failed.
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 239 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/239/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 485212 lines...]
[junit] 2016-09-08 04:30:11,761 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-08 04:30:11,761 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-08 04:30:11,762 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-08 04:30:11,763 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-08 04:30:11,763 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-08 04:30:11,763 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-08 04:30:11,763 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test3619808492391792431.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test3619808492391792431.junit.dir/version-2
[junit] 2016-09-08 04:30:11,764 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test3619808492391792431.junit.dir/version-2/snapshot.b
[junit] 2016-09-08 04:30:11,766 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test3619808492391792431.junit.dir/version-2/snapshot.b
[junit] 2016-09-08 04:30:11,767 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-08 04:30:11,767 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-08 04:30:11,768 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:44643
[junit] 2016-09-08 04:30:11,768 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:44643
[junit] 2016-09-08 04:30:11,769 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-08 04:30:11,769 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:44643 (no session established for client)
[junit] 2016-09-08 04:30:11,769 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-08 04:30:11,771 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-08 04:30:11,771 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-08 04:30:11,771 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-08 04:30:11,771 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-08 04:30:11,771 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17780
[junit] 2016-09-08 04:30:11,772 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-08 04:30:11,772 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-08 04:30:11,772 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-08 04:30:11,852 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x123850ae5bf0000 closed
[junit] 2016-09-08 04:30:11,852 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-08 04:30:11,852 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x123850ae5bf0000
[junit] 2016-09-08 04:30:11,852 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-08 04:30:11,852 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-08 04:30:11,852 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-08 04:30:11,852 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-08 04:30:11,853 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-08 04:30:11,853 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-08 04:30:11,853 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-08 04:30:11,853 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-08 04:30:11,854 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-08 04:30:11,854 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-08 04:30:11,854 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-08 04:30:11,854 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-08 04:30:11,854 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-08 04:30:11,855 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-08 04:30:11,855 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-08 04:30:11,855 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-08 04:30:11,859 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-08 04:30:11,859 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-08 04:30:11,860 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-08 04:30:11,861 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.036 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1233: Tests failed!
Total time: 78 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
FAILED: org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig
Error Message:
waiting for server 4 being up
Stack Trace:
junit.framework.AssertionFailedError: waiting for server 4 being up
at org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig(ReconfigRecoveryTest.java:217)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.NettyNettySuiteTest.testRemoveAddTwo
Error Message:
Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
Stack Trace:
junit.framework.AssertionFailedError: Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 238 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/238/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 436932 lines...]
[junit] 2016-09-08 02:37:41,910 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-08 02:37:41,910 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-08 02:37:41,911 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-08 02:37:41,912 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-08 02:37:41,912 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-08 02:37:41,912 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-08 02:37:41,912 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test1637389904088749490.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test1637389904088749490.junit.dir/version-2
[junit] 2016-09-08 02:37:41,913 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test1637389904088749490.junit.dir/version-2/snapshot.b
[junit] 2016-09-08 02:37:41,915 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test1637389904088749490.junit.dir/version-2/snapshot.b
[junit] 2016-09-08 02:37:41,917 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-08 02:37:41,917 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-08 02:37:41,917 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:58047
[junit] 2016-09-08 02:37:41,918 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:58047
[junit] 2016-09-08 02:37:41,918 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-08 02:37:41,918 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:58047 (no session established for client)
[junit] 2016-09-08 02:37:41,919 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-08 02:37:41,920 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-08 02:37:41,920 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-08 02:37:41,920 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-08 02:37:41,921 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-08 02:37:41,921 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 18130
[junit] 2016-09-08 02:37:41,921 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-08 02:37:41,921 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-08 02:37:41,921 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-08 02:37:41,992 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x12384a3e73c0000
[junit] 2016-09-08 02:37:41,992 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x12384a3e73c0000 closed
[junit] 2016-09-08 02:37:41,992 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-08 02:37:41,993 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-08 02:37:41,993 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-08 02:37:41,993 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-08 02:37:41,993 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-08 02:37:41,993 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-08 02:37:41,994 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-08 02:37:41,994 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-08 02:37:41,994 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-08 02:37:41,994 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-08 02:37:41,994 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-08 02:37:41,994 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-08 02:37:41,994 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-08 02:37:41,995 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-08 02:37:41,995 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-08 02:37:41,995 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-08 02:37:41,996 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-08 02:37:42,001 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-08 02:37:42,001 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-08 02:37:42,002 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-08 02:37:42,002 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.865 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1233: Tests failed!
Total time: 67 minutes 48 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
2 tests failed.
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 237 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/237/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 498690 lines...]
[junit] 2016-09-07 17:26:52,231 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-07 17:26:52,231 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-07 17:26:52,231 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-07 17:26:52,232 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-07 17:26:52,232 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-07 17:26:52,232 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-07 17:26:52,232 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test8235908172023867882.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test8235908172023867882.junit.dir/version-2
[junit] 2016-09-07 17:26:52,233 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test8235908172023867882.junit.dir/version-2/snapshot.b
[junit] 2016-09-07 17:26:52,234 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test8235908172023867882.junit.dir/version-2/snapshot.b
[junit] 2016-09-07 17:26:52,235 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-07 17:26:52,236 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-07 17:26:52,236 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:55909
[junit] 2016-09-07 17:26:52,236 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:55909
[junit] 2016-09-07 17:26:52,237 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-07 17:26:52,237 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:55909 (no session established for client)
[junit] 2016-09-07 17:26:52,237 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-07 17:26:52,238 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-07 17:26:52,238 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-07 17:26:52,239 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-07 17:26:52,239 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-07 17:26:52,239 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 15826
[junit] 2016-09-07 17:26:52,239 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-07 17:26:52,239 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-07 17:26:52,239 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-07 17:26:52,322 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x12382ab96c00000 closed
[junit] 2016-09-07 17:26:52,322 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x12382ab96c00000
[junit] 2016-09-07 17:26:52,322 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-07 17:26:52,322 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-07 17:26:52,322 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-07 17:26:52,322 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-07 17:26:52,322 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-07 17:26:52,323 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-07 17:26:52,323 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-07 17:26:52,323 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-07 17:26:52,323 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-07 17:26:52,323 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-07 17:26:52,323 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-07 17:26:52,323 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-07 17:26:52,324 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-07 17:26:52,324 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-07 17:26:52,324 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-07 17:26:52,324 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-07 17:26:52,324 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-07 17:26:52,328 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-07 17:26:52,328 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-07 17:26:52,329 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-07 17:26:52,330 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 2.271 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1233: Tests failed!
Total time: 72 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
3 tests failed.
FAILED: org.apache.zookeeper.test.NettyNettySuiteTest.testQuorumSystemChange
Error Message:
Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
Stack Trace:
junit.framework.AssertionFailedError: Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 236 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/236/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 100205 lines...]
[junit] 2016-09-06 16:51:27,253 [myid:1] - INFO [QuorumPeer[myid=1](plain=/127.0.0.1:11300)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id1]
[junit] 2016-09-06 16:51:27,254 [myid:1] - INFO [QuorumPeer[myid=1](plain=/127.0.0.1:11300)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1]
[junit] 2016-09-06 16:51:27,254 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower,name3=InMemoryDataTree]
[junit] 2016-09-06 16:51:27,254 [myid:1] - INFO [QuorumPeer[myid=1](plain=/127.0.0.1:11300)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.0]
[junit] 2016-09-06 16:51:27,254 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-06 16:51:27,254 [myid:1] - INFO [QuorumPeer[myid=1](plain=/127.0.0.1:11300)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.2]
[junit] 2016-09-06 16:51:27,254 [myid:2] - INFO [SyncThread:2:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-06 16:51:27,255 [myid:2] - WARN [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):QuorumPeer@1158] - PeerState set to LOOKING
[junit] 2016-09-06 16:51:27,255 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):QuorumPeer@1033] - LOOKING
[junit] 2016-09-06 16:51:27,351 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x1237d64c79e0000 closed
[junit] 2016-09-06 16:51:27,351 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1237d64c79e0000
[junit] 2016-09-06 16:51:27,351 [myid:] - INFO [main:QuorumBase@394] - Shutting down quorum peer QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled)
[junit] 2016-09-06 16:51:27,352 [myid:2] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-06 16:51:27,354 [myid:2] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-06 16:51:27,354 [myid:2] - INFO [NIOServerCxnFactory.AcceptThread:localhost/127.0.0.1:11303:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-06 16:51:27,355 [myid:2] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-06 16:51:27,357 [myid:2] - INFO [localhost/127.0.0.1:11305:QuorumCnxManager$Listener@659] - Leaving listener
[junit] 2016-09-06 16:51:27,357 [myid:] - INFO [main:QuorumBase@398] - Shutting down leader election QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled)
[junit] 2016-09-06 16:51:27,357 [myid:] - INFO [main:QuorumBase@403] - Waiting for QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled) to exit thread
[junit] 2016-09-06 16:51:27,721 [myid:1] - INFO [WorkerReceiver[myid=1]:FastLeaderElection$Messenger$WorkerReceiver@440] - WorkerReceiver is down
[junit] 2016-09-06 16:51:27,721 [myid:0] - INFO [WorkerReceiver[myid=0]:FastLeaderElection$Messenger$WorkerReceiver@440] - WorkerReceiver is down
[junit] 2016-09-06 16:51:27,721 [myid:2] - INFO [WorkerSender[myid=2]:FastLeaderElection$Messenger$WorkerSender@470] - WorkerSender is down
[junit] 2016-09-06 16:51:27,721 [myid:2] - INFO [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@440] - WorkerReceiver is down
[junit] 2016-09-06 16:51:27,721 [myid:0] - INFO [WorkerSender[myid=0]:FastLeaderElection$Messenger$WorkerSender@470] - WorkerSender is down
[junit] 2016-09-06 16:51:27,721 [myid:1] - INFO [WorkerSender[myid=1]:FastLeaderElection$Messenger$WorkerSender@470] - WorkerSender is down
[junit] 2016-09-06 16:51:28,261 [myid:1] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
[junit] 2016-09-06 16:51:28,315 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test5501137576673997156.junit.dir/data/version-2/snapshot.100000000
[junit] 2016-09-06 16:51:28,382 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):FastLeaderElection@894] - New election. My id = 2, proposed zxid=0x100000011
[junit] 2016-09-06 16:51:28,382 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=LeaderElection]
[junit] 2016-09-06 16:51:28,382 [myid:2] - WARN [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):QuorumPeer@1140] - QuorumPeer main thread exited
[junit] 2016-09-06 16:51:28,382 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2]
[junit] 2016-09-06 16:51:28,382 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2]
[junit] 2016-09-06 16:51:28,382 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.0]
[junit] 2016-09-06 16:51:28,383 [myid:2] - INFO [QuorumPeer[myid=2](plain=/127.0.0.1:11303)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.1]
[junit] 2016-09-06 16:51:28,861 [myid:127.0.0.1:11303] - INFO [main-SendThread(127.0.0.1:11303):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:11303. Will not attempt to authenticate using SASL (unknown error)
[junit] 2016-09-06 16:51:28,861 [myid:127.0.0.1:11303] - ERROR [main-SendThread(127.0.0.1:11303):ClientCnxnSocketNIO@287] - Unable to open socket to 127.0.0.1/127.0.0.1:11303
[junit] 2016-09-06 16:51:28,971 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x2237d64c9360000 closed
[junit] 2016-09-06 16:51:28,971 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x2237d64c9360000
[junit] 2016-09-06 16:51:28,971 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 24441
[junit] 2016-09-06 16:51:28,972 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 4
[junit] 2016-09-06 16:51:28,972 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testNextConfigCompletion
[junit] 2016-09-06 16:51:28,972 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testNextConfigCompletion
[junit] 2016-09-06 16:51:28,972 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testNextConfigCompletion
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 205.218 sec
[junit] Running org.apache.zookeeper.server.quorum.RemotePeerBeanTest
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 2.746 sec
[junit] Running org.apache.zookeeper.server.quorum.StandaloneDisabledTest
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1219: Process fork failed.
Total time: 32 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
2 tests failed.
FAILED: org.apache.zookeeper.server.admin.JettyAdminServerTest.testQuorum
Error Message:
Connection refused
Stack Trace:
java.net.ConnectException: Connection refused
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
at java.net.Socket.connect(Socket.java:579)
at java.net.Socket.connect(Socket.java:528)
at sun.net.NetworkClient.doConnect(NetworkClient.java:180)
at sun.net.www.http.HttpClient.openServer(HttpClient.java:432)
at sun.net.www.http.HttpClient.openServer(HttpClient.java:527)
at sun.net.www.http.HttpClient.<init>(HttpClient.java:211)
at sun.net.www.http.HttpClient.New(HttpClient.java:308)
at sun.net.www.http.HttpClient.New(HttpClient.java:326)
at sun.net.www.protocol.http.HttpURLConnection.getNewHttpClient(HttpURLConnection.java:997)
at sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:933)
at sun.net.www.protocol.http.HttpURLConnection.connect(HttpURLConnection.java:851)
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1301)
at java.net.URL.openStream(URL.java:1037)
at org.apache.zookeeper.server.admin.JettyAdminServerTest.queryAdminServer(JettyAdminServerTest.java:158)
at org.apache.zookeeper.server.admin.JettyAdminServerTest.queryAdminServer(JettyAdminServerTest.java:150)
at org.apache.zookeeper.server.admin.JettyAdminServerTest.testQuorum(JettyAdminServerTest.java:131)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.server.quorum.StandaloneDisabledTest.unknown
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
ZooKeeper_branch35_solaris - Build # 235 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/235/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 426447 lines...]
[junit] 2016-09-05 17:23:47,433 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-09-05 17:23:47,433 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
[junit] 2016-09-05 17:23:47,434 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-05 17:23:47,435 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-05 17:23:47,435 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-05 17:23:47,435 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-05 17:23:47,435 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2188628105015244452.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2188628105015244452.junit.dir/version-2
[junit] 2016-09-05 17:23:47,436 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2188628105015244452.junit.dir/version-2/snapshot.b
[junit] 2016-09-05 17:23:47,438 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test2188628105015244452.junit.dir/version-2/snapshot.b
[junit] 2016-09-05 17:23:47,439 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-05 17:23:47,440 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-05 17:23:47,440 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:40993
[junit] 2016-09-05 17:23:47,441 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:40993
[junit] 2016-09-05 17:23:47,441 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-05 17:23:47,441 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:40993 (no session established for client)
[junit] 2016-09-05 17:23:47,448 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-05 17:23:47,449 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-05 17:23:47,449 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-05 17:23:47,449 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-05 17:23:47,450 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-05 17:23:47,450 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17877
[junit] 2016-09-05 17:23:47,450 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-05 17:23:47,450 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-05 17:23:47,450 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-05 17:23:47,522 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x123785c18b90000 closed
[junit] 2016-09-05 17:23:47,522 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x123785c18b90000
[junit] 2016-09-05 17:23:47,522 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-05 17:23:47,522 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-05 17:23:47,522 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-05 17:23:47,522 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-05 17:23:47,522 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-05 17:23:47,523 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-05 17:23:47,523 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-05 17:23:47,523 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-05 17:23:47,524 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-05 17:23:47,524 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-05 17:23:47,524 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-05 17:23:47,524 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-05 17:23:47,524 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-05 17:23:47,524 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-05 17:23:47,525 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-05 17:23:47,525 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-05 17:23:47,525 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-05 17:23:47,530 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-05 17:23:47,530 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-05 17:23:47,531 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-05 17:23:47,531 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.84 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1233: Tests failed!
Total time: 65 minutes 47 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
FAILED: org.apache.zookeeper.server.ZxidRolloverTest.testSimpleRolloverFollower
Error Message:
Failed to connect to ZooKeeper server.
Stack Trace:
java.util.concurrent.TimeoutException: Failed to connect to ZooKeeper server.
at org.apache.zookeeper.test.ClientBase$CountdownWatcher.waitForConnected(ClientBase.java:132)
at org.apache.zookeeper.server.ZxidRolloverTest.waitForClientsConnected(ZxidRolloverTest.java:80)
at org.apache.zookeeper.server.ZxidRolloverTest.createNodes(ZxidRolloverTest.java:237)
at org.apache.zookeeper.server.ZxidRolloverTest.testSimpleRolloverFollower(ZxidRolloverTest.java:263)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.LETest.testLE
Error Message:
Threads didn't join
Stack Trace:
junit.framework.AssertionFailedError: Threads didn't join
at org.apache.zookeeper.test.LETest.testLE(LETest.java:123)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 234 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/234/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 427971 lines...]
[junit] 2016-09-04 17:26:07,173 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-09-04 17:26:07,174 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-09-04 17:26:07,174 [myid:] - INFO [main:ZooKeeperServer@889] - minSessionTimeout set to 6000
[junit] 2016-09-04 17:26:07,174 [myid:] - INFO [main:ZooKeeperServer@898] - maxSessionTimeout set to 60000
[junit] 2016-09-04 17:26:07,174 [myid:] - INFO [main:ZooKeeperServer@159] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test8830585549206278338.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test8830585549206278338.junit.dir/version-2
[junit] 2016-09-04 17:26:07,175 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test8830585549206278338.junit.dir/version-2/snapshot.b
[junit] 2016-09-04 17:26:07,177 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build/test/tmp/test8830585549206278338.junit.dir/version-2/snapshot.b
[junit] 2016-09-04 17:26:07,179 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-04 17:26:07,179 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-04 17:26:07,179 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:64665
[junit] 2016-09-04 17:26:07,180 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:64665
[junit] 2016-09-04 17:26:07,180 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-09-04 17:26:07,180 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:64665 (no session established for client)
[junit] 2016-09-04 17:26:07,180 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-04 17:26:07,182 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-09-04 17:26:07,182 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-09-04 17:26:07,182 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-09-04 17:26:07,182 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-09-04 17:26:07,183 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 18130
[junit] 2016-09-04 17:26:07,183 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-09-04 17:26:07,183 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-09-04 17:26:07,183 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-09-04 17:26:07,261 [myid:] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
[junit] 2016-09-04 17:26:07,261 [myid:] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
[junit] 2016-09-04 17:26:07,262 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x1237337ded90000 closed
[junit] 2016-09-04 17:26:07,262 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1237337ded90000
[junit] 2016-09-04 17:26:07,262 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-09-04 17:26:07,262 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-09-04 17:26:07,262 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-04 17:26:07,262 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-09-04 17:26:07,262 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-09-04 17:26:07,263 [myid:] - INFO [main:ZooKeeperServer@529] - shutting down
[junit] 2016-09-04 17:26:07,263 [myid:] - ERROR [main:ZooKeeperServer@501] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-04 17:26:07,263 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-09-04 17:26:07,263 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down
[junit] 2016-09-04 17:26:07,264 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-09-04 17:26:07,264 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-09-04 17:26:07,264 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-09-04 17:26:07,264 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-09-04 17:26:07,264 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-09-04 17:26:07,265 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-09-04 17:26:07,265 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-09-04 17:26:07,265 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-04 17:26:07,270 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-09-04 17:26:07,270 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-09-04 17:26:07,271 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-04 17:26:07,271 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.048 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/branch-3.5/build.xml:1233: Tests failed!
Total time: 68 minutes 1 second
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
FAILED: org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testEarlyLeaderAbandonment
Error Message:
null
Stack Trace:
junit.framework.AssertionFailedError
at org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testEarlyLeaderAbandonment(QuorumPeerMainTest.java:200)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.LETest.testLE
Error Message:
Threads didn't join
Stack Trace:
junit.framework.AssertionFailedError: Threads didn't join
at org.apache.zookeeper.test.LETest.testLE(LETest.java:123)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:662)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:173)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:732)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:658)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)