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/12/16 16:48:08 UTC
ZooKeeper_branch35_solaris - Build # 354 - Still Failing
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/354/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 470964 lines...]
[junit] 2016-12-16 16:49:51,155 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-12-16 16:49:51,156 [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-12-16 16:49:51,156 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-12-16 16:49:51,157 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-12-16 16:49:51,157 [myid:] - INFO [main:ZooKeeperServer@893] - minSessionTimeout set to 6000
[junit] 2016-12-16 16:49:51,157 [myid:] - INFO [main:ZooKeeperServer@902] - maxSessionTimeout set to 60000
[junit] 2016-12-16 16:49:51,157 [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/test6476041602000078439.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test6476041602000078439.junit.dir/version-2
[junit] 2016-12-16 16:49:51,158 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test6476041602000078439.junit.dir/version-2/snapshot.b
[junit] 2016-12-16 16:49:51,160 [myid:] - INFO [main:FileTxnSnapLog@306] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test6476041602000078439.junit.dir/version-2/snapshot.b
[junit] 2016-12-16 16:49:51,161 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-16 16:49:51,161 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-16 16:49:51,162 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:61581
[junit] 2016-12-16 16:49:51,162 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:61581
[junit] 2016-12-16 16:49:51,163 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-12-16 16:49:51,163 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:61581 (no session established for client)
[junit] 2016-12-16 16:49:51,163 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-12-16 16:49:51,165 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-12-16 16:49:51,165 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-12-16 16:49:51,165 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-12-16 16:49:51,165 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-12-16 16:49:51,165 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17748
[junit] 2016-12-16 16:49:51,165 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-12-16 16:49:51,166 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-12-16 16:49:51,166 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-12-16 16:49:51,243 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x125858410bc0000
[junit] 2016-12-16 16:49:51,243 [myid:] - INFO [main:ZooKeeper@1311] - Session: 0x125858410bc0000 closed
[junit] 2016-12-16 16:49:51,245 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-12-16 16:49:51,245 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-12-16 16:49:51,245 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-16 16:49:51,245 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-12-16 16:49:51,245 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-16 16:49:51,246 [myid:] - INFO [main:ZooKeeperServer@533] - shutting down
[junit] 2016-12-16 16:49:51,246 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-16 16:49:51,246 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-12-16 16:49:51,246 [myid:] - INFO [main:PrepRequestProcessor@974] - Shutting down
[junit] 2016-12-16 16:49:51,246 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-12-16 16:49:51,246 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-12-16 16:49:51,247 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-12-16 16:49:51,247 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-12-16 16:49:51,247 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-12-16 16:49:51,248 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-12-16 16:49:51,248 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-16 16:49:51,248 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-12-16 16:49:51,260 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-12-16 16:49:51,260 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-12-16 16:49:51,261 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-12-16 16:49:51,261 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.918 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1234: Tests failed!
Total time: 74 minutes 43 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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:719)
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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:715)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 361 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/361/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 135689 lines...]
[junit] 2016-12-21 17:00:22,477 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@77] - RUNNING TEST METHOD testMutipleWatcherObjs
[junit] 2016-12-21 17:00:22,478 [myid:] - INFO [NIOWorkerThread-7:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:57308 which had sessionid 0x1259f4d6fe50000
[junit] 2016-12-21 17:00:22,478 [myid:] - INFO [main:ZooKeeper@853] - Initiating client connection, connectString=127.0.0.1:11235/chrootclienttest sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@17b043b
[junit] 2016-12-21 17:00:22,478 [myid:127.0.0.1:11235] - INFO [main-SendThread(127.0.0.1:11235):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:11235. Will not attempt to authenticate using SASL (unknown error)
[junit] 2016-12-21 17:00:22,479 [myid:127.0.0.1:11235] - INFO [main-SendThread(127.0.0.1:11235):ClientCnxn$SendThread@948] - Socket connection established, initiating session, client: null, server: null
[junit] 2016-12-21 17:00:22,479 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11235:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:57309
[junit] 2016-12-21 17:00:22,479 [myid:] - INFO [NIOWorkerThread-8:ZooKeeperServer@999] - Client attempting to establish new session at /127.0.0.1:57309
[junit] 2016-12-21 17:00:22,481 [myid:] - INFO [SyncThread:0:ZooKeeperServer@713] - Established session 0x1259f4d6fe50001 with negotiated timeout 30000 for client /127.0.0.1:57309
[junit] 2016-12-21 17:00:22,481 [myid:127.0.0.1:11235] - INFO [main-SendThread(127.0.0.1:11235):ClientCnxn$SendThread@1381] - Session establishment complete on server null, sessionid = 0x1259f4d6fe50001, negotiated timeout = 30000
[junit] 2016-12-21 17:00:22,482 [myid:] - INFO [main:JMXEnv@117] - expect:0x1259f4d6fe50001
[junit] 2016-12-21 17:00:22,482 [myid:] - INFO [main:JMXEnv@120] - found:0x1259f4d6fe50001 org.apache.ZooKeeperService:name0=StandaloneServer_port11235,name1=Connections,name2=127.0.0.1,name3=0x1259f4d6fe50001
[junit] 2016-12-21 17:00:23,984 [myid:] - INFO [ProcessThread(sid:0 cport:11235)::PrepRequestProcessor@656] - Processed session termination for sessionid: 0x1259f4d6fe50001
[junit] 2016-12-21 17:00:23,985 [myid:] - INFO [main:ZooKeeper@1311] - Session: 0x1259f4d6fe50001 closed
[junit] 2016-12-21 17:00:23,985 [myid:] - INFO [NIOWorkerThread-4:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11235,name1=Connections,name2=127.0.0.1,name3=0x1259f4d6fe50001]
[junit] 2016-12-21 17:00:23,985 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1259f4d6fe50001
[junit] 2016-12-21 17:00:23,985 [myid:] - INFO [NIOWorkerThread-4:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:57309 which had sessionid 0x1259f4d6fe50001
[junit] 2016-12-21 17:00:23,985 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 18158
[junit] 2016-12-21 17:00:23,986 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 40
[junit] 2016-12-21 17:00:23,986 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testMutipleWatcherObjs
[junit] 2016-12-21 17:00:23,986 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-12-21 17:00:23,986 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-12-21 17:00:23,986 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-12-21 17:00:23,987 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-21 17:00:23,987 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11235:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-12-21 17:00:23,988 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-21 17:00:23,988 [myid:] - INFO [main:ZooKeeperServer@533] - shutting down
[junit] 2016-12-21 17:00:23,988 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-21 17:00:23,988 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-12-21 17:00:23,989 [myid:] - INFO [main:PrepRequestProcessor@974] - Shutting down
[junit] 2016-12-21 17:00:23,989 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-12-21 17:00:23,989 [myid:] - INFO [ProcessThread(sid:0 cport:11235)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-12-21 17:00:23,989 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-12-21 17:00:23,989 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-12-21 17:00:23,989 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11235,name1=InMemoryDataTree]
[junit] 2016-12-21 17:00:23,990 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11235]
[junit] 2016-12-21 17:00:23,990 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11235
[junit] 2016-12-21 17:00:23,990 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-12-21 17:00:23,994 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 76
[junit] 2016-12-21 17:00:23,994 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testMutipleWatcherObjs
[junit] 2016-12-21 17:00:23,994 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testMutipleWatcherObjs
[junit] 2016-12-21 17:00:24,261 [myid:] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
[junit] 2016-12-21 17:00:24,261 [myid:] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
[junit] 2016-12-21 17:00:24,261 [myid:] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
[junit] 2016-12-21 17:00:24,261 [myid:] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
[junit] 2016-12-21 17:00:24,261 [myid:] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
[junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 56.594 sec
[junit] Running org.apache.zookeeper.test.ChrootTest
[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:1220: Process fork failed.
Total time: 49 minutes 43 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.ChrootTest.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 # 360 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/360/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 468459 lines...]
[junit] 2016-12-20 17:52:19,216 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-12-20 17:52:19,216 [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-12-20 17:52:19,216 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-12-20 17:52:19,217 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-12-20 17:52:19,217 [myid:] - INFO [main:ZooKeeperServer@893] - minSessionTimeout set to 6000
[junit] 2016-12-20 17:52:19,217 [myid:] - INFO [main:ZooKeeperServer@902] - maxSessionTimeout set to 60000
[junit] 2016-12-20 17:52:19,218 [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/test8814593274694219845.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test8814593274694219845.junit.dir/version-2
[junit] 2016-12-20 17:52:19,218 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test8814593274694219845.junit.dir/version-2/snapshot.b
[junit] 2016-12-20 17:52:19,220 [myid:] - INFO [main:FileTxnSnapLog@306] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test8814593274694219845.junit.dir/version-2/snapshot.b
[junit] 2016-12-20 17:52:19,222 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-20 17:52:19,222 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-20 17:52:19,222 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:61996
[junit] 2016-12-20 17:52:19,223 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:61996
[junit] 2016-12-20 17:52:19,223 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-12-20 17:52:19,223 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:61996 (no session established for client)
[junit] 2016-12-20 17:52:19,224 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-12-20 17:52:19,225 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-12-20 17:52:19,225 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-12-20 17:52:19,225 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-12-20 17:52:19,226 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-12-20 17:52:19,226 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17747
[junit] 2016-12-20 17:52:19,226 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-12-20 17:52:19,226 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-12-20 17:52:19,226 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-12-20 17:52:19,303 [myid:] - INFO [main:ZooKeeper@1311] - Session: 0x1259a56a0b20000 closed
[junit] 2016-12-20 17:52:19,303 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-12-20 17:52:19,303 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-12-20 17:52:19,304 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-20 17:52:19,304 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-20 17:52:19,303 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-12-20 17:52:19,304 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1259a56a0b20000
[junit] 2016-12-20 17:52:19,304 [myid:] - INFO [main:ZooKeeperServer@533] - shutting down
[junit] 2016-12-20 17:52:19,305 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-20 17:52:19,305 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-12-20 17:52:19,305 [myid:] - INFO [main:PrepRequestProcessor@974] - Shutting down
[junit] 2016-12-20 17:52:19,305 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-12-20 17:52:19,305 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-12-20 17:52:19,305 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-12-20 17:52:19,306 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-12-20 17:52:19,306 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-12-20 17:52:19,306 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-12-20 17:52:19,307 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-20 17:52:19,307 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-12-20 17:52:19,312 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-12-20 17:52:19,312 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-12-20 17:52:19,314 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-12-20 17:52:19,314 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.344 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1234: Tests failed!
Total time: 67 minutes 29 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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:719)
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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:715)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 359 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/359/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 457031 lines...]
[junit] 2016-12-20 15:16:02,360 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-12-20 15:16:02,361 [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-12-20 15:16:02,361 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-12-20 15:16:02,362 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-12-20 15:16:02,362 [myid:] - INFO [main:ZooKeeperServer@893] - minSessionTimeout set to 6000
[junit] 2016-12-20 15:16:02,362 [myid:] - INFO [main:ZooKeeperServer@902] - maxSessionTimeout set to 60000
[junit] 2016-12-20 15:16:02,362 [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/test7012898382742462567.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test7012898382742462567.junit.dir/version-2
[junit] 2016-12-20 15:16:02,363 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test7012898382742462567.junit.dir/version-2/snapshot.b
[junit] 2016-12-20 15:16:02,365 [myid:] - INFO [main:FileTxnSnapLog@306] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test7012898382742462567.junit.dir/version-2/snapshot.b
[junit] 2016-12-20 15:16:02,366 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-20 15:16:02,366 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-20 15:16:02,367 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:57046
[junit] 2016-12-20 15:16:02,367 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:57046
[junit] 2016-12-20 15:16:02,368 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-12-20 15:16:02,368 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:57046 (no session established for client)
[junit] 2016-12-20 15:16:02,368 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-12-20 15:16:02,369 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-12-20 15:16:02,370 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-12-20 15:16:02,370 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-12-20 15:16:02,370 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-12-20 15:16:02,370 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17745
[junit] 2016-12-20 15:16:02,370 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-12-20 15:16:02,370 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-12-20 15:16:02,371 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-12-20 15:16:02,457 [myid:] - INFO [main:ZooKeeper@1311] - Session: 0x12599c78d390000 closed
[junit] 2016-12-20 15:16:02,457 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-12-20 15:16:02,457 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x12599c78d390000
[junit] 2016-12-20 15:16:02,457 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-12-20 15:16:02,457 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-20 15:16:02,457 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-12-20 15:16:02,457 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-20 15:16:02,458 [myid:] - INFO [main:ZooKeeperServer@533] - shutting down
[junit] 2016-12-20 15:16:02,458 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-20 15:16:02,458 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-12-20 15:16:02,458 [myid:] - INFO [main:PrepRequestProcessor@974] - Shutting down
[junit] 2016-12-20 15:16:02,458 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-12-20 15:16:02,459 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-12-20 15:16:02,459 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-12-20 15:16:02,459 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-12-20 15:16:02,459 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-12-20 15:16:02,459 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-12-20 15:16:02,460 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-20 15:16:02,460 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-12-20 15:16:02,464 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-12-20 15:16:02,464 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-12-20 15:16:02,465 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-12-20 15:16:02,466 [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/build.xml:1234: Tests failed!
Total time: 66 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) ##############################
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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:719)
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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:715)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 358 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/358/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 169862 lines...]
[junit] 2016-12-19 17:10:03,296 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11237)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4]
[junit] 2016-12-19 17:10:03,296 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11237)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4]
[junit] 2016-12-19 17:10:03,296 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11237)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.1]
[junit] 2016-12-19 17:10:03,296 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11237)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.2]
[junit] 2016-12-19 17:10:03,297 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11237)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.3]
[junit] 2016-12-19 17:10:03,297 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:11237)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.5]
[junit] 2016-12-19 17:10:03,297 [myid:] - INFO [main:HierarchicalQuorumTest@285] - Shutting down server 5
[junit] 2016-12-19 17:10:03,297 [myid:] - INFO [main:QuorumBase@394] - Shutting down quorum peer QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled)
[junit] 2016-12-19 17:10:03,298 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-12-19 17:10:03,298 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-19 17:10:03,298 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-19 17:10:03,299 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:/127.0.0.1:11238:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-12-19 17:10:03,323 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=LeaderElection]
[junit] 2016-12-19 17:10:03,323 [myid:] - INFO [/127.0.0.1:11233:QuorumCnxManager$Listener@661] - Leaving listener
[junit] 2016-12-19 17:10:03,323 [myid:] - WARN [QuorumPeer[myid=5](plain=/127.0.0.1:11238)(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-12-19 17:10:03,324 [myid:] - INFO [main:QuorumBase@398] - Shutting down leader election QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled)
[junit] 2016-12-19 17:10:03,324 [myid:] - WARN [QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled):QuorumPeer@1140] - QuorumPeer main thread exited
[junit] 2016-12-19 17:10:03,324 [myid:] - INFO [main:QuorumBase@403] - Waiting for QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled) to exit thread
[junit] 2016-12-19 17:10:03,324 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5]
[junit] 2016-12-19 17:10:03,324 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5]
[junit] 2016-12-19 17:10:03,325 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1]
[junit] 2016-12-19 17:10:03,325 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2]
[junit] 2016-12-19 17:10:03,325 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3]
[junit] 2016-12-19 17:10:03,325 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:11238)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.4]
[junit] 2016-12-19 17:10:03,497 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11234
[junit] 2016-12-19 17:10:03,497 [myid:] - INFO [main:HierarchicalQuorumTest@292] - 127.0.0.1:11234 is no longer accepting client connections
[junit] 2016-12-19 17:10:03,497 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11235
[junit] 2016-12-19 17:10:03,497 [myid:] - INFO [main:HierarchicalQuorumTest@292] - 127.0.0.1:11235 is no longer accepting client connections
[junit] 2016-12-19 17:10:03,497 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11236
[junit] 2016-12-19 17:10:03,498 [myid:] - INFO [main:HierarchicalQuorumTest@292] - 127.0.0.1:11236 is no longer accepting client connections
[junit] 2016-12-19 17:10:03,498 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11237
[junit] 2016-12-19 17:10:03,498 [myid:] - INFO [main:HierarchicalQuorumTest@292] - 127.0.0.1:11237 is no longer accepting client connections
[junit] 2016-12-19 17:10:03,498 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11238
[junit] 2016-12-19 17:10:03,498 [myid:] - INFO [main:HierarchicalQuorumTest@292] - 127.0.0.1:11238 is no longer accepting client connections
[junit] 2016-12-19 17:10:03,644 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testHierarchicalQuorum
[junit] 2016-12-19 17:10:03,644 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testHierarchicalQuorum
[junit] 2016-12-19 17:10:04,381 [myid:] - INFO [WorkerReceiver[myid=3]:FastLeaderElection$Messenger$WorkerReceiver@440] - WorkerReceiver is down
[junit] 2016-12-19 17:10:04,381 [myid:] - INFO [WorkerReceiver[myid=4]:FastLeaderElection$Messenger$WorkerReceiver@440] - WorkerReceiver is down
[junit] 2016-12-19 17:10:04,391 [myid:] - INFO [WorkerSender[myid=3]:FastLeaderElection$Messenger$WorkerSender@470] - WorkerSender is down
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 108.059 sec
[junit] Running org.apache.zookeeper.test.InvalidSnapshotTest
[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:1220: Process fork failed.
Total time: 61 minutes 30 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.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.FourLetterWordsTest.testFourLetterWords
Error Message:
test timed out after 30000 milliseconds
Stack Trace:
at java.io.UnixFileSystem.getBooleanAttributes0(Native Method)
at java.io.UnixFileSystem.getBooleanAttributes(UnixFileSystem.java:242)
at java.io.File.exists(File.java:813)
at sun.misc.URLClassPath$FileLoader.getResource(URLClassPath.java:1080)
at sun.misc.URLClassPath.getResource(URLClassPath.java:199)
at java.net.URLClassLoader$1.run(URLClassLoader.java:358)
at java.net.URLClassLoader$1.run(URLClassLoader.java:355)
at java.security.AccessController.doPrivileged(Native Method)
at java.net.URLClassLoader.findClass(URLClassLoader.java:354)
at java.lang.ClassLoader.loadClass(ClassLoader.java:425)
at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:308)
at java.lang.ClassLoader.loadClass(ClassLoader.java:358)
at org.apache.zookeeper.server.persistence.FileSnap.findNValidSnapshots(FileSnap.java:155)
at org.apache.zookeeper.server.persistence.FileSnap.deserialize(FileSnap.java:72)
at org.apache.zookeeper.server.persistence.FileTxnSnapLog.restore(FileTxnSnapLog.java:168)
at org.apache.zookeeper.server.ZKDatabase.loadDataBase(ZKDatabase.java:225)
at org.apache.zookeeper.server.ZooKeeperServer.loadData(ZooKeeperServer.java:283)
at org.apache.zookeeper.server.ZooKeeperServer.startdata(ZooKeeperServer.java:443)
at org.apache.zookeeper.server.NIOServerCnxnFactory.startup(NIOServerCnxnFactory.java:764)
at org.apache.zookeeper.server.ServerCnxnFactory.startup(ServerCnxnFactory.java:98)
at org.apache.zookeeper.test.ClientBase.startServerInstance(ClientBase.java:363)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:469)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.zookeeper.test.FourLetterWordsTest.testSetTraceMask
Error Message:
Unexpected bean exists! expected:<0> but was:<2>
Stack Trace:
junit.framework.AssertionFailedError: Unexpected bean exists! expected:<0> but was:<2>
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 java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.zookeeper.test.FourLetterWordsTest.testValidateConsOutput
Error Message:
null
Stack Trace:
java.lang.NullPointerException
at org.apache.zookeeper.test.JMXEnv.conn(JMXEnv.java:83)
at org.apache.zookeeper.test.JMXEnv.ensureParent(JMXEnv.java:238)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:471)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.zookeeper.test.InvalidSnapshotTest.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 # 357 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/357/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 467017 lines...]
[junit] 2016-12-18 17:14:42,759 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-12-18 17:14:42,759 [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-12-18 17:14:42,760 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-12-18 17:14:42,761 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-12-18 17:14:42,761 [myid:] - INFO [main:ZooKeeperServer@893] - minSessionTimeout set to 6000
[junit] 2016-12-18 17:14:42,761 [myid:] - INFO [main:ZooKeeperServer@902] - maxSessionTimeout set to 60000
[junit] 2016-12-18 17:14:42,761 [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/test7646123721090205247.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test7646123721090205247.junit.dir/version-2
[junit] 2016-12-18 17:14:42,762 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test7646123721090205247.junit.dir/version-2/snapshot.b
[junit] 2016-12-18 17:14:42,764 [myid:] - INFO [main:FileTxnSnapLog@306] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test7646123721090205247.junit.dir/version-2/snapshot.b
[junit] 2016-12-18 17:14:42,765 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-18 17:14:42,766 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-18 17:14:42,766 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:40876
[junit] 2016-12-18 17:14:42,767 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:40876
[junit] 2016-12-18 17:14:42,767 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-12-18 17:14:42,767 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:40876 (no session established for client)
[junit] 2016-12-18 17:14:42,767 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-12-18 17:14:42,769 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-12-18 17:14:42,769 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-12-18 17:14:42,769 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-12-18 17:14:42,769 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-12-18 17:14:42,769 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 18069
[junit] 2016-12-18 17:14:42,770 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-12-18 17:14:42,770 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-12-18 17:14:42,770 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-12-18 17:14:42,842 [myid:] - INFO [main:ZooKeeper@1311] - Session: 0x1258fe77ea00000 closed
[junit] 2016-12-18 17:14:42,842 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-12-18 17:14:42,842 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1258fe77ea00000
[junit] 2016-12-18 17:14:42,842 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-12-18 17:14:42,842 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-18 17:14:42,842 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-18 17:14:42,842 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-12-18 17:14:42,843 [myid:] - INFO [main:ZooKeeperServer@533] - shutting down
[junit] 2016-12-18 17:14:42,843 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-18 17:14:42,843 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-12-18 17:14:42,843 [myid:] - INFO [main:PrepRequestProcessor@974] - Shutting down
[junit] 2016-12-18 17:14:42,844 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-12-18 17:14:42,844 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-12-18 17:14:42,844 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-12-18 17:14:42,844 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-12-18 17:14:42,844 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-12-18 17:14:42,845 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-12-18 17:14:42,845 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-18 17:14:42,845 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-12-18 17:14:42,850 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-12-18 17:14:42,850 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-12-18 17:14:42,851 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-12-18 17:14:42,851 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 2.055 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1234: Tests failed!
Total time: 64 minutes 16 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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:719)
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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:715)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_solaris - Build # 356 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/356/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 107675 lines...]
[junit] 2016-12-17 16:28:24,429 [myid:0] - INFO [Thread-0: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-12-17 16:28:24,431 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-17 16:28:24,431 [myid:] - INFO [main:ClientBase@248] - server 127.0.0.1:11222 not up
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit] at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
[junit] at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
[junit] at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
[junit] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit] at java.net.Socket.connect(Socket.java:579)
[junit] at org.apache.zookeeper.client.FourLetterWordMain.send4LetterWord(FourLetterWordMain.java:99)
[junit] at org.apache.zookeeper.client.FourLetterWordMain.send4LetterWord(FourLetterWordMain.java:69)
[junit] at org.apache.zookeeper.test.ClientBase.waitForServerUp(ClientBase.java:241)
[junit] at org.apache.zookeeper.test.ClientBase.waitForServerUp(ClientBase.java:232)
[junit] at org.apache.zookeeper.server.quorum.StandaloneDisabledTest.startServer(StandaloneDisabledTest.java:172)
[junit] at org.apache.zookeeper.server.quorum.StandaloneDisabledTest.startSingleServerTest(StandaloneDisabledTest.java:60)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit] at java.lang.reflect.Method.invoke(Method.java:606)
[junit] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[junit] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[junit] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[junit] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[junit] at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
[junit] at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:53)
[junit] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
[junit] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
[junit] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
[junit] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
[junit] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
[junit] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
[junit] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
[junit] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
[junit] at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:38)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:518)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:1052)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:906)
[junit] 2016-12-17 16:28:24,643 [myid:0] - INFO [Thread-0:NIOServerCnxnFactory@686] - binding to port localhost/127.0.0.1:11222
[junit] 2016-12-17 16:28:24,691 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
Agent went offline during the build
ERROR: Connection was broken: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:73)
Caused by: java.io.EOFException
at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2353)
at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:2822)
at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:804)
at java.io.ObjectInputStream.<init>(ObjectInputStream.java:301)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:48)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:34)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:59)
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
ERROR: Step ?Publish JUnit test result report? failed: no workspace for ZooKeeper_branch35_solaris #356
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
No tests ran.
ZooKeeper_branch35_solaris - Build # 355 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_solaris/355/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 479965 lines...]
[junit] 2016-12-16 18:09:59,409 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222
[junit] 2016-12-16 18:09:59,409 [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-12-16 18:09:59,410 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222
[junit] 2016-12-16 18:09:59,410 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222
[junit] 2016-12-16 18:09:59,411 [myid:] - INFO [main:ZooKeeperServer@893] - minSessionTimeout set to 6000
[junit] 2016-12-16 18:09:59,411 [myid:] - INFO [main:ZooKeeperServer@902] - maxSessionTimeout set to 60000
[junit] 2016-12-16 18:09:59,411 [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/test8452092576738581669.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test8452092576738581669.junit.dir/version-2
[junit] 2016-12-16 18:09:59,412 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test8452092576738581669.junit.dir/version-2/snapshot.b
[junit] 2016-12-16 18:09:59,414 [myid:] - INFO [main:FileTxnSnapLog@306] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build/test/tmp/test8452092576738581669.junit.dir/version-2/snapshot.b
[junit] 2016-12-16 18:09:59,415 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-16 18:09:59,415 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-16 18:09:59,416 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:49273
[junit] 2016-12-16 18:09:59,416 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:49273
[junit] 2016-12-16 18:09:59,416 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output
[junit] 2016-12-16 18:09:59,417 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:49273 (no session established for client)
[junit] 2016-12-16 18:09:59,417 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-12-16 18:09:59,418 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree
[junit] 2016-12-16 18:09:59,418 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree
[junit] 2016-12-16 18:09:59,419 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port
[junit] 2016-12-16 18:09:59,419 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222
[junit] 2016-12-16 18:09:59,419 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17744
[junit] 2016-12-16 18:09:59,419 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24
[junit] 2016-12-16 18:09:59,419 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota
[junit] 2016-12-16 18:09:59,419 [myid:] - INFO [main:ClientBase@543] - tearDown starting
[junit] 2016-12-16 18:09:59,492 [myid:] - INFO [main:ZooKeeper@1311] - Session: 0x12585cd6ee10000 closed
[junit] 2016-12-16 18:09:59,492 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x12585cd6ee10000
[junit] 2016-12-16 18:09:59,492 [myid:] - INFO [main:ClientBase@513] - STOPPING server
[junit] 2016-12-16 18:09:59,492 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2016-12-16 18:09:59,493 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-16 18:09:59,492 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2016-12-16 18:09:59,492 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2016-12-16 18:09:59,493 [myid:] - INFO [main:ZooKeeperServer@533] - shutting down
[junit] 2016-12-16 18:09:59,493 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-12-16 18:09:59,493 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-12-16 18:09:59,494 [myid:] - INFO [main:PrepRequestProcessor@974] - Shutting down
[junit] 2016-12-16 18:09:59,494 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-12-16 18:09:59,494 [myid:] - INFO [ProcessThread(sid:0 cport:11222)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop!
[junit] 2016-12-16 18:09:59,494 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-12-16 18:09:59,494 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete
[junit] 2016-12-16 18:09:59,494 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree]
[junit] 2016-12-16 18:09:59,495 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port11222]
[junit] 2016-12-16 18:09:59,495 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222
[junit] 2016-12-16 18:09:59,495 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-12-16 18:09:59,499 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 60 at start it was 43
[junit] 2016-12-16 18:09:59,500 [myid:] - INFO [main:ClientBase@570] - sleeping for 20 secs
[junit] 2016-12-16 18:09:59,501 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-12-16 18:09:59,501 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.775 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch35_solaris/build.xml:1234: Tests failed!
Total time: 79 minutes 7 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.testRolloverThenRestart
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.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.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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortLeader(ReconfigTest.java:719)
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:183)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPort(ReconfigTest.java:796)
at org.apache.zookeeper.test.ReconfigTest.testPortChangeToBlockedPortFollower(ReconfigTest.java:715)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)