2016-09-23 08:29:50,517 - INFO [main:QuorumPeerConfig@103] - Reading configuration from: /usr/hdp/current/zookeeper-server/conf/zoo.cfg 2016-09-23 08:29:50,520 - INFO [main:QuorumPeerConfig@338] - Defaulting to majority quorums 2016-09-23 08:29:50,525 - INFO [main:DatadirCleanupManager@78] - autopurge.snapRetainCount set to 10 2016-09-23 08:29:50,525 - INFO [main:DatadirCleanupManager@79] - autopurge.purgeInterval set to 24 2016-09-23 08:29:50,526 - INFO [PurgeTask:DatadirCleanupManager$PurgeTask@138] - Purge task started. 2016-09-23 08:29:50,531 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:hiearchy=default 2016-09-23 08:29:50,531 - DEBUG [PurgeTask:FileTxnSnapLog@79] - Opening datadir:/var/lib/zookeeper snapDir:/var/lib/zookeeper 2016-09-23 08:29:50,531 - DEBUG [main:HierarchyDynamicMBean@260] - postRegister is called. 2016-09-23 08:29:50,532 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=root 2016-09-23 08:29:50,533 - DEBUG [main:LoggerDynamicMBean@258] - Adding AppenderMBean for appender named ROLLINGFILE 2016-09-23 08:29:50,534 - DEBUG [main:AppenderDynamicMBean@158] - getMBeanInfo called. 2016-09-23 08:29:50,535 - DEBUG [main:AppenderDynamicMBean@337] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:appender=ROLLINGFILE 2016-09-23 08:29:50,535 - DEBUG [main:AppenderDynamicMBean@197] - Adding LayoutMBean:ROLLINGFILE,layout=org.apache.log4j.PatternLayout 2016-09-23 08:29:50,536 - DEBUG [main:LayoutDynamicMBean@140] - getMBeanInfo called. 2016-09-23 08:29:50,536 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:appender=ROLLINGFILE,layout=org.apache.log4j.PatternLayout 2016-09-23 08:29:50,536 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [root] as listener. 2016-09-23 08:29:50,541 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.server.quorum.flexible.QuorumMaj 2016-09-23 08:29:50,541 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.server.quorum.flexible.QuorumMaj] as listener. 2016-09-23 08:29:50,541 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.server.persistence.FileSnap 2016-09-23 08:29:50,542 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.server.persistence.FileSnap] as listener. 2016-09-23 08:29:50,542 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.log4j.jmx.LoggerDynamicMBean 2016-09-23 08:29:50,542 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.log4j.jmx.LoggerDynamicMBean] as listener. 2016-09-23 08:29:50,544 - INFO [PurgeTask:DatadirCleanupManager$PurgeTask@144] - Purge task completed. 2016-09-23 08:29:50,545 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.server.PurgeTxnLog 2016-09-23 08:29:50,545 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.server.PurgeTxnLog] as listener. 2016-09-23 08:29:50,545 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.jmx.MBeanRegistry 2016-09-23 08:29:50,545 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.jmx.MBeanRegistry] as listener. 2016-09-23 08:29:50,545 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.server.persistence.FileTxnSnapLog 2016-09-23 08:29:50,546 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.server.persistence.FileTxnSnapLog] as listener. 2016-09-23 08:29:50,546 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.server.quorum.QuorumPeerConfig 2016-09-23 08:29:50,546 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.server.quorum.QuorumPeerConfig] as listener. 2016-09-23 08:29:50,546 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.log4j.jmx.AppenderDynamicMBean 2016-09-23 08:29:50,546 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.log4j.jmx.AppenderDynamicMBean] as listener. 2016-09-23 08:29:50,547 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.server.persistence.Util 2016-09-23 08:29:50,547 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.server.persistence.Util] as listener. 2016-09-23 08:29:50,547 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.server.quorum.QuorumPeerMain 2016-09-23 08:29:50,547 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.server.quorum.QuorumPeerMain] as listener. 2016-09-23 08:29:50,547 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.log4j.jmx.HierarchyDynamicMBean 2016-09-23 08:29:50,547 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.log4j.jmx.HierarchyDynamicMBean] as listener. 2016-09-23 08:29:50,547 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.server.DatadirCleanupManager 2016-09-23 08:29:50,548 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.server.DatadirCleanupManager] as listener. 2016-09-23 08:29:50,548 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.log4j.jmx.LayoutDynamicMBean 2016-09-23 08:29:50,548 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.log4j.jmx.LayoutDynamicMBean] as listener. 2016-09-23 08:29:50,548 - DEBUG [main:AbstractDynamicMBean@148] - preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@66d3c617, name=log4j:logger=org.apache.zookeeper.server.persistence.FileTxnLog 2016-09-23 08:29:50,548 - DEBUG [main:HierarchyDynamicMBean@128] - ---Adding logger [org.apache.zookeeper.server.persistence.FileTxnLog] as listener. 2016-09-23 08:29:50,548 - INFO [main:QuorumPeerMain@127] - Starting quorum peer 2016-09-23 08:29:50,775 - INFO [main:Login@293] - successfully logged in. 2016-09-23 08:29:50,776 - INFO [Thread-2:Login$1@127] - TGT refresh thread started. 2016-09-23 08:29:50,778 - INFO [main:NIOServerCnxnFactory@94] - binding to port 0.0.0.0/0.0.0.0:2181 2016-09-23 08:29:50,784 - DEBUG [Thread-2:Login@319] - Found tgt Ticket (hex) = 0000: 61 82 01 1A 30 82 01 16 A0 03 02 01 05 A1 14 1B a...0........... 0010: 12 44 48 44 31 2E 4F 4F 55 41 54 2E 43 55 53 54 .realmelement.realmelement. 0020: 4F 4D 53 A2 27 30 25 A0 03 02 01 02 A1 1E 30 1C realmelement.'0%.......0. 0030: 1B 06 6B 72 62 74 67 74 1B 12 44 48 44 31 2E 4F ..krbtgt..realmelement. 0040: 4F 55 41 54 2E 43 55 53 54 4F 4D 53 A3 81 CF 30 realmelement.realmelement...0 0050: 81 CC A0 03 02 01 10 A1 03 02 01 01 A2 81 BF 04 ................ 0060: 81 BC 51 55 6D D7 52 0C 59 56 A5 42 16 D6 29 28 ..QUm.R.YV.B..)( 0070: B1 69 04 E5 78 E7 A2 93 7B 9B A8 CF EE 86 0D 0E .i..x........... 0080: 6E D4 C2 34 8E 8B 5A FD C0 07 34 AA BD 8D 7A 03 n..4..Z...4...z. 0090: 1C 3F 97 2F 7D D1 A1 D7 7A 66 0B E8 E4 53 35 98 .?./....zf...S5. 00A0: F7 C2 64 31 DA EA 93 2A A5 4A 9E 56 1F D9 09 D3 ..d1...*.J.V.... 00B0: 36 3D 1E 1F 18 E6 35 94 85 DA BC 08 79 16 79 E7 6=....5.....y.y. 00C0: 6C 44 D9 41 C6 CC 69 1C D4 E3 54 8E 87 8B AE 3F lD.A..i...T....? 00D0: EF 69 47 7A A9 2E 71 98 F2 4E 7F 2C 4A ED 45 50 .iGz..q..N.,J.EP 00E0: 06 DB 0B 8C 05 4C 3C 28 FE B1 98 11 CD 51 8A C4 .....L<(.....Q.. 00F0: 36 A0 5E DF F4 BD 40 71 0D 72 C5 14 8E E9 6D CA 6.^...@q.r....m. 0100: 8A AE 9D 53 40 11 7B 63 1E 94 E6 E7 0A BE 73 7B ...S@..c......s. 0110: 16 FC BD B7 87 DF 55 1C 6F 79 60 7A 52 28 ......U.oy`zR( Client Principal = zookeeper/namenode_1@realm Server Principal = krbtgt/realm@realm Session Key = EncryptionKey: keyType=16 keyBytes (hex dump)= 0000: 46 8C D0 31 08 19 02 C7 8C 4A AB 37 F8 89 25 91 F..1.....J.7..%. 0010: CD AD 79 26 94 FB 16 58 ..y&...X Forwardable Ticket true Forwarded Ticket false Proxiable Ticket false Proxy Ticket false Postdated Ticket false Renewable Ticket false Initial Ticket false Auth Time = Fri Sep 23 08:29:50 AEST 2016 Start Time = Fri Sep 23 08:29:50 AEST 2016 End Time = Sat Sep 24 08:29:50 AEST 2016 Renew Till = null Client Addresses Null . 2016-09-23 08:29:50,784 - INFO [Thread-2:Login@301] - TGT valid starting at: Fri Sep 23 08:29:50 AEST 2016 2016-09-23 08:29:50,784 - INFO [Thread-2:Login@302] - TGT expires: Sat Sep 24 08:29:50 AEST 2016 2016-09-23 08:29:50,784 - INFO [Thread-2:Login$1@181] - TGT refresh sleeping until: Sat Sep 24 04:20:02 AEST 2016 2016-09-23 08:29:50,788 - DEBUG [main:FileTxnSnapLog@79] - Opening datadir:/var/lib/zookeeper snapDir:/var/lib/zookeeper 2016-09-23 08:29:50,788 - INFO [main:QuorumPeer@992] - tickTime set to 2000 2016-09-23 08:29:50,788 - INFO [main:QuorumPeer@1012] - minSessionTimeout set to -1 2016-09-23 08:29:50,788 - INFO [main:QuorumPeer@1023] - maxSessionTimeout set to -1 2016-09-23 08:29:50,788 - INFO [main:QuorumPeer@1038] - initLimit set to 10 2016-09-23 08:29:50,797 - INFO [main:FileSnap@83] - Reading snapshot /var/lib/zookeeper/version-2/snapshot.7000000122 2016-09-23 08:29:51,136 - DEBUG [main:FileTxnLog$FileTxnIterator@593] - Created new input stream /var/lib/zookeeper/version-2/log.6f00000001 2016-09-23 08:29:51,137 - DEBUG [main:FileTxnLog$FileTxnIterator@596] - Created new input archive /var/lib/zookeeper/version-2/log.6f00000001 2016-09-23 08:29:51,143 - DEBUG [main:FileTxnLog$FileTxnIterator@636] - EOF excepton java.io.EOFException: Failed to read /var/lib/zookeeper/version-2/log.6f00000001 2016-09-23 08:29:51,143 - DEBUG [main:FileTxnLog$FileTxnIterator@593] - Created new input stream /var/lib/zookeeper/version-2/log.7000000123 2016-09-23 08:29:51,143 - DEBUG [main:FileTxnLog$FileTxnIterator@596] - Created new input archive /var/lib/zookeeper/version-2/log.7000000123 2016-09-23 08:29:51,157 - DEBUG [main:DataTree@949] - Ignoring processTxn failure hdr: -1 : error: -110 2016-09-23 08:29:51,158 - DEBUG [main:FileTxnSnapLog@214] - Ignoring processTxn failure hdr:-1, error: -110, path: null ... 2016-09-23 08:29:51,790 - DEBUG [main:FileTxnSnapLog@214] - Ignoring processTxn failure hdr:-1, error: -110, path: null 2016-09-23 08:29:51,791 - DEBUG [main:DataTree@949] - Ignoring processTxn failure hdr: -1 : error: -101 2016-09-23 08:29:51,791 - DEBUG [main:FileTxnSnapLog@214] - Ignoring processTxn failure hdr:-1, error: -101, path: null 2016-09-23 08:29:51,791 - DEBUG [main:FileTxnLog$FileTxnIterator@636] - EOF excepton java.io.EOFException: Failed to read /var/lib/zookeeper/version-2/log.7300000001 2016-09-23 08:29:51,794 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.3:34139 2016-09-23 08:29:51,795 - INFO [Thread-3:QuorumCnxManager$Listener@506] - My election bind port: namenode_1/34.45.6.2:3888 2016-09-23 08:29:51,799 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:51,799 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:51,799 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:51,799 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:QuorumPeer@712] - Starting quorum peer 2016-09-23 08:29:51,803 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:QuorumPeer@747] - LOOKING 2016-09-23 08:29:51,803 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:QuorumPeer@678] - Initializing leader election protocol... 2016-09-23 08:29:51,804 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@716] - Updating proposal: 2 (newleader), 0x73000079ea (newzxid), -1 (oldleader), 0xffffffffffffffff (oldzxid) 2016-09-23 08:29:51,804 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@815] - New election. My id = 2, proposed zxid=0x73000079ea 2016-09-23 08:29:51,804 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@586] - Sending Notification: 2 (n.leader), 0x73000079ea (n.zxid), 0x1 (n.round), 1 (recipient), 2 (myid), 0x73 (n.peerEpoch) 2016-09-23 08:29:51,805 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@586] - Sending Notification: 2 (n.leader), 0x73000079ea (n.zxid), 0x1 (n.round), 2 (recipient), 2 (myid), 0x73 (n.peerEpoch) 2016-09-23 08:29:51,805 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@586] - Sending Notification: 2 (n.leader), 0x73000079ea (n.zxid), 0x1 (n.round), 3 (recipient), 2 (myid), 0x73 (n.peerEpoch) 2016-09-23 08:29:51,805 - DEBUG [WorkerSender[myid=2]:QuorumCnxManager@364] - Opening channel to server 1 2016-09-23 08:29:51,807 - DEBUG [WorkerSender[myid=2]:QuorumCnxManager@370] - Connected to server 1 2016-09-23 08:29:51,808 - DEBUG [WorkerSender[myid=2]:QuorumCnxManager$SendWorker@590] - Address of remote peer: 1 2016-09-23 08:29:51,810 - DEBUG [WorkerSender[myid=2]:QuorumCnxManager@364] - Opening channel to server 3 2016-09-23 08:29:51,810 - DEBUG [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@270] - Receive new notification message. My id = 2 2016-09-23 08:29:51,810 - DEBUG [WorkerSender[myid=2]:QuorumCnxManager@370] - Connected to server 3 2016-09-23 08:29:51,810 - INFO [WorkerSender[myid=2]:QuorumCnxManager@193] - Have smaller server identifier, so dropping the connection: (3, 2) 2016-09-23 08:29:51,810 - INFO [WorkerReceiver[myid=2]:FastLeaderElection@597] - Notification: 1 (message format version), 2 (n.leader), 0x73000079ea (n.zxid), 0x1 (n.round), LOOKING (n.state), 2 (n.sid), 0x73 (n.peerEpoch) LOOKING (my state) 2016-09-23 08:29:51,810 - DEBUG [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@270] - Receive new notification message. My id = 2 2016-09-23 08:29:51,810 - INFO [WorkerReceiver[myid=2]:FastLeaderElection@597] - Notification: 1 (message format version), 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), LOOKING (n.state), 1 (n.sid), 0x73 (n.peerEpoch) LOOKING (my state) 2016-09-23 08:29:51,810 - INFO [namenode_1/34.45.6.2:3888:QuorumCnxManager$Listener@513] - Received connection request /34.45.6.3:40194 2016-09-23 08:29:51,810 - DEBUG [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@270] - Receive new notification message. My id = 2 2016-09-23 08:29:51,810 - DEBUG [namenode_1/34.45.6.2:3888:QuorumCnxManager$SendWorker@590] - Address of remote peer: 3 2016-09-23 08:29:51,810 - INFO [WorkerReceiver[myid=2]:FastLeaderElection@597] - Notification: 1 (message format version), 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), FOLLOWING (n.state), 1 (n.sid), 0x73 (n.peerEpoch) LOOKING (my state) 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@609] - id: 2, proposed id: 2, zxid: 0x73000079ea, proposed zxid: 0x73000079ea 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@885] - Adding vote: from=2, proposed leader=2, proposed zxid=0x73000079ea, proposed election epoch=0x1 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@609] - id: 3, proposed id: 2, zxid: 0x73000079ea, proposed zxid: 0x73000079ea 2016-09-23 08:29:51,811 - DEBUG [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@270] - Receive new notification message. My id = 2 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@716] - Updating proposal: 3 (newleader), 0x73000079ea (newzxid), 2 (oldleader), 0x73000079ea (oldzxid) 2016-09-23 08:29:51,811 - INFO [WorkerReceiver[myid=2]:FastLeaderElection@597] - Notification: 1 (message format version), 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), LOOKING (n.state), 3 (n.sid), 0x73 (n.peerEpoch) LOOKING (my state) 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@586] - Sending Notification: 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), 1 (recipient), 2 (myid), 0x73 (n.peerEpoch) 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@586] - Sending Notification: 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), 2 (recipient), 2 (myid), 0x73 (n.peerEpoch) 2016-09-23 08:29:51,811 - DEBUG [WorkerSender[myid=2]:QuorumCnxManager@389] - There is a connection already for server 1 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@586] - Sending Notification: 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), 3 (recipient), 2 (myid), 0x73 (n.peerEpoch) 2016-09-23 08:29:51,811 - DEBUG [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@270] - Receive new notification message. My id = 2 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@885] - Adding vote: from=1, proposed leader=3, proposed zxid=0x73000079ea, proposed election epoch=0x2 2016-09-23 08:29:51,811 - INFO [WorkerReceiver[myid=2]:FastLeaderElection@597] - Notification: 1 (message format version), 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), LEADING (n.state), 3 (n.sid), 0x73 (n.peerEpoch) LOOKING (my state) 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@609] - id: 3, proposed id: 3, zxid: 0x73000079ea, proposed zxid: 0x73000079ea 2016-09-23 08:29:51,811 - DEBUG [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@270] - Receive new notification message. My id = 2 2016-09-23 08:29:51,811 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@885] - Adding vote: from=3, proposed leader=3, proposed zxid=0x73000079ea, proposed election epoch=0x2 2016-09-23 08:29:51,812 - INFO [WorkerReceiver[myid=2]:FastLeaderElection@597] - Notification: 1 (message format version), 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), LOOKING (n.state), 2 (n.sid), 0x73 (n.peerEpoch) LOOKING (my state) 2016-09-23 08:29:51,812 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@609] - id: 3, proposed id: 3, zxid: 0x73000079ea, proposed zxid: 0x73000079ea 2016-09-23 08:29:51,812 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.2:60031 2016-09-23 08:29:51,812 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@609] - id: 3, proposed id: 3, zxid: 0x73000079ea, proposed zxid: 0x73000079ea 2016-09-23 08:29:51,812 - DEBUG [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@270] - Receive new notification message. My id = 2 2016-09-23 08:29:51,812 - DEBUG [WorkerSender[myid=2]:QuorumCnxManager@389] - There is a connection already for server 3 2016-09-23 08:29:51,812 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:51,812 - INFO [WorkerReceiver[myid=2]:FastLeaderElection@597] - Notification: 1 (message format version), 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), FOLLOWING (n.state), 1 (n.sid), 0x73 (n.peerEpoch) LOOKING (my state) 2016-09-23 08:29:51,812 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:51,812 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:51,812 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@609] - id: 3, proposed id: 3, zxid: 0x73000079ea, proposed zxid: 0x73000079ea 2016-09-23 08:29:51,812 - DEBUG [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@270] - Receive new notification message. My id = 2 2016-09-23 08:29:51,812 - INFO [WorkerReceiver[myid=2]:FastLeaderElection@597] - Notification: 1 (message format version), 3 (n.leader), 0x73000079ea (n.zxid), 0x2 (n.round), LEADING (n.state), 3 (n.sid), 0x73 (n.peerEpoch) LOOKING (my state) 2016-09-23 08:29:51,812 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@609] - id: 3, proposed id: 3, zxid: 0x73000079ea, proposed zxid: 0x73000079ea 2016-09-23 08:29:51,814 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running 2016-09-23 08:29:51,814 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@366] - IOException stack trace java.io.IOException: ZooKeeperServer not running at org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:931) at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:237) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) at java.lang.Thread.run(Thread.java:745) 2016-09-23 08:29:51,816 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.3:34139 (no session established for client) 2016-09-23 08:29:51,816 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running 2016-09-23 08:29:51,816 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@366] - IOException stack trace java.io.IOException: ZooKeeperServer not running at org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:931) at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:237) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) at java.lang.Thread.run(Thread.java:745) 2016-09-23 08:29:51,816 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.2:60031 (no session established for client) 2016-09-23 08:29:51,817 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.2:60032 2016-09-23 08:29:51,817 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:51,817 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:51,817 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:51,818 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running 2016-09-23 08:29:51,818 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@366] - IOException stack trace java.io.IOException: ZooKeeperServer not running at org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:931) at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:237) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) at java.lang.Thread.run(Thread.java:745) 2016-09-23 08:29:51,818 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.2:60032 (no session established for client) 2016-09-23 08:29:51,818 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.2:60036 2016-09-23 08:29:51,818 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:51,818 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:51,818 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:51,819 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@827] - Processing stat command from /34.45.6.2:60036 2016-09-23 08:29:51,821 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.2:60037 2016-09-23 08:29:51,821 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:51,821 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:51,821 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:51,821 - INFO [Thread-4:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.2:60036 (no session established for client) 2016-09-23 08:29:51,822 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.3:34144 2016-09-23 08:29:51,822 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:51,822 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:51,822 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:51,823 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running 2016-09-23 08:29:51,823 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@366] - IOException stack trace java.io.IOException: ZooKeeperServer not running at org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:931) at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:237) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) at java.lang.Thread.run(Thread.java:745) 2016-09-23 08:29:51,823 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.2:60037 (no session established for client) 2016-09-23 08:29:51,823 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running 2016-09-23 08:29:51,823 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@366] - IOException stack trace java.io.IOException: ZooKeeperServer not running at org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:931) at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:237) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) at java.lang.Thread.run(Thread.java:745) 2016-09-23 08:29:51,823 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.3:34144 (no session established for client) 2016-09-23 08:29:51,823 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.4:43694 2016-09-23 08:29:51,823 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:51,823 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:51,823 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:51,824 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running 2016-09-23 08:29:51,824 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@366] - IOException stack trace java.io.IOException: ZooKeeperServer not running at org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:931) at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:237) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) at java.lang.Thread.run(Thread.java:745) 2016-09-23 08:29:51,824 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.4:43694 (no session established for client) 2016-09-23 08:29:51,825 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.5:33604 2016-09-23 08:29:51,825 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:51,825 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:51,825 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:51,826 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running 2016-09-23 08:29:51,826 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@366] - IOException stack trace java.io.IOException: ZooKeeperServer not running at org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:931) at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:237) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) at java.lang.Thread.run(Thread.java:745) 2016-09-23 08:29:51,826 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.5:33604 (no session established for client) 2016-09-23 08:29:51,827 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.3:34152 2016-09-23 08:29:51,827 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:51,827 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:51,827 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:51,828 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running 2016-09-23 08:29:51,828 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@366] - IOException stack trace java.io.IOException: ZooKeeperServer not running at org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:931) at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:237) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) at java.lang.Thread.run(Thread.java:745) 2016-09-23 08:29:51,828 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.3:34152 (no session established for client) 2016-09-23 08:29:52,013 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@738] - I'm a participant: 2 2016-09-23 08:29:52,013 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FastLeaderElection@548] - About to leave FLE instance: leader=3, zxid=0x73000079ea, my id=2, my state=FOLLOWING 2016-09-23 08:29:52,013 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:QuorumPeer@817] - FOLLOWING 2016-09-23 08:29:52,017 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Learner@86] - TCP NoDelay set to: true 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:zookeeper.version=34.45.6-258--1, built on 04/24/2016 15:43 GMT 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:host.name=namenode_1 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:java.version=1.8.0_91 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:java.vendor=Oracle Corporation 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:java.home=/opt/teradata/jvm64/jdk8/jre 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:java.class.path=/usr/hdp/current/zookeeper-server/bin/../build/classes:/usr/hdp/current/zookeeper-server/bin/../build/lib/*.jar:/usr/hdp/current/zookeeper-server/bin/../lib/xercesMinimal-34.45.6.2.jar:/usr/hdp/current/zookeeper-server/bin/../lib/wagon-provider-api-2.4.jar:/usr/hdp/current/zookeeper-server/bin/../lib/wagon-http-shared4-2.4.jar:/usr/hdp/current/zookeeper-server/bin/../lib/wagon-http-shared-1.0-beta-6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/wagon-http-lightweight-1.0-beta-6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/wagon-http-2.4.jar:/usr/hdp/current/zookeeper-server/bin/../lib/wagon-file-1.0-beta-6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/slf4j-log4j12-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/slf4j-api-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/plexus-utils-3.0.8.jar:/usr/hdp/current/zookeeper-server/bin/../lib/plexus-interpolation-1.11.jar:/usr/hdp/current/zookeeper-server/bin/../lib/plexus-container-default-1.0-alpha-9-stable-1.jar:/usr/hdp/current/zookeeper-server/bin/../lib/netty-3.7.0.Final.jar:/usr/hdp/current/zookeeper-server/bin/../lib/nekohtml-34.45.6.2.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-settings-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-repository-metadata-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-project-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-profile-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-plugin-registry-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-model-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-error-diagnostics-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-artifact-manager-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-artifact-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/maven-ant-tasks-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/log4j-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/jsoup-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/jline-0.9.94.jar:/usr/hdp/current/zookeeper-server/bin/../lib/httpcore-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/httpclient-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/commons-logging-34.45.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/commons-io-2.2.jar:/usr/hdp/current/zookeeper-server/bin/../lib/commons-codec-1.6.jar:/usr/hdp/current/zookeeper-server/bin/../lib/classworlds-1.1-alpha-2.jar:/usr/hdp/current/zookeeper-server/bin/../lib/backport-util-concurrent-3.1.jar:/usr/hdp/current/zookeeper-server/bin/../lib/ant-launcher-1.8.0.jar:/usr/hdp/current/zookeeper-server/bin/../lib/ant-1.8.0.jar:/usr/hdp/current/zookeeper-server/bin/../zookeeper-34.45.6.34.45.6.0-258.jar:/usr/hdp/current/zookeeper-server/bin/../src/java/lib/*.jar:/usr/hdp/current/zookeeper-server/conf::/usr/share/zookeeper/*:/usr/share/zookeeper/* 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:java.library.path=/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:java.io.tmpdir=/tmp 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:java.compiler= 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:os.name=Linux 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:os.arch=amd64 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:os.version=3.0.101-0.80.TDC.1.R.0-default 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:user.name=zookeeper 2016-09-23 08:29:52,022 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:user.home=/var/run/zookeeper 2016-09-23 08:29:52,023 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Environment@100] - Server environment:user.dir=/var/run/zookeeper 2016-09-23 08:29:52,023 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@162] - Created server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 datadir /var/lib/zookeeper/version-2 snapdir /var/lib/zookeeper/version-2 2016-09-23 08:29:52,024 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Follower@63] - FOLLOWING - LEADER ELECTION TOOK - 220 2016-09-23 08:29:52,033 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Learner@323] - Getting a diff from the leader 0x73000079ea 2016-09-23 08:29:52,035 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:FileTxnSnapLog@240] - Snapshotting: 0x73000079ea to /var/lib/zookeeper/version-2/snapshot.73000079ea 2016-09-23 08:29:52,278 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.6:46163 2016-09-23 08:29:52,278 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,278 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,278 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,279 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running 2016-09-23 08:29:52,279 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@366] - IOException stack trace java.io.IOException: ZooKeeperServer not running at org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:931) at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:237) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) at java.lang.Thread.run(Thread.java:745) 2016-09-23 08:29:52,279 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1007] - Closed socket connection for client /34.45.6.6:46163 (no session established for client) 2016-09-23 08:29:52,503 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.5:33605 2016-09-23 08:29:52,503 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,503 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,503 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,505 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.5:33605 client's lastZxid is 0x7300001465 2016-09-23 08:29:52,505 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x2574fefc9520019 at /34.45.6.5:33605 2016-09-23 08:29:52,505 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x2574fefc9520019 2016-09-23 08:29:52,508 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x2574fefc9520019 with negotiated timeout 30000 for client /34.45.6.5:33605 2016-09-23 08:29:52,509 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,510 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 546 2016-09-23 08:29:52,520 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@984] - Size of server SASL response: 65 2016-09-23 08:29:52,521 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,521 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 97 2016-09-23 08:29:52,522 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@118] - Successfully authenticated client: authenticationID=hbase/datanode_2@realm; authorizationID=hbase/datanode_2@realm. 2016-09-23 08:29:52,524 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@134] - Setting authorizedID: hbase 2016-09-23 08:29:52,525 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@964] - adding SASL authorization for authorizationID: hbase 2016-09-23 08:29:52,643 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.4:43699 2016-09-23 08:29:52,706 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,706 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,706 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,707 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.4:43699 client's lastZxid is 0x5d0000011e 2016-09-23 08:29:52,707 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x3574a76d3ff0009 at /34.45.6.4:43699 2016-09-23 08:29:52,707 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x3574a76d3ff0009 2016-09-23 08:29:52,707 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.3:34155 2016-09-23 08:29:52,707 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,707 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,707 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,707 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x3574a76d3ff0009 with negotiated timeout 30000 for client /34.45.6.4:43699 2016-09-23 08:29:52,708 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.3:34155 client's lastZxid is 0x730000006c 2016-09-23 08:29:52,708 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x2574fefc9520000 at /34.45.6.3:34155 2016-09-23 08:29:52,708 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x2574fefc9520000 2016-09-23 08:29:52,708 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x2574fefc9520000 with negotiated timeout 10000 for client /34.45.6.3:34155 2016-09-23 08:29:52,709 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x2574fefc9520000 type:setWatches cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,710 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x2574fefc9520000 type:exists cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:/yarn-leader-election/yarn-cluster/ActiveStandbyElectorLock 2016-09-23 08:29:52,710 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x2574fefc9520000 type:setWatches cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,710 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x2574fefc9520000 type:setWatches cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,711 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x2574fefc9520000 type:exists cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:/yarn-leader-election/yarn-cluster/ActiveStandbyElectorLock 2016-09-23 08:29:52,711 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x2574fefc9520000 type:exists cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:/yarn-leader-election/yarn-cluster/ActiveStandbyElectorLock 2016-09-23 08:29:52,712 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x2574fefc9520000 type:getData cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:/yarn-leader-election/yarn-cluster/ActiveBreadCrumb 2016-09-23 08:29:52,713 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x2574fefc9520000 type:getData cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:/yarn-leader-election/yarn-cluster/ActiveBreadCrumb 2016-09-23 08:29:52,713 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x2574fefc9520000 type:getData cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:/yarn-leader-election/yarn-cluster/ActiveBreadCrumb 2016-09-23 08:29:52,714 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.3:34156 2016-09-23 08:29:52,714 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,714 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,714 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,715 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.3:34156 client's lastZxid is 0x73000075e9 2016-09-23 08:29:52,715 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x1574a76e0fa0008 at /34.45.6.3:34156 2016-09-23 08:29:52,715 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x1574a76e0fa0008 2016-09-23 08:29:52,715 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x1574a76e0fa0008 with negotiated timeout 30000 for client /34.45.6.3:34156 2016-09-23 08:29:52,717 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x2574fefc9520000 type:setData cxid:0xa zxid:0xfffffffffffffffe txntype:unknown reqpath:/yarn-leader-election/yarn-cluster/ActiveBreadCrumb 2016-09-23 08:29:52,719 - WARN [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:Follower@118] - Got zxid 0x7400000001 expected 0x1 2016-09-23 08:29:52,719 - INFO [SyncThread:2:FileTxnLog@199] - Creating new log file: log.7400000001 2016-09-23 08:29:52,720 - DEBUG [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:CommitProcessor@161] - Committing request:: sessionid:0x2574fefc9520000 type:setData cxid:0xa zxid:0x7400000001 txntype:5 reqpath:n/a 2016-09-23 08:29:52,720 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x2574fefc9520000 type:setData cxid:0xa zxid:0x7400000001 txntype:5 reqpath:/yarn-leader-election/yarn-cluster/ActiveBreadCrumb 2016-09-23 08:29:52,720 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x2574fefc9520000 type:setData cxid:0xa zxid:0x7400000001 txntype:5 reqpath:/yarn-leader-election/yarn-cluster/ActiveBreadCrumb 2016-09-23 08:29:52,752 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.2:60055 2016-09-23 08:29:52,752 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,752 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,752 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,753 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.2:60055 client's lastZxid is 0x710000005e 2016-09-23 08:29:52,753 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x3574496197f0001 at /34.45.6.2:60055 2016-09-23 08:29:52,753 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x3574496197f0001 2016-09-23 08:29:52,754 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x3574496197f0001 with negotiated timeout 40000 for client /34.45.6.2:60055 2016-09-23 08:29:52,759 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,759 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 562 2016-09-23 08:29:52,763 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@984] - Size of server SASL response: 65 2016-09-23 08:29:52,764 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,764 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 97 2016-09-23 08:29:52,764 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@118] - Successfully authenticated client: authenticationID=hbase/datanode_1@realm; authorizationID=hbase/datanode_1@realm. 2016-09-23 08:29:52,764 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@134] - Setting authorizedID: hbase 2016-09-23 08:29:52,764 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@964] - adding SASL authorization for authorizationID: hbase 2016-09-23 08:29:52,787 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.3:34157 2016-09-23 08:29:52,787 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,787 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,787 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,788 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.3:34157 client's lastZxid is 0x73000079e3 2016-09-23 08:29:52,788 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x157272714860001 at /34.45.6.3:34157 2016-09-23 08:29:52,788 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x157272714860001 2016-09-23 08:29:52,789 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x157272714860001 with negotiated timeout 40000 for client /34.45.6.3:34157 2016-09-23 08:29:52,794 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,794 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 562 2016-09-23 08:29:52,797 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@984] - Size of server SASL response: 65 2016-09-23 08:29:52,798 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,798 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 97 2016-09-23 08:29:52,798 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@118] - Successfully authenticated client: authenticationID=hbase/namenode_2@realm; authorizationID=hbase/namenode_2@realm. 2016-09-23 08:29:52,799 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@134] - Setting authorizedID: hbase 2016-09-23 08:29:52,799 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@964] - adding SASL authorization for authorizationID: hbase 2016-09-23 08:29:52,799 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x1574a76e0fa0008 type:setWatches cxid:0x329 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,799 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x1574a76e0fa0008 type:setWatches cxid:0x329 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,799 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x1574a76e0fa0008 type:setWatches cxid:0x329 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,810 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.5:33606 2016-09-23 08:29:52,817 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,817 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,817 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,818 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.5:33606 client's lastZxid is 0x73000079ea 2016-09-23 08:29:52,818 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x35727271b960007 at /34.45.6.5:33606 2016-09-23 08:29:52,818 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x35727271b960007 2016-09-23 08:29:52,818 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x35727271b960007 with negotiated timeout 30000 for client /34.45.6.5:33606 2016-09-23 08:29:52,818 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@892] - got auth packet /34.45.6.5:33606 2016-09-23 08:29:52,821 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@923] - Authentication succeeded for scheme: digest 2016-09-23 08:29:52,821 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@926] - auth success /34.45.6.5:33606 2016-09-23 08:29:52,821 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:setWatches cxid:0x9c7c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,821 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:setWatches cxid:0x9c7c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,821 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:exists cxid:0x9c7d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_2:9997 2016-09-23 08:29:52,821 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:setWatches cxid:0x9c7c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,822 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:exists cxid:0x9c7d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_2:9997 2016-09-23 08:29:52,822 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:exists cxid:0x9c7d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_2:9997 2016-09-23 08:29:52,822 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c7e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,822 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c7e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,822 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c7e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,833 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,834 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 562 2016-09-23 08:29:52,836 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@984] - Size of server SASL response: 65 2016-09-23 08:29:52,837 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,837 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 97 2016-09-23 08:29:52,837 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@118] - Successfully authenticated client: authenticationID=hive/namenode_1@realm; authorizationID=hive/namenode_1@realm. 2016-09-23 08:29:52,838 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@134] - Setting authorizedID: hive 2016-09-23 08:29:52,838 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@964] - adding SASL authorization for authorizationID: hive 2016-09-23 08:29:52,840 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getChildren cxid:0x9c7f zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,865 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getChildren cxid:0x9c7f zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,866 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getChildren cxid:0x9c7f zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,867 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c80 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,867 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c80 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,867 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c80 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,867 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:exists cxid:0x9c81 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_2:9997/zlock-0000000000 2016-09-23 08:29:52,867 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:exists cxid:0x9c81 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_2:9997/zlock-0000000000 2016-09-23 08:29:52,868 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:exists cxid:0x9c81 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_2:9997/zlock-0000000000 2016-09-23 08:29:52,868 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:exists cxid:0x9c82 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_2:9997 2016-09-23 08:29:52,868 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:exists cxid:0x9c82 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_2:9997 2016-09-23 08:29:52,868 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:exists cxid:0x9c82 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_2:9997 2016-09-23 08:29:52,868 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c83 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,868 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c83 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,878 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c83 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,882 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getChildren cxid:0x9c84 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,882 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getChildren cxid:0x9c84 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,882 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getChildren cxid:0x9c84 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,883 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c85 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,883 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c85 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,883 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c85 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,883 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:exists cxid:0x9c86 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:52,883 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:exists cxid:0x9c86 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:52,883 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:exists cxid:0x9c86 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:52,884 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getChildren cxid:0x9c87 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:52,884 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getChildren cxid:0x9c87 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:52,884 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getChildren cxid:0x9c87 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:52,884 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c88 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/25 2016-09-23 08:29:52,884 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c88 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/25 2016-09-23 08:29:52,884 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c88 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/25 2016-09-23 08:29:52,884 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c89 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/26 2016-09-23 08:29:52,884 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c89 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/26 2016-09-23 08:29:52,885 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c89 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/26 2016-09-23 08:29:52,885 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/27 2016-09-23 08:29:52,885 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/27 2016-09-23 08:29:52,885 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,885 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c8a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/27 2016-09-23 08:29:52,885 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 562 2016-09-23 08:29:52,889 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@984] - Size of server SASL response: 65 2016-09-23 08:29:52,889 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/28 2016-09-23 08:29:52,889 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.2:60056 2016-09-23 08:29:52,889 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/28 2016-09-23 08:29:52,889 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,889 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c8b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/28 2016-09-23 08:29:52,889 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,889 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,890 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8c zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/29 2016-09-23 08:29:52,890 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,890 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8c zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/29 2016-09-23 08:29:52,890 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 97 2016-09-23 08:29:52,890 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c8c zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/29 2016-09-23 08:29:52,891 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@118] - Successfully authenticated client: authenticationID=hive/namenode_2@realm; authorizationID=hive/namenode_2@realm. 2016-09-23 08:29:52,891 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@134] - Setting authorizedID: hive 2016-09-23 08:29:52,891 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@964] - adding SASL authorization for authorizationID: hive 2016-09-23 08:29:52,891 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.2:60056 client's lastZxid is 0x730000799a 2016-09-23 08:29:52,891 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x35727271b960008 at /34.45.6.2:60056 2016-09-23 08:29:52,891 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x35727271b960008 2016-09-23 08:29:52,891 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/30 2016-09-23 08:29:52,891 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/30 2016-09-23 08:29:52,891 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c8d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/30 2016-09-23 08:29:52,891 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x35727271b960008 with negotiated timeout 30000 for client /34.45.6.2:60056 2016-09-23 08:29:52,892 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@892] - got auth packet /34.45.6.2:60056 2016-09-23 08:29:52,892 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/31 2016-09-23 08:29:52,892 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@923] - Authentication succeeded for scheme: digest 2016-09-23 08:29:52,892 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960007 type:getData cxid:0x9c8e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/31 2016-09-23 08:29:52,892 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@926] - auth success /34.45.6.2:60056 2016-09-23 08:29:52,892 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960007 type:getData cxid:0x9c8e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/31 2016-09-23 08:29:52,892 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:setWatches cxid:0x2025e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,892 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:getData cxid:0x2025f zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,892 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:setWatches cxid:0x2025e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,892 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:setWatches cxid:0x2025e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,892 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:getData cxid:0x2025f zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,892 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:getData cxid:0x2025f zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,899 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:getChildren cxid:0x20260 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,899 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:getChildren cxid:0x20260 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,899 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:getChildren cxid:0x20260 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,899 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:getData cxid:0x20261 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,899 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:getData cxid:0x20261 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,899 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:getData cxid:0x20261 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,900 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:exists cxid:0x20262 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/gc/lock 2016-09-23 08:29:52,900 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:exists cxid:0x20262 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/gc/lock 2016-09-23 08:29:52,900 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:exists cxid:0x20262 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/gc/lock 2016-09-23 08:29:52,900 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:exists cxid:0x20263 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/gc/lock/zlock-0000000027 2016-09-23 08:29:52,900 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:exists cxid:0x20263 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/gc/lock/zlock-0000000027 2016-09-23 08:29:52,900 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:exists cxid:0x20263 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/gc/lock/zlock-0000000027 2016-09-23 08:29:52,900 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:getData cxid:0x20264 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,900 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:getData cxid:0x20264 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,900 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:getData cxid:0x20264 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,901 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.6:46165 2016-09-23 08:29:52,901 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,901 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,901 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,902 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.6:46165 client's lastZxid is 0x5d0000011c 2016-09-23 08:29:52,902 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x2574a76d2250009 at /34.45.6.6:46165 2016-09-23 08:29:52,902 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x2574a76d2250009 2016-09-23 08:29:52,903 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x2574a76d2250009 with negotiated timeout 30000 for client /34.45.6.6:46165 2016-09-23 08:29:52,904 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:getChildren cxid:0x20265 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,904 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:getChildren cxid:0x20265 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,904 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:getChildren cxid:0x20265 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:52,904 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:getData cxid:0x20266 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,904 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:getData cxid:0x20266 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,904 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:getData cxid:0x20266 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:52,905 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960008 type:exists cxid:0x20267 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/gc/lock 2016-09-23 08:29:52,905 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960008 type:exists cxid:0x20267 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/gc/lock 2016-09-23 08:29:52,905 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960008 type:exists cxid:0x20267 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/gc/lock 2016-09-23 08:29:52,937 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,937 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 562 2016-09-23 08:29:52,940 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@984] - Size of server SASL response: 65 2016-09-23 08:29:52,940 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:52,940 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 97 2016-09-23 08:29:52,941 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@118] - Successfully authenticated client: authenticationID=hbase/datanode_3@realm; authorizationID=hbase/datanode_3@realm. 2016-09-23 08:29:52,941 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@134] - Setting authorizedID: hbase 2016-09-23 08:29:52,941 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@964] - adding SASL authorization for authorizationID: hbase 2016-09-23 08:29:52,950 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.6:46166 2016-09-23 08:29:52,957 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:52,957 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:52,958 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:52,959 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.6:46166 client's lastZxid is 0x73000079e4 2016-09-23 08:29:52,959 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x35727271b960006 at /34.45.6.6:46166 2016-09-23 08:29:52,959 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x35727271b960006 2016-09-23 08:29:52,959 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x35727271b960006 with negotiated timeout 30000 for client /34.45.6.6:46166 2016-09-23 08:29:52,960 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@892] - got auth packet /34.45.6.6:46166 2016-09-23 08:29:52,960 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@923] - Authentication succeeded for scheme: digest 2016-09-23 08:29:52,960 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@926] - auth success /34.45.6.6:46166 2016-09-23 08:29:52,960 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:setWatches cxid:0x16a49 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,960 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a4a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_3:9997 2016-09-23 08:29:52,960 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:setWatches cxid:0x16a49 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,961 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:setWatches cxid:0x16a49 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:52,961 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a4a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_3:9997 2016-09-23 08:29:52,961 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a4a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_3:9997 2016-09-23 08:29:52,961 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a4b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,962 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a4b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:52,962 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a4b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:53,006 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getChildren cxid:0x16a4c zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:53,006 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getChildren cxid:0x16a4c zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:53,006 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getChildren cxid:0x16a4c zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:53,006 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a4d zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:53,006 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a4d zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:53,006 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a4d zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:53,007 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a4e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_3:9997 2016-09-23 08:29:53,007 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a4e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_3:9997 2016-09-23 08:29:53,007 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a4e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_3:9997 2016-09-23 08:29:53,007 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a4f zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:53,007 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a4f zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:53,008 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a4f zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:53,013 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a50 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_3:9997/zlock-0000000002 2016-09-23 08:29:53,013 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a50 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_3:9997/zlock-0000000002 2016-09-23 08:29:53,013 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a50 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_3:9997/zlock-0000000002 2016-09-23 08:29:53,014 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getChildren cxid:0x16a51 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:53,014 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getChildren cxid:0x16a51 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:53,014 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getChildren cxid:0x16a51 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:53,014 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a52 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:53,014 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a52 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:53,014 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a52 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:53,015 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a53 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:53,015 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a53 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:53,015 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a53 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:53,015 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getChildren cxid:0x16a54 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:53,015 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getChildren cxid:0x16a54 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:53,015 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getChildren cxid:0x16a54 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys 2016-09-23 08:29:53,015 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a55 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/25 2016-09-23 08:29:53,015 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a55 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/25 2016-09-23 08:29:53,015 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a55 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/25 2016-09-23 08:29:53,016 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a56 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/26 2016-09-23 08:29:53,016 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a56 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/26 2016-09-23 08:29:53,016 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a56 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/26 2016-09-23 08:29:53,017 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a57 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/27 2016-09-23 08:29:53,017 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a57 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/27 2016-09-23 08:29:53,017 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a57 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/27 2016-09-23 08:29:53,017 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a58 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/28 2016-09-23 08:29:53,017 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a58 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/28 2016-09-23 08:29:53,017 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a58 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/28 2016-09-23 08:29:53,017 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a59 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/29 2016-09-23 08:29:53,018 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a59 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/29 2016-09-23 08:29:53,018 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a59 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/29 2016-09-23 08:29:53,018 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a5a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/30 2016-09-23 08:29:53,018 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a5a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/30 2016-09-23 08:29:53,018 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a5a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/30 2016-09-23 08:29:53,018 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a5b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/31 2016-09-23 08:29:53,018 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a5b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/31 2016-09-23 08:29:53,018 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a5b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/delegation_token_keys/31 2016-09-23 08:29:53,042 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getChildren cxid:0x16a5c zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables 2016-09-23 08:29:53,042 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getChildren cxid:0x16a5c zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables 2016-09-23 08:29:53,042 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getChildren cxid:0x16a5c zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables 2016-09-23 08:29:53,042 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a5d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/+r/conf/table.compaction.minor.idle 2016-09-23 08:29:53,042 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a5d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/+r/conf/table.compaction.minor.idle 2016-09-23 08:29:53,042 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a5d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/+r/conf/table.compaction.minor.idle 2016-09-23 08:29:53,045 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a5e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/+r/namespace 2016-09-23 08:29:53,045 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a5e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/+r/namespace 2016-09-23 08:29:53,045 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a5e zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/+r/namespace 2016-09-23 08:29:53,045 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a5f zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/+r/namespace 2016-09-23 08:29:53,045 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a5f zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/+r/namespace 2016-09-23 08:29:53,045 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a5f zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/+r/namespace 2016-09-23 08:29:53,045 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a60 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/namespaces/+accumulo/conf/table.compaction.minor.idle 2016-09-23 08:29:53,046 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a60 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/namespaces/+accumulo/conf/table.compaction.minor.idle 2016-09-23 08:29:53,046 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a60 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/namespaces/+accumulo/conf/table.compaction.minor.idle 2016-09-23 08:29:53,046 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a61 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/config/table.compaction.minor.idle 2016-09-23 08:29:53,046 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a61 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/config/table.compaction.minor.idle 2016-09-23 08:29:53,046 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a61 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/config/table.compaction.minor.idle 2016-09-23 08:29:53,046 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a62 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/conf/table.compaction.minor.idle 2016-09-23 08:29:53,046 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a62 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/conf/table.compaction.minor.idle 2016-09-23 08:29:53,046 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a62 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/conf/table.compaction.minor.idle 2016-09-23 08:29:53,047 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a63 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,047 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:exists cxid:0x16a63 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,047 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:exists cxid:0x16a63 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,047 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a64 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,047 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x35727271b960006 type:getData cxid:0x16a64 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,047 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x35727271b960006 type:getData cxid:0x16a64 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,140 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.4:43701 2016-09-23 08:29:53,140 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:53,140 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:53,140 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:53,141 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.4:43701 client's lastZxid is 0x73000079e4 2016-09-23 08:29:53,142 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x157272714860003 at /34.45.6.4:43701 2016-09-23 08:29:53,142 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x157272714860003 2016-09-23 08:29:53,142 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x157272714860003 with negotiated timeout 30000 for client /34.45.6.4:43701 2016-09-23 08:29:53,142 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@892] - got auth packet /34.45.6.4:43701 2016-09-23 08:29:53,143 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@923] - Authentication succeeded for scheme: digest 2016-09-23 08:29:53,143 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@926] - auth success /34.45.6.4:43701 2016-09-23 08:29:53,143 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:setWatches cxid:0x10680 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:53,143 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:getChildren cxid:0x10681 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:53,143 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:setWatches cxid:0x10680 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:53,143 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:setWatches cxid:0x10680 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a 2016-09-23 08:29:53,144 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:getChildren cxid:0x10681 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:53,144 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:getChildren cxid:0x10681 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers 2016-09-23 08:29:53,144 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:getData cxid:0x10682 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:53,144 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:getData cxid:0x10682 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:53,145 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:getData cxid:0x10682 zxid:0xfffffffffffffffe txntype:unknown reqpath:/tracers/trace-0000000008 2016-09-23 08:29:53,145 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x10683 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_1:9997 2016-09-23 08:29:53,145 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x10683 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_1:9997 2016-09-23 08:29:53,145 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:exists cxid:0x10683 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tservers/datanode_1:9997 2016-09-23 08:29:53,145 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:getData cxid:0x10684 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:53,146 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:getData cxid:0x10684 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:53,146 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:getData cxid:0x10684 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/monitor/log4j_addr 2016-09-23 08:29:53,166 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.2:60063 2016-09-23 08:29:53,166 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:53,166 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:53,166 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:53,167 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.2:60063 client's lastZxid is 0x6e00000071 2016-09-23 08:29:53,167 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x1574f7c71bf0000 at /34.45.6.2:60063 2016-09-23 08:29:53,167 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x1574f7c71bf0000 2016-09-23 08:29:53,167 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x1574f7c71bf0000 with negotiated timeout 30000 for client /34.45.6.2:60063 2016-09-23 08:29:53,203 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:53,203 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 562 2016-09-23 08:29:53,206 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@984] - Size of server SASL response: 65 2016-09-23 08:29:53,206 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:53,206 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 97 2016-09-23 08:29:53,207 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@118] - Successfully authenticated client: authenticationID=hbase/namenode_1@realm; authorizationID=hbase/namenode_1@realm. 2016-09-23 08:29:53,207 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:SaslServerCallbackHandler@134] - Setting authorizedID: hbase 2016-09-23 08:29:53,207 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@964] - adding SASL authorization for authorizationID: hbase 2016-09-23 08:29:53,220 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:getChildren cxid:0x10685 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables 2016-09-23 08:29:53,220 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:getChildren cxid:0x10685 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables 2016-09-23 08:29:53,220 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:getChildren cxid:0x10685 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables 2016-09-23 08:29:53,221 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x10686 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/1/namespace 2016-09-23 08:29:53,221 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x10686 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/1/namespace 2016-09-23 08:29:53,221 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:exists cxid:0x10686 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/1/namespace 2016-09-23 08:29:53,221 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:getData cxid:0x10687 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/1/namespace 2016-09-23 08:29:53,221 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:getData cxid:0x10687 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/1/namespace 2016-09-23 08:29:53,221 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:getData cxid:0x10687 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/1/namespace 2016-09-23 08:29:53,222 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x10688 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/namespaces/+default/conf/table.compaction.minor.idle 2016-09-23 08:29:53,222 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x10688 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/namespaces/+default/conf/table.compaction.minor.idle 2016-09-23 08:29:53,222 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:exists cxid:0x10688 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/namespaces/+default/conf/table.compaction.minor.idle 2016-09-23 08:29:53,222 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x10689 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/config/table.compaction.minor.idle 2016-09-23 08:29:53,222 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x10689 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/config/table.compaction.minor.idle 2016-09-23 08:29:53,222 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:exists cxid:0x10689 zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/config/table.compaction.minor.idle 2016-09-23 08:29:53,223 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@197] - Accepted socket connection from /34.45.6.2:60064 2016-09-23 08:29:53,223 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@78] - serviceHostname is 'namenode_1' 2016-09-23 08:29:53,223 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@79] - servicePrincipalName is 'zookeeper' 2016-09-23 08:29:53,223 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperSaslServer@80] - SASL mechanism(mech) is 'GSSAPI' 2016-09-23 08:29:53,224 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@810] - Session establishment request from client /34.45.6.2:60064 client's lastZxid is 0x5b00000c87 2016-09-23 08:29:53,224 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@861] - Client attempting to renew session 0x3574a76d3ff0007 at /34.45.6.2:60064 2016-09-23 08:29:53,224 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:Learner@108] - Revalidating client: 0x3574a76d3ff0007 2016-09-23 08:29:53,224 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x1068a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/conf/table.compaction.minor.idle 2016-09-23 08:29:53,224 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x1068a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/conf/table.compaction.minor.idle 2016-09-23 08:29:53,224 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:exists cxid:0x1068a zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/conf/table.compaction.minor.idle 2016-09-23 08:29:53,224 - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@617] - Established session 0x3574a76d3ff0007 with negotiated timeout 30000 for client /34.45.6.2:60064 2016-09-23 08:29:53,224 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x1068b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,224 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x1068b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,224 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:exists cxid:0x1068b zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,225 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:getData cxid:0x1068c zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,225 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:53,225 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 562 2016-09-23 08:29:53,226 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:getData cxid:0x1068c zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,226 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:getData cxid:0x1068c zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/tables/!0/namespace 2016-09-23 08:29:53,228 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@984] - Size of server SASL response: 65 2016-09-23 08:29:53,228 - DEBUG [FollowerRequestProcessor:2:CommitProcessor@171] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x1068d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/namespaces/+accumulo/conf/table.compaction.minor.idle 2016-09-23 08:29:53,228 - DEBUG [CommitProcessor:2:FinalRequestProcessor@88] - Processing request:: sessionid:0x157272714860003 type:exists cxid:0x1068d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/namespaces/+accumulo/conf/table.compaction.minor.idle 2016-09-23 08:29:53,228 - DEBUG [CommitProcessor:2:FinalRequestProcessor@160] - sessionid:0x157272714860003 type:exists cxid:0x1068d zxid:0xfffffffffffffffe txntype:unknown reqpath:/accumulo/a691b877-b2e4-4f24-be57-d380e78b061c/namespaces/+accumulo/conf/table.compaction.minor.idle 2016-09-23 08:29:53,228 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@949] - Responding to client SASL token. 2016-09-23 08:29:53,228 - DEBUG [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@953] - Size of client SASL token: 97