[
https://issues.apache.org/jira/browse/ZOOKEEPER-483?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12734907#action_12734907
]
Patrick Hunt commented on ZOOKEEPER-483:
----------------------------------------
This is interesting, from hbase-hadoop-zookeeper-sv2borg153.log
2009-07-23 12:29:01,661 INFO org.apache.zookeeper.server.quorum.Follower:
shutdown called
java.lang.Exception: shutdown Follower
at
org.apache.zookeeper.server.quorum.Follower.shutdown(Follower.java:427)
at
org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:498)
I don't see any further quorum messages until ~11 seconds later:
2009-07-23 12:29:12,516 FATAL org.apache.zookeeper.server.SyncRequestProcessor:
Severe unrecoverable error, exiting
java.net.SocketException: Socket closed
at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:99)
at java.net.SocketOutputStream.write(SocketOutputStream.java:136)
at
java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:65)
at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:123)
at
org.apache.zookeeper.server.quorum.Follower.writePacket(Follower.java:100)
at
org.apache.zookeeper.server.quorum.SendAckRequestProcessor.flush(SendAckRequestProcessor.java:52)
at
org.apache.zookeeper.server.SyncRequestProcessor.flush(SyncRequestProcessor.java:131)
at
org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:76)
2009-07-23 12:29:12,559 WARN org.apache.zookeeper.server.NIOServerCnxn:
Exception causing close of session 0x0 due to java.io.IOException:
ZooKeeperServer not running
SRP should be running/active if the QP is not following the leader?
> ZK fataled on me, and ugly
> --------------------------
>
> Key: ZOOKEEPER-483
> URL: https://issues.apache.org/jira/browse/ZOOKEEPER-483
> Project: Zookeeper
> Issue Type: Bug
> Affects Versions: 3.1.1
> Reporter: ryan rawson
> Fix For: 3.2.1
>
> Attachments: zklogs.tar.gz
>
>
> here are the part of the log whereby my zookeeper instance crashed, taking 3
> out of 5 down, and thus ruining the quorum for all clients:
> 2009-07-23 12:29:06,769 WARN org.apache.zookeeper.server.NIOServerCnxn:
> Exception causing close of session 0x52276d1d5161350 due to
> java.io.IOException: Read error
> 2009-07-23 12:29:00,756 WARN org.apache.zookeeper.server.quorum.Follower:
> Exception when following the leader
> java.io.EOFException
> at java.io.DataInputStream.readInt(DataInputStream.java:375)
> at
> org.apache.jute.BinaryInputArchive.readInt(BinaryInputArchive.java:63)
> at
> org.apache.zookeeper.server.quorum.QuorumPacket.deserialize(QuorumPacket.java:65)
> at
> org.apache.jute.BinaryInputArchive.readRecord(BinaryInputArchive.java:108)
> at
> org.apache.zookeeper.server.quorum.Follower.readPacket(Follower.java:114)
> at
> org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:243)
> at
> org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:494)
> 2009-07-23 12:29:06,770 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d5161350 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.168:39489]
> 2009-07-23 12:29:06,770 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x12276d15dfb0578 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.159:46797]
> 2009-07-23 12:29:06,771 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x42276d1d3fa013e NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.153:33998]
> 2009-07-23 12:29:06,771 WARN org.apache.zookeeper.server.NIOServerCnxn:
> Exception causing close of session 0x52276d1d5160593 due to
> java.io.IOException: Read error
> 2009-07-23 12:29:06,808 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e02bb NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.158:53758]
> 2009-07-23 12:29:06,809 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x42276d1d3fa13e4 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.154:58681]
> 2009-07-23 12:29:06,809 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e691382 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.162:59967]
> 2009-07-23 12:29:06,809 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x12276d15dfb1354 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.163:49957]
> 2009-07-23 12:29:06,809 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x42276d1d3fa13cd NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.150:34212]
> 2009-07-23 12:29:06,809 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e691383 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.159:46813]
> 2009-07-23 12:29:06,809 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x12276d15dfb0350 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.162:59956]
> 2009-07-23 12:29:06,809 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e139b NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.156:55138]
> 2009-07-23 12:29:06,809 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e1398 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.167:41257]
> 2009-07-23 12:29:06,810 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d5161355 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.153:34032]
> 2009-07-23 12:29:06,810 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d516011c NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.155:56314]
> 2009-07-23 12:29:06,810 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e056b NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.155:56322]
> 2009-07-23 12:29:06,810 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d516011f NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.157:49618]
> 2009-07-23 12:29:06,810 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e11ea NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.10.20.42:55483]
> 2009-07-23 12:29:06,810 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e02ba NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.157:49632]
> 2009-07-23 12:29:06,810 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x12276d15dfb1355 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.169:58824]
> 2009-07-23 12:29:06,810 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e691378 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.161:40973]
> 2009-07-23 12:29:06,811 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e691380 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.162:59944]
> 2009-07-23 12:29:06,811 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e0311 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.160:56167]
> 2009-07-23 12:29:06,811 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e690374 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.169:58815]
> 2009-07-23 12:29:06,811 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e139f NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.151:51396]
> 2009-07-23 12:29:06,811 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e139c NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.155:56315]
> 2009-07-23 12:29:06,811 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e69137b NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.162:59859]
> 2009-07-23 12:29:06,811 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d5160594 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.151:51370]
> 2009-07-23 12:29:06,811 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e69137a NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.159:46682]
> 2009-07-23 12:29:06,812 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d5160347 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.165:35722]
> 2009-07-23 12:29:06,812 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e69137f NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.159:46754]
> 2009-07-23 12:29:06,812 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d5160121 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.155:56307]
> 2009-07-23 12:29:06,812 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x12276d15dfb0126 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.154:58688]
> 2009-07-23 12:29:06,812 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x42276d1d3fa05fc NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.152:45067]
> 2009-07-23 12:29:06,812 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e0316 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.169:58800]
> 2009-07-23 12:29:06,812 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e69137e NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.159:46737]
> 2009-07-23 12:29:06,813 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e69137d NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.159:46733]
> 2009-07-23 12:29:06,813 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x42276d1d3fa13df NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.156:55137]
> 2009-07-23 12:29:06,813 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x12276d15dfb134e NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.166:40443]
> 2009-07-23 12:29:06,813 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e691381 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.161:41086]
> 2009-07-23 12:29:06,813 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d5161356 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.165:35719]
> 2009-07-23 12:29:06,813 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x12276d15dfb1349 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181 remote=/10.
> 20.20.158:53770]
> 2009-07-23 12:29:06,813 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x12276d15dfb0352 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.165:35718]
> 2009-07-23 12:29:06,813 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e691379 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.162:59823]
> 2009-07-23 12:29:06,814 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d516000e NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.150:34216]
> 2009-07-23 12:29:06,814 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x32276d15d2e1397 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.169:58829]
> 2009-07-23 12:29:06,814 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e69137c NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.162:59862]
> 2009-07-23 12:29:06,814 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x42276d1d3fa0140 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.155:56271]
> 2009-07-23 12:29:06,814 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x42276d1d3fa13e1 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.157:49608]
> 2009-07-23 12:29:06,814 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x22276d15e691377 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.162:59789]
> 2009-07-23 12:29:06,814 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x52276d1d5160593 NIOServerCnxn:
> java.nio.channels.SocketChannel[connected local=/10.20.20.151:2181
> remote=/10.20.20.165:35703]
> 2009-07-23 12:29:06,814 INFO
> org.apache.zookeeper.server.FinalRequestProcessor: shutdown of request
> processor complete
> 2009-07-23 12:29:06,814 INFO
> org.apache.zookeeper.server.quorum.FollowerRequestProcessor:
> FollowerRequestProcessor exited loop!
> 2009-07-23 12:29:06,814 INFO
> org.apache.zookeeper.server.quorum.CommitProcessor: CommitProcessor exited
> loop!
> 2009-07-23 12:29:06,815 INFO org.apache.zookeeper.server.quorum.Follower:
> shutdown called
> java.lang.Exception: shutdown Follower
> at
> org.apache.zookeeper.server.quorum.Follower.shutdown(Follower.java:427)
> at
> org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:498)
> 2009-07-23 12:29:06,815 WARN org.apache.zookeeper.server.NIOServerCnxn:
> Ignoring exception
> java.nio.channels.CancelledKeyException
> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:55)
> at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:69)
> at
> org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:201)
> 2009-07-23 12:29:06,815 INFO org.apache.zookeeper.server.quorum.QuorumPeer:
> LOOKING
> 2009-07-23 12:29:06,817 WARN org.apache.zookeeper.server.NIOServerCnxn:
> Exception causing close of session 0x0 due to java.io.IOException:
> ZooKeeperServer not running
> 2009-07-23 12:29:06,817 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected
> local=/10.20.20.151:2181 remote=/10.20.20.156:55206]
> 2009-07-23 12:29:06,818 WARN org.apache.zookeeper.server.NIOServerCnxn:
> Exception causing close of session 0x0 due to java.io.IOException:
> ZooKeeperServer not running
> 2009-07-23 12:29:06,818 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected
> local=/10.20.20.151:2181 remote=/10.20.20.155:56331]
> [elided lots of the same]
> 2009-07-23 12:29:33,008 INFO org.apache.zookeeper.server.NIOServerCnxn:
> closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected
> local=/10.20.20.151:2181 remote=/10.20.20.152:5945
> 8]
> 2009-07-23 12:29:33,011 FATAL
> org.apache.zookeeper.server.SyncRequestProcessor: Severe unrecoverable error,
> exiting
> java.net.SocketException: Socket closed
> at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:99)
> at java.net.SocketOutputStream.write(SocketOutputStream.java:136)
> at
> java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:65)
> at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:123)
> at
> org.apache.zookeeper.server.quorum.Follower.writePacket(Follower.java:100)
> at
> org.apache.zookeeper.server.quorum.SendAckRequestProcessor.flush(SendAckRequestProcessor.java:52)
> at
> org.apache.zookeeper.server.SyncRequestProcessor.flush(SyncRequestProcessor.java:131)
> at
> org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:76)
> The good news is when I restarted the downed zookeepers, everything returned
> to normal.
--
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.
