Assertion failed for sliding window in rUDP

[Fraser999]

Issue migrated from maidsafe-archive/MaidSafe#103

Assertion failed for IsFull() in sliding window for rUDP/core. This behaviour pop up while running test case SendGroup in APIs tests.

===================Crash traces==============

Assertion failed: !IsFull(), file C:\MaidSafe\src\rudp\src\maidsafe/rudp/core/sl
iding_window.h, line 96
Assertion failed: !IsFull(), file C:\MaidSafe\src\rudp\src\maidsafe/rudp/core/sl
iding_window.h, line 96

[Fraser999]

Routing Traces:

Repeating all tests (iteration 1) . . .

Note: Google Test filter = *APITest.BEH_API_SendGroup
[==========] Running 1 test from 1 test case.
[----------] Global test environment set-up.
[==========] 1 test from APITest
[ RUN      ] APITest.BEH_API_SendGroup
I 1 11:00:18.263195 routing/routing_impl.cc:78] non-client node. Id : ecf84b..d61b3c
I 1 11:00:18.918386 routing/routing_impl.cc:78] non-client node. Id : fd6785..dbd749
I 1 11:00:19.635997 routing/routing_impl.cc:78] non-client node. Id : 4896e3..a74675
I 1 11:00:20.181999 routing/routing_impl.cc:78] non-client node. Id : ec891d..c315f8
I 1 11:00:20.618806 routing/routing_impl.cc:78] non-client node. Id : 852d46..e79bea
I 1 11:00:21.102429 routing/routing_impl.cc:78] non-client node. Id : 092808..68b383
I 1 11:00:22.163227 routing/routing_impl.cc:78] non-client node. Id : 71a9ec..1074fd
I 1 11:00:22.615648 routing/routing_impl.cc:78] non-client node. Id : 826320..b16b0a
I 1 11:00:23.052434 routing/routing_impl.cc:78] non-client node. Id : f2ab31..ad066b
I 1 11:00:23.348841 routing/routing_impl.cc:78] non-client node. Id : b4f0d9..135354
I 3 11:00:23.379997 routing/network_utils.cc:115] Bootstrap successful, bootstrap connection id - ecf84b..d61b3c
I 2 11:00:23.379997 routing/network_utils.cc:115] Bootstrap successful, bootstrap connection id - fd6785..dbd749
I 3 11:00:23.379997 routing/routing_impl.cc:277] [fd6785..dbd749]'s bootstrap connection id : ecf84b..d61b3c
I 2 11:00:23.379997 routing/routing_impl.cc:277] [ecf84b..d61b3c]'s bootstrap connection id : fd6785..dbd749
V 2 11:00:23.379997 routing/routing_impl.cc:283] 192.168.0.118:31022 Bootstrapped with remote endpoint 192.168.0.118:33565
V 3 11:00:23.379997 routing/routing_impl.cc:283] 192.168.0.118:33565 Bootstrapped with remote endpoint 192.168.0.118:31022
V 3 11:00:23.483207 routing/group_matrix.cc:36] AddConnectedPeer : ecf84b..d61b3c
V 2 11:00:23.483207 routing/group_matrix.cc:36] AddConnectedPeer : fd6785..dbd749
V 3 11:00:23.487208 routing/routing_table.cc:868] fd6785..dbd749 Updating network status !!! 1
V 2 11:00:23.487208 routing/routing_table.cc:868] ecf84b..d61b3c Updating network status !!! 1
V 3 11:00:23.489208 routing/group_change_handler.cc:177] [fd6785..dbd749] SendClosestNodesUpdateRpcs: 1
V 2 11:00:23.489208 routing/group_change_handler.cc:177] [ecf84b..d61b3c] SendClosestNodesUpdateRpcs: 1
V 3 11:00:23.492208 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 1
V 2 11:00:23.492208 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 1
V 3 11:00:23.494209 routing/group_change_handler.cc:207] fd6785..dbd749 SendSubscribeRpc to ecf84b..d61b3c
V 2 11:00:23.494209 routing/group_change_handler.cc:207] ecf84b..d61b3c SendSubscribeRpc to fd6785..dbd749
V 3 11:00:23.496221 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 2 11:00:23.497209 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id fd6785..dbd749
V 2 11:00:23.499210 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 465) --To Rudp--
V 3 11:00:23.499210 routing/network_utils.cc:178]   [fd6785..dbd749] send : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 1940) --To Rudp--
I 3 11:00:23.503210 routing/routing_table.cc:173] 

[fd6785..dbd749] This node's own routing table and peer connections:
Routing table size: 1
    Peer [ecf84b..d61b3c]-->ecf84b..d61b3c && xored 119fce..0dcc75 bucket 508



I 2 11:00:23.503210 routing/routing_table.cc:173] 

[ecf84b..d61b3c] This node's own routing table and peer connections:
Routing table size: 1
    Peer [fd6785..dbd749]-->fd6785..dbd749 && xored 119fce..0dcc75 bucket 508



V 2 11:00:23.507210 routing/utils.cc:95] [ecf84b..d61b3c] added node to routing table.  Node ID: fd6785..dbd749
V 3 11:00:23.507210 routing/utils.cc:95] [fd6785..dbd749] added node to routing table.  Node ID: ecf84b..d61b3c
W 4 11:00:23.514211 routing/routing_impl.cc:522] Message received, failed to parse
W 5 11:00:23.514211 routing/routing_impl.cc:522] Message received, failed to parse
V 6 11:00:23.515211 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 465)
V 7 11:00:23.515211 routing/network_utils.cc:258]   [fd6785..dbd749] sent : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 1940)
V 4 11:00:23.524275 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kCloses_Nodes_Update_Subscribe Req from ecf84b..d61b3c to fd6785..dbd749   (id: 465)
V 5 11:00:23.525270 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kCloses_Nodes_Update_Subscribe Req from fd6785..dbd749 to ecf84b..d61b3c   (id: 1940)
V 4 11:00:23.526262 routing/message_handler.cc:174] Message for this node. id: 465
V 5 11:00:23.527226 routing/message_handler.cc:174] Message for this node. id: 1940
V 4 11:00:23.528216 routing/group_change_handler.cc:125] [fd6785..dbd749] subscribing ecf84b..d61b3c
V 5 11:00:23.528216 routing/group_change_handler.cc:125] [ecf84b..d61b3c] subscribing fd6785..dbd749
I 3 11:00:23.609322 routing/routing_impl.cc:316] Node Successfully joined zero state network, with ecf84b..d61b3c, Routing table size - 1, Node id : fd6785..dbd749
I 2 11:00:23.609322 routing/routing_impl.cc:316] Node Successfully joined zero state network, with fd6785..dbd749, Routing table size - 1, Node id : ecf84b..d61b3c
I 1 11:00:23.612282 routing/routing_impl.cc:142] Doing a BootstrapFromTheseEndpoints Join.  Entered first bootstrap endpoint: 192.168.0.118:31022, this node's ID: 4896e3..a74675
I 1 11:00:23.661261 routing/network_utils.cc:115] Bootstrap successful, bootstrap connection id - ecf84b..d61b3c
V 1 11:00:23.662231 routing/rpcs.cc:157] FindNodes RPC has relay connection id 4896e3..a74675
V 1 11:00:23.662231 routing/routing_impl.cc:230]    [4896e3..a74675] (attempt 0) requesting 1 nodes   (id: 6661)
V 1 11:00:23.663271 routing/network_utils.cc:178]   [4896e3..a74675] send : kFindNodes Req to   ecf84b..d61b3c   (id: 6661) --To Rudp--
V 5 11:00:23.670231 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kFindNodes Req from 4896e3..a74675 to 4896e3..a74675   (id: 6661) --Relay--
V 5 11:00:23.671232 routing/group_matrix.cc:136] [ecf84b..d61b3c]   target: 4896e3..a74675  found node in matrix: ecf84b..d61b3c    reccommend sending to: ecf84b..d61b3c
I 5 11:00:23.672232 routing/message_handler.cc:507] Group members for group_id 4896e3..a74675 are: [ecf84b..d61b3c]
V 5 11:00:23.672232 routing/service.cc:240] [ecf84b..d61b3c] parsed find node request for target id : 4896e3..a74675
V 5 11:00:23.673233 routing/service.cc:251] Responding Find node with 1 contacts.
V 5 11:00:23.673233 routing/service.cc:261] Relay message, so not setting destination ID.
V 8 11:00:23.674233 routing/routing_impl.cc:237]    [4896e3..a74675] sent : kFindNodes Req to   ecf84b..d61b3c   (id: 6661)
V 5 11:00:23.675232 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 5 11:00:23.675232 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kFindNodes Res to   4896e3..a74675   (id: 6661) --To Rudp--
V 9 11:00:23.683290 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kFindNodes Res from ecf84b..d61b3c to 4896e3..a74675   (id: 6661)
V 9 11:00:23.684232 routing/message_handler.cc:174] Message for this node. id: 6661
V 9 11:00:23.684232 routing/response_handler.cc:179] [4896e3..a74675] received FindNodes response from ecf84b..d61b3c id: 6661
V 9 11:00:23.685273 routing/response_handler.cc:188] FindNodes from ecf84b..d61b3c returned :
[ecf84b..d61b3c]    
V 9 11:00:23.685273 routing/response_handler.cc:212] CheckNode succeeded for node ecf84b..d61b3c
V 9 11:00:23.686275 routing/rpcs.cc:92] Connect RPC has relay connection id 4896e3..a74675
V 9 11:00:23.687274 routing/response_handler.cc:255] Sending Connect RPC to ecf84b..d61b3c message id : 1610
V 6 11:00:23.687274 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kFindNodes Res to   4896e3..a74675   (id: 6661)
V 9 11:00:23.688287 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 9 11:00:23.688287 routing/network_utils.cc:178]   [4896e3..a74675] send : kConnect   Req to   ecf84b..d61b3c   (id: 1610) --To Rudp--
V 5 11:00:23.696293 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kConnect   Req from 4896e3..a74675 to ecf84b..d61b3c   (id: 1610) --Relay--
V 5 11:00:23.696293 routing/message_handler.cc:414] Relay request with this node's ID as destination ID id: 1610
V 5 11:00:23.697275 routing/message_handler.cc:174] Message for this node. id: 1610
V 5 11:00:23.698238 routing/service.cc:109] [ecf84b..d61b3c] received Connect request from 4896e3..a74675
V 5 11:00:23.698238 routing/service.cc:152] Server connect request - will check routing table.
V 5 11:00:23.699238 routing/service.cc:157] CheckNode(node) for server node succeeded.
V 5 11:00:23.700234 routing/utils.cc:44] AddToRudp. peer_id : 4896e3..a74675 , connection id : 4896e3..a74675
V 10    11:00:23.700234 routing/network_utils.cc:258]   [4896e3..a74675] sent : kConnect   Req to   ecf84b..d61b3c   (id: 1610)
V 5 11:00:23.701235 routing/utils.cc:54] rudp.Add succeeded for peer node [4896e3..a74675]. Connection id : 4896e3..a74675
V 5 11:00:23.702235 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 5 11:00:23.703236 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kConnect   Res to   4896e3..a74675   (id: 1610) --To Rudp--
V 9 11:00:23.708235 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kC-Success Req from ecf84b..d61b3c to 4896e3..a74675   (id: 940)
V 9 11:00:23.708235 routing/message_handler.cc:174] Message for this node. id: 940
V 9 11:00:23.709235 routing/service.cc:303] ConnectSuccessFromResponder peer id : ecf84b..d61b3c
V 9 11:00:23.710237 routing/service.cc:305] Special case : kConnectSuccess from bootstrapping node: ecf84b..d61b3c
V 11    11:00:23.710237 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kConnect   Res from ecf84b..d61b3c to 4896e3..a74675   (id: 1610)
V 6 11:00:23.711236 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kConnect   Res to   4896e3..a74675   (id: 1610)
V 11    11:00:23.711236 routing/message_handler.cc:174] Message for this node. id: 1610
V 11    11:00:23.712282 routing/response_handler.cc:120] This node [4896e3..a74675] received connect response from ecf84b..d61b3c id: 1610
V 11    11:00:23.713264 routing/utils.cc:44] AddToRudp. peer_id : ecf84b..d61b3c , connection id : ecf84b..d61b3c
V 11    11:00:23.714236 routing/utils.cc:54] rudp.Add succeeded for peer node [ecf84b..d61b3c]. Connection id : ecf84b..d61b3c
I 11    11:00:23.714236 routing/response_handler.cc:135] Special case with bootstrapping peer : ecf84b..d61b3c
V 11    11:00:23.715236 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 11    11:00:23.716237 routing/network_utils.cc:178]   [4896e3..a74675] send : kC-Suc-Ack Res to   ecf84b..d61b3c   (id: 8178) --To Rudp--
V 5 11:00:23.721238 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kC-Success Req from 4896e3..a74675 to ecf84b..d61b3c   (id: 1787)
V 5 11:00:23.722238 routing/message_handler.cc:174] Message for this node. id: 1787
V 5 11:00:23.724239 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kC-Suc-Ack Res from 4896e3..a74675 to ecf84b..d61b3c   (id: 8178)
V 8 11:00:23.724239 routing/network_utils.cc:258]   [4896e3..a74675] sent : kC-Suc-Ack Res to   ecf84b..d61b3c   (id: 8178)
V 5 11:00:23.724239 routing/message_handler.cc:174] Message for this node. id: 8178
I 5 11:00:23.725239 routing/response_handler.cc:298] Validation -- Need non-client's public key
W 5 11:00:23.726288 routing/tests/routing_api_test.cc:679] node_validation called for 4896e3..a74675
I 5 11:00:23.726288 routing/response_handler.cc:333] Validation callback called with public key for 4896e3..a74675
V 5 11:00:23.727239 routing/group_matrix.cc:36] AddConnectedPeer : 4896e3..a74675
V 5 11:00:23.728239 routing/routing_table.cc:868] ecf84b..d61b3c Updating network status !!! 3
V 5 11:00:23.729241 routing/group_change_handler.cc:177] [ecf84b..d61b3c] SendClosestNodesUpdateRpcs: 2
V 5 11:00:23.729241 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 2
V 5 11:00:23.730240 routing/group_change_handler.cc:207] ecf84b..d61b3c SendSubscribeRpc to fd6785..dbd749
V 5 11:00:23.730240 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id fd6785..dbd749
V 5 11:00:23.731240 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 4476) --To Rudp--
V 5 11:00:23.732240 routing/group_change_handler.cc:207] ecf84b..d61b3c SendSubscribeRpc to 4896e3..a74675
V 5 11:00:23.733240 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 5 11:00:23.733240 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kCloses_Nodes_Update_Subscribe Req to   4896e3..a74675   (id: 7608) --To Rudp--
I 5 11:00:23.734240 routing/routing_table.cc:173] 

[ecf84b..d61b3c] This node's own routing table and peer connections:
Routing table size: 2
    Peer [fd6785..dbd749]-->fd6785..dbd749 && xored 119fce..0dcc75 bucket 508
    Peer [4896e3..a74675]-->4896e3..a74675 && xored a46ea8..715d49 bucket 511



V 5 11:00:23.735240 routing/utils.cc:95] [ecf84b..d61b3c] added node to routing table.  Node ID: 4896e3..a74675
V 5 11:00:23.736240 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 5 11:00:23.736240 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kC-Suc-Ack Res to   4896e3..a74675   (id: 9429) --To Rudp--
V 6 11:00:23.738241 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kCloses_Nodes_Update_Subscribe Req to   4896e3..a74675   (id: 7608)
V 6 11:00:23.739262 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kC-Suc-Ack Res to   4896e3..a74675   (id: 9429)
V 12    11:00:23.740240 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kCloses_Nodes_Update_Subscribe Req from ecf84b..d61b3c to fd6785..dbd749   (id: 4476)
V 12    11:00:23.741241 routing/message_handler.cc:174] Message for this node. id: 4476
V 12    11:00:23.741241 routing/group_change_handler.cc:125] [fd6785..dbd749] subscribing ecf84b..d61b3c
V 11    11:00:23.741241 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kCloses_Nodes_Update_Subscribe Req from ecf84b..d61b3c to 4896e3..a74675   (id: 7608)
V 11    11:00:23.742240 routing/message_handler.cc:174] Message for this node. id: 7608
V 11    11:00:23.743241 routing/group_change_handler.cc:125] [4896e3..a74675] subscribing ecf84b..d61b3c
V 11    11:00:23.744241 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kC-Suc-Ack Res from ecf84b..d61b3c to 4896e3..a74675   (id: 9429)
V 11    11:00:23.745240 routing/message_handler.cc:174] Message for this node. id: 9429
I 11    11:00:23.746240 routing/response_handler.cc:298] Validation -- Need non-client's public key
V 6 11:00:23.746240 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 4476)
W 11    11:00:23.747240 routing/tests/routing_api_test.cc:679] node_validation called for ecf84b..d61b3c
I 11    11:00:23.747240 routing/response_handler.cc:333] Validation callback called with public key for ecf84b..d61b3c
V 11    11:00:23.748241 routing/group_matrix.cc:36] AddConnectedPeer : ecf84b..d61b3c
V 11    11:00:23.749265 routing/routing_table.cc:868] 4896e3..a74675 Updating network status !!! 1
V 11    11:00:23.749265 routing/group_change_handler.cc:177] [4896e3..a74675] SendClosestNodesUpdateRpcs: 1
V 11    11:00:23.750241 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 1
V 11    11:00:23.751259 routing/group_change_handler.cc:207] 4896e3..a74675 SendSubscribeRpc to ecf84b..d61b3c
V 11    11:00:23.751259 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 11    11:00:23.752241 routing/network_utils.cc:178]   [4896e3..a74675] send : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 7367) --To Rudp--
I 11    11:00:23.753241 routing/routing_table.cc:173] 

[4896e3..a74675] This node's own routing table and peer connections:
Routing table size: 1
    Peer [ecf84b..d61b3c]-->ecf84b..d61b3c && xored a46ea8..715d49 bucket 511



V 11    11:00:23.754241 routing/utils.cc:95] [4896e3..a74675] added node to routing table.  Node ID: ecf84b..d61b3c
V 11    11:00:23.754241 routing/response_handler.cc:212] CheckNode succeeded for node fd6785..dbd749
V 11    11:00:23.758242 routing/rpcs.cc:92] Connect RPC has relay connection id 4896e3..a74675
V 11    11:00:23.759242 routing/response_handler.cc:255] Sending Connect RPC to fd6785..dbd749 message id : 9331
V 11    11:00:23.760242 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 11    11:00:23.760242 routing/network_utils.cc:178]   [4896e3..a74675] send : kConnect   Req to   ecf84b..d61b3c   (id: 9331) --To Rudp--
V 5 11:00:23.760242 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kCloses_Nodes_Update_Subscribe Req from 4896e3..a74675 to ecf84b..d61b3c   (id: 7367)
V 5 11:00:23.762243 routing/message_handler.cc:174] Message for this node. id: 7367
V 5 11:00:23.762243 routing/group_change_handler.cc:125] [ecf84b..d61b3c] subscribing 4896e3..a74675
V 8 11:00:23.763243 routing/network_utils.cc:258]   [4896e3..a74675] sent : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 7367)
V 8 11:00:23.764243 routing/network_utils.cc:258]   [4896e3..a74675] sent : kConnect   Req to   ecf84b..d61b3c   (id: 9331)
V 5 11:00:23.768243 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kConnect   Req from 4896e3..a74675 to fd6785..dbd749   (id: 9331) --Relay--
V 5 11:00:23.769244 routing/routing_table.cc:694] [ecf84b..d61b3c] - best node to send to is fd6785..dbd749 (Excluded: )
V 5 11:00:23.770244 routing/network_utils.cc:370] Rudp recursive send message to fd6785..dbd749
V 5 11:00:23.770244 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kConnect   Req to   fd6785..dbd749   (id: 9331) --To Rudp--
V 12    11:00:23.778247 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kConnect   Req from ecf84b..d61b3c to fd6785..dbd749   (id: 9331)
V 12    11:00:23.778247 routing/message_handler.cc:174] Message for this node. id: 9331
V 12    11:00:23.779245 routing/service.cc:109] [fd6785..dbd749] received Connect request from 4896e3..a74675
V 12    11:00:23.779245 routing/service.cc:152] Server connect request - will check routing table.
V 12    11:00:23.780245 routing/service.cc:157] CheckNode(node) for server node succeeded.
V 12    11:00:23.784245 routing/utils.cc:44] AddToRudp. peer_id : 4896e3..a74675 , connection id : 4896e3..a74675
V 12    11:00:23.785288 routing/utils.cc:54] rudp.Add succeeded for peer node [4896e3..a74675]. Connection id : 4896e3..a74675
V 12    11:00:23.786263 routing/routing_table.cc:694] [fd6785..dbd749] - best node to send to is ecf84b..d61b3c (Excluded: )
V 6 11:00:23.786263 routing/network_utils.cc:338]   [ecf84b..d61b3c] sent : kConnect   Req to   fd6785..dbd749   (id: 9331) dst : fd6785..dbd749
V 12    11:00:23.787250 routing/network_utils.cc:370] Rudp recursive send message to ecf84b..d61b3c
V 12    11:00:23.788290 routing/network_utils.cc:178]   [fd6785..dbd749] send : kConnect   Res to   ecf84b..d61b3c   (id: 9331) --To Rudp--
V 5 11:00:23.795247 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kConnect   Res from fd6785..dbd749 to ecf84b..d61b3c   (id: 9331)
V 5 11:00:23.796264 routing/message_handler.cc:550] Relaying response to 4896e3..a74675 id: 9331
V 5 11:00:23.796264 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 5 11:00:23.797248 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kConnect   Res to   4896e3..a74675   (id: 9331) --To Rudp--
V 13    11:00:23.803247 routing/network_utils.cc:338]   [fd6785..dbd749] sent : kConnect   Res to   ecf84b..d61b3c   (id: 9331) dst : ecf84b..d61b3c
V 11    11:00:23.805265 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kConnect   Res from fd6785..dbd749 to 4896e3..a74675   (id: 9331)
V 11    11:00:23.805265 routing/message_handler.cc:174] Message for this node. id: 9331
V 11    11:00:23.806248 routing/response_handler.cc:120] This node [4896e3..a74675] received connect response from fd6785..dbd749 id: 9331
V 11    11:00:23.807249 routing/utils.cc:44] AddToRudp. peer_id : fd6785..dbd749 , connection id : fd6785..dbd749
V 11    11:00:23.808248 routing/utils.cc:54] rudp.Add succeeded for peer node [fd6785..dbd749]. Connection id : fd6785..dbd749
V 6 11:00:23.809265 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kConnect   Res to   4896e3..a74675   (id: 9331)
V 12    11:00:23.819257 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kC-Success Req from 4896e3..a74675 to fd6785..dbd749   (id: 828)
V 11    11:00:23.819257 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kC-Success Req from fd6785..dbd749 to 4896e3..a74675   (id: 9752)
V 12    11:00:23.820264 routing/message_handler.cc:174] Message for this node. id: 828
V 11    11:00:23.820264 routing/message_handler.cc:174] Message for this node. id: 9752
V 11    11:00:23.821250 routing/service.cc:303] ConnectSuccessFromResponder peer id : fd6785..dbd749
V 11    11:00:23.822250 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id fd6785..dbd749
V 11    11:00:23.822250 routing/network_utils.cc:178]   [4896e3..a74675] send : kC-Suc-Ack Res to   fd6785..dbd749   (id: 8720) --To Rudp--
V 12    11:00:23.830267 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kC-Suc-Ack Res from 4896e3..a74675 to fd6785..dbd749   (id: 8720)
V 12    11:00:23.830267 routing/message_handler.cc:174] Message for this node. id: 8720
I 12    11:00:23.831291 routing/response_handler.cc:298] Validation -- Need non-client's public key
W 12    11:00:23.831291 routing/tests/routing_api_test.cc:679] node_validation called for 4896e3..a74675
I 12    11:00:23.832295 routing/response_handler.cc:333] Validation callback called with public key for 4896e3..a74675
V 8 11:00:23.833273 routing/network_utils.cc:258]   [4896e3..a74675] sent : kC-Suc-Ack Res to   fd6785..dbd749   (id: 8720)
V 12    11:00:23.833273 routing/group_matrix.cc:36] AddConnectedPeer : 4896e3..a74675
V 12    11:00:23.834327 routing/routing_table.cc:868] fd6785..dbd749 Updating network status !!! 3
V 12    11:00:23.835293 routing/group_change_handler.cc:177] [fd6785..dbd749] SendClosestNodesUpdateRpcs: 2
V 12    11:00:23.835293 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 2
V 12    11:00:23.836294 routing/group_change_handler.cc:207] fd6785..dbd749 SendSubscribeRpc to ecf84b..d61b3c
V 12    11:00:23.837295 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 12    11:00:23.837295 routing/network_utils.cc:178]   [fd6785..dbd749] send : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 5687) --To Rudp--
V 12    11:00:23.838297 routing/group_change_handler.cc:207] fd6785..dbd749 SendSubscribeRpc to 4896e3..a74675
V 12    11:00:23.839253 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 12    11:00:23.840254 routing/network_utils.cc:178]   [fd6785..dbd749] send : kCloses_Nodes_Update_Subscribe Req to   4896e3..a74675   (id: 9418) --To Rudp--
I 12    11:00:23.840254 routing/routing_table.cc:173] 

[fd6785..dbd749] This node's own routing table and peer connections:
Routing table size: 2
    Peer [ecf84b..d61b3c]-->ecf84b..d61b3c && xored 119fce..0dcc75 bucket 508
    Peer [4896e3..a74675]-->4896e3..a74675 && xored b5f166..7c913c bucket 511



V 12    11:00:23.841253 routing/utils.cc:95] [fd6785..dbd749] added node to routing table.  Node ID: 4896e3..a74675
V 12    11:00:23.842253 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 12    11:00:23.843254 routing/network_utils.cc:178]   [fd6785..dbd749] send : kC-Suc-Ack Res to   4896e3..a74675   (id: 1975) --To Rudp--
V 5 11:00:23.845254 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kCloses_Nodes_Update_Subscribe Req from fd6785..dbd749 to ecf84b..d61b3c   (id: 5687)
V 5 11:00:23.846254 routing/message_handler.cc:174] Message for this node. id: 5687
V 5 11:00:23.847255 routing/group_change_handler.cc:125] [ecf84b..d61b3c] subscribing fd6785..dbd749
V 11    11:00:23.848315 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kCloses_Nodes_Update_Subscribe Req from fd6785..dbd749 to 4896e3..a74675   (id: 9418)
V 11    11:00:23.849260 routing/message_handler.cc:174] Message for this node. id: 9418
V 11    11:00:23.849260 routing/group_change_handler.cc:125] [4896e3..a74675] subscribing fd6785..dbd749
V 7 11:00:23.851263 routing/network_utils.cc:258]   [fd6785..dbd749] sent : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 5687)
V 13    11:00:23.851263 routing/network_utils.cc:258]   [fd6785..dbd749] sent : kCloses_Nodes_Update_Subscribe Req to   4896e3..a74675   (id: 9418)
V 11    11:00:23.851263 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kC-Suc-Ack Res from fd6785..dbd749 to 4896e3..a74675   (id: 1975)
V 13    11:00:23.853254 routing/network_utils.cc:258]   [fd6785..dbd749] sent : kC-Suc-Ack Res to   4896e3..a74675   (id: 1975)
V 11    11:00:23.853254 routing/message_handler.cc:174] Message for this node. id: 1975
I 11    11:00:23.854254 routing/response_handler.cc:298] Validation -- Need non-client's public key
W 11    11:00:23.854254 routing/tests/routing_api_test.cc:679] node_validation called for fd6785..dbd749
I 11    11:00:23.855254 routing/response_handler.cc:333] Validation callback called with public key for fd6785..dbd749
V 11    11:00:23.856254 routing/group_matrix.cc:36] AddConnectedPeer : fd6785..dbd749
V 11    11:00:23.857254 routing/tests/routing_api_test.cc:737] node - 2joined
I 1 11:00:23.857254 routing/routing_impl.cc:142] Doing a BootstrapFromTheseEndpoints Join.  Entered first bootstrap endpoint: 192.168.0.118:31022, this node's ID: ec891d..c315f8
V 11    11:00:23.858255 routing/routing_table.cc:868] 4896e3..a74675 Updating network status !!! 3
V 11    11:00:23.858255 routing/group_change_handler.cc:177] [4896e3..a74675] SendClosestNodesUpdateRpcs: 2
V 11    11:00:23.859255 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 2
V 11    11:00:23.860255 routing/group_change_handler.cc:207] 4896e3..a74675 SendSubscribeRpc to ecf84b..d61b3c
V 11    11:00:23.861255 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 11    11:00:23.861255 routing/network_utils.cc:178]   [4896e3..a74675] send : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 606) --To Rudp--
V 11    11:00:23.862255 routing/group_change_handler.cc:207] 4896e3..a74675 SendSubscribeRpc to fd6785..dbd749
V 11    11:00:23.863255 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id fd6785..dbd749
V 11    11:00:23.864256 routing/network_utils.cc:178]   [4896e3..a74675] send : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 6119) --To Rudp--
I 11    11:00:23.865256 routing/routing_table.cc:173] 

[4896e3..a74675] This node's own routing table and peer connections:
Routing table size: 2
    Peer [ecf84b..d61b3c]-->ecf84b..d61b3c && xored a46ea8..715d49 bucket 511
    Peer [fd6785..dbd749]-->fd6785..dbd749 && xored b5f166..7c913c bucket 511



V 11    11:00:23.866255 routing/utils.cc:95] [4896e3..a74675] added node to routing table.  Node ID: fd6785..dbd749
V 11    11:00:23.867256 routing/routing_table.cc:115] Node ecf84b..d61b3c already in routing table.
V 5 11:00:23.870299 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kCloses_Nodes_Update_Subscribe Req from 4896e3..a74675 to ecf84b..d61b3c   (id: 606)
V 5 11:00:23.870299 routing/message_handler.cc:174] Message for this node. id: 606
V 5 11:00:23.871297 routing/group_change_handler.cc:125] [ecf84b..d61b3c] subscribing 4896e3..a74675
V 12    11:00:23.872272 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kCloses_Nodes_Update_Subscribe Req from 4896e3..a74675 to fd6785..dbd749   (id: 6119)
V 12    11:00:23.873312 routing/message_handler.cc:174] Message for this node. id: 6119
V 10    11:00:23.873312 routing/network_utils.cc:258]   [4896e3..a74675] sent : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 606)
V 12    11:00:23.874257 routing/group_change_handler.cc:125] [fd6785..dbd749] subscribing 4896e3..a74675
V 8 11:00:23.875259 routing/network_utils.cc:258]   [4896e3..a74675] sent : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 6119)
I 1 11:00:23.885258 routing/network_utils.cc:115] Bootstrap successful, bootstrap connection id - ecf84b..d61b3c
V 1 11:00:23.886260 routing/rpcs.cc:157] FindNodes RPC has relay connection id ec891d..c315f8
V 1 11:00:23.887283 routing/routing_impl.cc:230]    [ec891d..c315f8] (attempt 0) requesting 1 nodes   (id: 2437)
V 1 11:00:23.887283 routing/network_utils.cc:178]   [ec891d..c315f8] send : kFindNodes Req to   ecf84b..d61b3c   (id: 2437) --To Rudp--
V 14    11:00:23.889258 routing/routing_impl.cc:237]    [ec891d..c315f8] sent : kFindNodes Req to   ecf84b..d61b3c   (id: 2437)
V 5 11:00:23.895304 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kFindNodes Req from ec891d..c315f8 to ec891d..c315f8   (id: 2437) --Relay--
V 5 11:00:23.896261 routing/group_matrix.cc:136] [ecf84b..d61b3c]   target: ec891d..c315f8  found node in matrix: ecf84b..d61b3c    reccommend sending to: ecf84b..d61b3c
I 5 11:00:23.897261 routing/message_handler.cc:507] Group members for group_id ec891d..c315f8 are: [ecf84b..d61b3c]
V 5 11:00:23.897261 routing/service.cc:240] [ecf84b..d61b3c] parsed find node request for target id : ec891d..c315f8
V 5 11:00:23.898276 routing/service.cc:251] Responding Find node with 1 contacts.
V 5 11:00:23.898276 routing/service.cc:261] Relay message, so not setting destination ID.
V 5 11:00:23.899261 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 5 11:00:23.900262 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kFindNodes Res to   ec891d..c315f8   (id: 2437) --To Rudp--
V 15    11:00:23.907310 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kFindNodes Res from ecf84b..d61b3c to ec891d..c315f8   (id: 2437)
V 15    11:00:23.907310 routing/message_handler.cc:174] Message for this node. id: 2437
V 15    11:00:23.908305 routing/response_handler.cc:179] [ec891d..c315f8] received FindNodes response from ecf84b..d61b3c id: 2437
V 15    11:00:23.908305 routing/response_handler.cc:188] FindNodes from ecf84b..d61b3c returned :
[ecf84b..d61b3c]    
V 15    11:00:23.909302 routing/response_handler.cc:212] CheckNode succeeded for node ecf84b..d61b3c
V 15    11:00:23.910297 routing/rpcs.cc:92] Connect RPC has relay connection id ec891d..c315f8
V 15    11:00:23.910297 routing/response_handler.cc:255] Sending Connect RPC to ecf84b..d61b3c message id : 3803
V 15    11:00:23.911304 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 16    11:00:23.911304 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kFindNodes Res to   ec891d..c315f8   (id: 2437)
V 15    11:00:23.912263 routing/network_utils.cc:178]   [ec891d..c315f8] send : kConnect   Req to   ecf84b..d61b3c   (id: 3803) --To Rudp--
V 17    11:00:23.920299 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kConnect   Req from ec891d..c315f8 to ecf84b..d61b3c   (id: 3803) --Relay--
V 17    11:00:23.920299 routing/message_handler.cc:414] Relay request with this node's ID as destination ID id: 3803
V 17    11:00:23.921305 routing/message_handler.cc:174] Message for this node. id: 3803
V 17    11:00:23.922303 routing/service.cc:109] [ecf84b..d61b3c] received Connect request from ec891d..c315f8
V 17    11:00:23.922303 routing/service.cc:152] Server connect request - will check routing table.
V 17    11:00:23.923299 routing/service.cc:157] CheckNode(node) for server node succeeded.
V 17    11:00:23.923299 routing/utils.cc:44] AddToRudp. peer_id : ec891d..c315f8 , connection id : ec891d..c315f8
V 14    11:00:23.924306 routing/network_utils.cc:258]   [ec891d..c315f8] sent : kConnect   Req to   ecf84b..d61b3c   (id: 3803)
V 17    11:00:23.925264 routing/utils.cc:54] rudp.Add succeeded for peer node [ec891d..c315f8]. Connection id : ec891d..c315f8
V 17    11:00:23.925264 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 17    11:00:23.926304 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kConnect   Res to   ec891d..c315f8   (id: 3803) --To Rudp--
V 15    11:00:23.931310 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kC-Success Req from ecf84b..d61b3c to ec891d..c315f8   (id: 9388)
V 15    11:00:23.932311 routing/message_handler.cc:174] Message for this node. id: 9388
V 15    11:00:23.933264 routing/service.cc:303] ConnectSuccessFromResponder peer id : ecf84b..d61b3c
V 15    11:00:23.933264 routing/service.cc:305] Special case : kConnectSuccess from bootstrapping node: ecf84b..d61b3c
V 18    11:00:23.933264 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kConnect   Res from ecf84b..d61b3c to ec891d..c315f8   (id: 3803)
V 18    11:00:23.934308 routing/message_handler.cc:174] Message for this node. id: 3803
V 18    11:00:23.935306 routing/response_handler.cc:120] This node [ec891d..c315f8] received connect response from ecf84b..d61b3c id: 3803
V 18    11:00:23.935306 routing/utils.cc:44] AddToRudp. peer_id : ecf84b..d61b3c , connection id : ecf84b..d61b3c
V 18    11:00:23.936307 routing/utils.cc:54] rudp.Add succeeded for peer node [ecf84b..d61b3c]. Connection id : ecf84b..d61b3c
V 16    11:00:23.937328 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kConnect   Res to   ec891d..c315f8   (id: 3803)
I 18    11:00:23.938266 routing/response_handler.cc:135] Special case with bootstrapping peer : ecf84b..d61b3c
V 18    11:00:23.938266 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 18    11:00:23.939280 routing/network_utils.cc:178]   [ec891d..c315f8] send : kC-Suc-Ack Res to   ecf84b..d61b3c   (id: 7122) --To Rudp--
V 17    11:00:23.945269 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kC-Success Req from ec891d..c315f8 to ecf84b..d61b3c   (id: 9412)
V 17    11:00:23.945269 routing/message_handler.cc:174] Message for this node. id: 9412
V 17    11:00:23.947312 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kC-Suc-Ack Res from ec891d..c315f8 to ecf84b..d61b3c   (id: 7122)
V 17    11:00:23.947312 routing/message_handler.cc:174] Message for this node. id: 7122
I 17    11:00:23.948311 routing/response_handler.cc:298] Validation -- Need non-client's public key
V 19    11:00:23.948311 routing/network_utils.cc:258]   [ec891d..c315f8] sent : kC-Suc-Ack Res to   ecf84b..d61b3c   (id: 7122)
W 17    11:00:23.949266 routing/tests/routing_api_test.cc:679] node_validation called for ec891d..c315f8
I 17    11:00:23.949266 routing/response_handler.cc:333] Validation callback called with public key for ec891d..c315f8
V 17    11:00:23.950308 routing/group_matrix.cc:36] AddConnectedPeer : ec891d..c315f8
V 17    11:00:23.951307 routing/routing_table.cc:868] ecf84b..d61b3c Updating network status !!! 4
V 17    11:00:23.952308 routing/group_change_handler.cc:177] [ecf84b..d61b3c] SendClosestNodesUpdateRpcs: 3
V 17    11:00:23.952308 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 3
V 17    11:00:23.953311 routing/group_change_handler.cc:207] ecf84b..d61b3c SendSubscribeRpc to ec891d..c315f8
V 17    11:00:23.954280 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 17    11:00:23.954280 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kCloses_Nodes_Update_Subscribe Req to   ec891d..c315f8   (id: 8638) --To Rudp--
V 17    11:00:23.955317 routing/group_change_handler.cc:207] ecf84b..d61b3c SendSubscribeRpc to fd6785..dbd749
V 17    11:00:23.956268 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id fd6785..dbd749
V 17    11:00:23.957267 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 6560) --To Rudp--
V 17    11:00:23.957267 routing/group_change_handler.cc:207] ecf84b..d61b3c SendSubscribeRpc to 4896e3..a74675
V 6 11:00:23.958268 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 6560)
V 17    11:00:23.959267 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 17    11:00:23.960268 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kCloses_Nodes_Update_Subscribe Req to   4896e3..a74675   (id: 6487) --To Rudp--
I 17    11:00:23.961268 routing/routing_table.cc:173] 

[ecf84b..d61b3c] This node's own routing table and peer connections:
Routing table size: 3
    Peer [ec891d..c315f8]-->ec891d..c315f8 && xored 007156..150ec4 bucket 502
    Peer [fd6785..dbd749]-->fd6785..dbd749 && xored 119fce..0dcc75 bucket 508
    Peer [4896e3..a74675]-->4896e3..a74675 && xored a46ea8..715d49 bucket 511



V 17    11:00:23.962268 routing/utils.cc:95] [ecf84b..d61b3c] added node to routing table.  Node ID: ec891d..c315f8
V 17    11:00:23.963268 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 18    11:00:23.963268 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kCloses_Nodes_Update_Subscribe Req from ecf84b..d61b3c to ec891d..c315f8   (id: 8638)
V 17    11:00:23.964268 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kC-Suc-Ack Res to   ec891d..c315f8   (id: 1817) --To Rudp--
V 18    11:00:23.964268 routing/message_handler.cc:174] Message for this node. id: 8638
V 18    11:00:23.965268 routing/group_change_handler.cc:125] [ec891d..c315f8] subscribing ecf84b..d61b3c
V 12    11:00:23.966270 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kCloses_Nodes_Update_Subscribe Req from ecf84b..d61b3c to fd6785..dbd749   (id: 6560)
V 6 11:00:23.966270 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kCloses_Nodes_Update_Subscribe Req to   ec891d..c315f8   (id: 8638)
V 12    11:00:23.967270 routing/message_handler.cc:174] Message for this node. id: 6560
V 6 11:00:23.968270 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kC-Suc-Ack Res to   ec891d..c315f8   (id: 1817)
V 12    11:00:23.968270 routing/group_change_handler.cc:125] [fd6785..dbd749] subscribing ecf84b..d61b3c
V 11    11:00:23.970270 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kCloses_Nodes_Update_Subscribe Req from ecf84b..d61b3c to 4896e3..a74675   (id: 6487)
V 11    11:00:23.970270 routing/message_handler.cc:174] Message for this node. id: 6487
V 11    11:00:23.971270 routing/group_change_handler.cc:125] [4896e3..a74675] subscribing ecf84b..d61b3c
V 6 11:00:23.971270 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kCloses_Nodes_Update_Subscribe Req to   4896e3..a74675   (id: 6487)
V 18    11:00:23.972270 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kC-Suc-Ack Res from ecf84b..d61b3c to ec891d..c315f8   (id: 1817)
V 18    11:00:23.973310 routing/message_handler.cc:174] Message for this node. id: 1817
I 18    11:00:23.973310 routing/response_handler.cc:298] Validation -- Need non-client's public key
W 18    11:00:23.974310 routing/tests/routing_api_test.cc:679] node_validation called for ecf84b..d61b3c
I 18    11:00:23.975278 routing/response_handler.cc:333] Validation callback called with public key for ecf84b..d61b3c
V 18    11:00:23.975278 routing/group_matrix.cc:36] AddConnectedPeer : ecf84b..d61b3c
V 18    11:00:23.976310 routing/routing_table.cc:868] ec891d..c315f8 Updating network status !!! 1
V 18    11:00:23.977277 routing/group_change_handler.cc:177] [ec891d..c315f8] SendClosestNodesUpdateRpcs: 1
V 18    11:00:23.977277 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 1
V 18    11:00:23.978311 routing/group_change_handler.cc:207] ec891d..c315f8 SendSubscribeRpc to ecf84b..d61b3c
V 18    11:00:23.978311 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 18    11:00:23.979319 routing/network_utils.cc:178]   [ec891d..c315f8] send : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 5347) --To Rudp--
I 18    11:00:23.980317 routing/routing_table.cc:173] 

[ec891d..c315f8] This node's own routing table and peer connections:
Routing table size: 1
    Peer [ecf84b..d61b3c]-->ecf84b..d61b3c && xored 007156..150ec4 bucket 502



V 18    11:00:23.981272 routing/utils.cc:95] [ec891d..c315f8] added node to routing table.  Node ID: ecf84b..d61b3c
V 18    11:00:23.982289 routing/response_handler.cc:212] CheckNode succeeded for node fd6785..dbd749
V 18    11:00:23.986272 routing/rpcs.cc:92] Connect RPC has relay connection id ec891d..c315f8
V 18    11:00:23.987272 routing/response_handler.cc:255] Sending Connect RPC to fd6785..dbd749 message id : 6708
V 18    11:00:23.987272 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 17    11:00:23.988273 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kCloses_Nodes_Update_Subscribe Req from ec891d..c315f8 to ecf84b..d61b3c   (id: 5347)
V 18    11:00:23.988273 routing/network_utils.cc:178]   [ec891d..c315f8] send : kConnect   Req to   ecf84b..d61b3c   (id: 6708) --To Rudp--
V 18    11:00:23.989275 routing/response_handler.cc:212] CheckNode succeeded for node 4896e3..a74675
V 17    11:00:23.989275 routing/message_handler.cc:174] Message for this node. id: 5347
V 18    11:00:23.990274 routing/rpcs.cc:92] Connect RPC has relay connection id ec891d..c315f8
V 17    11:00:23.990274 routing/group_change_handler.cc:125] [ecf84b..d61b3c] subscribing ec891d..c315f8
V 14    11:00:23.992273 routing/network_utils.cc:258]   [ec891d..c315f8] sent : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 5347)
V 18    11:00:23.992273 routing/response_handler.cc:255] Sending Connect RPC to 4896e3..a74675 message id : 8761
V 14    11:00:23.993273 routing/network_utils.cc:258]   [ec891d..c315f8] sent : kConnect   Req to   ecf84b..d61b3c   (id: 6708)
V 18    11:00:23.993273 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 18    11:00:23.994284 routing/network_utils.cc:178]   [ec891d..c315f8] send : kConnect   Req to   ecf84b..d61b3c   (id: 8761) --To Rudp--
V 17    11:00:23.998299 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kConnect   Req from ec891d..c315f8 to fd6785..dbd749   (id: 6708) --Relay--
V 17    11:00:23.998299 routing/routing_table.cc:694] [ecf84b..d61b3c] - best node to send to is fd6785..dbd749 (Excluded: )
V 17    11:00:23.999316 routing/network_utils.cc:370] Rudp recursive send message to fd6785..dbd749
V 17    11:00:24.000314 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kConnect   Req to   fd6785..dbd749   (id: 6708) --To Rudp--
V 17    11:00:24.002275 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kConnect   Req from ec891d..c315f8 to 4896e3..a74675   (id: 8761) --Relay--
V 17    11:00:24.003317 routing/routing_table.cc:694] [ecf84b..d61b3c] - best node to send to is 4896e3..a74675 (Excluded: )
V 17    11:00:24.003317 routing/network_utils.cc:370] Rudp recursive send message to 4896e3..a74675
V 17    11:00:24.004316 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kConnect   Req to   4896e3..a74675   (id: 8761) --To Rudp--
V 19    11:00:24.006285 routing/network_utils.cc:258]   [ec891d..c315f8] sent : kConnect   Req to   ecf84b..d61b3c   (id: 8761)
V 12    11:00:24.009298 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kConnect   Req from ecf84b..d61b3c to fd6785..dbd749   (id: 6708)
V 12    11:00:24.009298 routing/message_handler.cc:174] Message for this node. id: 6708
V 12    11:00:24.010315 routing/service.cc:109] [fd6785..dbd749] received Connect request from ec891d..c315f8
V 12    11:00:24.010315 routing/service.cc:152] Server connect request - will check routing table.
V 12    11:00:24.011335 routing/service.cc:157] CheckNode(node) for server node succeeded.
V 11    11:00:24.013275 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kConnect   Req from ecf84b..d61b3c to 4896e3..a74675   (id: 8761)
V 11    11:00:24.014297 routing/message_handler.cc:174] Message for this node. id: 8761
V 16    11:00:24.014297 routing/network_utils.cc:338]   [ecf84b..d61b3c] sent : kConnect   Req to   fd6785..dbd749   (id: 6708) dst : fd6785..dbd749
V 11    11:00:24.014297 routing/service.cc:109] [4896e3..a74675] received Connect request from ec891d..c315f8
V 16    11:00:24.016275 routing/network_utils.cc:338]   [ecf84b..d61b3c] sent : kConnect   Req to   4896e3..a74675   (id: 8761) dst : 4896e3..a74675
V 11    11:00:24.016275 routing/service.cc:152] Server connect request - will check routing table.
V 11    11:00:24.017275 routing/service.cc:157] CheckNode(node) for server node succeeded.
V 12    11:00:24.017275 routing/utils.cc:44] AddToRudp. peer_id : ec891d..c315f8 , connection id : ec891d..c315f8
V 12    11:00:24.018275 routing/utils.cc:54] rudp.Add succeeded for peer node [ec891d..c315f8]. Connection id : ec891d..c315f8
V 12    11:00:24.020277 routing/routing_table.cc:694] [fd6785..dbd749] - best node to send to is ecf84b..d61b3c (Excluded: )
V 12    11:00:24.020277 routing/network_utils.cc:370] Rudp recursive send message to ecf84b..d61b3c
V 12    11:00:24.022276 routing/network_utils.cc:178]   [fd6785..dbd749] send : kConnect   Res to   ecf84b..d61b3c   (id: 6708) --To Rudp--
V 11    11:00:24.023302 routing/utils.cc:44] AddToRudp. peer_id : ec891d..c315f8 , connection id : ec891d..c315f8
V 11    11:00:24.025320 routing/utils.cc:54] rudp.Add succeeded for peer node [ec891d..c315f8]. Connection id : ec891d..c315f8
V 11    11:00:24.026280 routing/routing_table.cc:694] [4896e3..a74675] - best node to send to is ecf84b..d61b3c (Excluded: )
V 11    11:00:24.026280 routing/network_utils.cc:370] Rudp recursive send message to ecf84b..d61b3c
V 11    11:00:24.027321 routing/network_utils.cc:178]   [4896e3..a74675] send : kConnect   Res to   ecf84b..d61b3c   (id: 8761) --To Rudp--
V 17    11:00:24.031278 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kConnect   Res from fd6785..dbd749 to ecf84b..d61b3c   (id: 6708)
V 17    11:00:24.032278 routing/message_handler.cc:550] Relaying response to ec891d..c315f8 id: 6708
V 17    11:00:24.033278 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 17    11:00:24.033278 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kConnect   Res to   ec891d..c315f8   (id: 6708) --To Rudp--
V 17    11:00:24.036322 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kConnect   Res from 4896e3..a74675 to ecf84b..d61b3c   (id: 8761)
V 13    11:00:24.036322 routing/network_utils.cc:338]   [fd6785..dbd749] sent : kConnect   Res to   ecf84b..d61b3c   (id: 6708) dst : ecf84b..d61b3c
V 17    11:00:24.037277 routing/message_handler.cc:550] Relaying response to ec891d..c315f8 id: 8761
V 17    11:00:24.037277 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 10    11:00:24.038322 routing/network_utils.cc:338]   [4896e3..a74675] sent : kConnect   Res to   ecf84b..d61b3c   (id: 8761) dst : ecf84b..d61b3c
V 17    11:00:24.038322 routing/network_utils.cc:178]   [ecf84b..d61b3c] send : kConnect   Res to   ec891d..c315f8   (id: 8761) --To Rudp--
V 18    11:00:24.041321 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kConnect   Res from fd6785..dbd749 to ec891d..c315f8   (id: 6708)
V 18    11:00:24.042320 routing/message_handler.cc:174] Message for this node. id: 6708
V 18    11:00:24.043293 routing/response_handler.cc:120] This node [ec891d..c315f8] received connect response from fd6785..dbd749 id: 6708
V 18    11:00:24.043293 routing/utils.cc:44] AddToRudp. peer_id : fd6785..dbd749 , connection id : fd6785..dbd749
V 16    11:00:24.044321 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kConnect   Res to   ec891d..c315f8   (id: 6708)
V 18    11:00:24.044321 routing/utils.cc:54] rudp.Add succeeded for peer node [fd6785..dbd749]. Connection id : fd6785..dbd749
V 16    11:00:24.045334 routing/network_utils.cc:258]   [ecf84b..d61b3c] sent : kConnect   Res to   ec891d..c315f8   (id: 8761)
V 18    11:00:24.046309 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kConnect   Res from 4896e3..a74675 to ec891d..c315f8   (id: 8761)
V 18    11:00:24.047279 routing/message_handler.cc:174] Message for this node. id: 8761
V 18    11:00:24.048279 routing/response_handler.cc:120] This node [ec891d..c315f8] received connect response from 4896e3..a74675 id: 8761
V 18    11:00:24.049279 routing/utils.cc:44] AddToRudp. peer_id : 4896e3..a74675 , connection id : 4896e3..a74675
V 18    11:00:24.050279 routing/utils.cc:54] rudp.Add succeeded for peer node [4896e3..a74675]. Connection id : 4896e3..a74675
V 18    11:00:24.057280 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kC-Success Req from fd6785..dbd749 to ec891d..c315f8   (id: 5326)
V 4 11:00:24.058280 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kC-Success Req from ec891d..c315f8 to fd6785..dbd749   (id: 4488)
V 18    11:00:24.058280 routing/message_handler.cc:174] Message for this node. id: 5326
V 4 11:00:24.059280 routing/message_handler.cc:174] Message for this node. id: 4488
V 18    11:00:24.059280 routing/service.cc:303] ConnectSuccessFromResponder peer id : fd6785..dbd749
V 18    11:00:24.060280 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id fd6785..dbd749
V 18    11:00:24.061280 routing/network_utils.cc:178]   [ec891d..c315f8] send : kC-Suc-Ack Res to   fd6785..dbd749   (id: 6408) --To Rudp--
V 15    11:00:24.062281 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kC-Success Req from 4896e3..a74675 to ec891d..c315f8   (id: 8012)
V 11    11:00:24.062281 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kC-Success Req from ec891d..c315f8 to 4896e3..a74675   (id: 3172)
V 15    11:00:24.063281 routing/message_handler.cc:174] Message for this node. id: 8012
V 11    11:00:24.063281 routing/message_handler.cc:174] Message for this node. id: 3172
V 15    11:00:24.064348 routing/service.cc:303] ConnectSuccessFromResponder peer id : 4896e3..a74675
V 15    11:00:24.065323 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 15    11:00:24.066319 routing/network_utils.cc:178]   [ec891d..c315f8] send : kC-Suc-Ack Res to   4896e3..a74675   (id: 3964) --To Rudp--
V 4 11:00:24.071282 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kC-Suc-Ack Res from ec891d..c315f8 to fd6785..dbd749   (id: 6408)
V 4 11:00:24.071282 routing/message_handler.cc:174] Message for this node. id: 6408
I 4 11:00:24.072281 routing/response_handler.cc:298] Validation -- Need non-client's public key
V 19    11:00:24.072281 routing/network_utils.cc:258]   [ec891d..c315f8] sent : kC-Suc-Ack Res to   fd6785..dbd749   (id: 6408)
W 4 11:00:24.073282 routing/tests/routing_api_test.cc:679] node_validation called for ec891d..c315f8
I 4 11:00:24.073282 routing/response_handler.cc:333] Validation callback called with public key for ec891d..c315f8
V 11    11:00:24.074282 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kC-Suc-Ack Res from ec891d..c315f8 to 4896e3..a74675   (id: 3964)
V 4 11:00:24.075341 routing/group_matrix.cc:36] AddConnectedPeer : ec891d..c315f8
V 11    11:00:24.075341 routing/message_handler.cc:174] Message for this node. id: 3964
I 11    11:00:24.076282 routing/response_handler.cc:298] Validation -- Need non-client's public key
V 4 11:00:24.076282 routing/routing_table.cc:868] fd6785..dbd749 Updating network status !!! 4
W 11    11:00:24.076282 routing/tests/routing_api_test.cc:679] node_validation called for ec891d..c315f8
V 14    11:00:24.077283 routing/network_utils.cc:258]   [ec891d..c315f8] sent : kC-Suc-Ack Res to   4896e3..a74675   (id: 3964)
V 4 11:00:24.077283 routing/group_change_handler.cc:177] [fd6785..dbd749] SendClosestNodesUpdateRpcs: 3
I 11    11:00:24.078303 routing/response_handler.cc:333] Validation callback called with public key for ec891d..c315f8
V 4 11:00:24.079283 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 3
V 4 11:00:24.079283 routing/group_change_handler.cc:207] fd6785..dbd749 SendSubscribeRpc to ecf84b..d61b3c
V 11    11:00:24.080283 routing/group_matrix.cc:36] AddConnectedPeer : ec891d..c315f8
V 4 11:00:24.080283 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 11    11:00:24.081283 routing/routing_table.cc:868] 4896e3..a74675 Updating network status !!! 4
V 4 11:00:24.081283 routing/network_utils.cc:178]   [fd6785..dbd749] send : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 296) --To Rudp--
V 11    11:00:24.081283 routing/group_change_handler.cc:177] [4896e3..a74675] SendClosestNodesUpdateRpcs: 3
V 4 11:00:24.082306 routing/group_change_handler.cc:207] fd6785..dbd749 SendSubscribeRpc to ec891d..c315f8
V 11    11:00:24.083284 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 3
V 4 11:00:24.084283 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 11    11:00:24.084283 routing/group_change_handler.cc:207] 4896e3..a74675 SendSubscribeRpc to ec891d..c315f8
V 4 11:00:24.085284 routing/network_utils.cc:178]   [fd6785..dbd749] send : kCloses_Nodes_Update_Subscribe Req to   ec891d..c315f8   (id: 8813) --To Rudp--
V 11    11:00:24.085284 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 4 11:00:24.086315 routing/group_change_handler.cc:207] fd6785..dbd749 SendSubscribeRpc to 4896e3..a74675
V 11    11:00:24.087284 routing/network_utils.cc:178]   [4896e3..a74675] send : kCloses_Nodes_Update_Subscribe Req to   ec891d..c315f8   (id: 9192) --To Rudp--
V 4 11:00:24.087284 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id 4896e3..a74675
V 11    11:00:24.088284 routing/group_change_handler.cc:207] 4896e3..a74675 SendSubscribeRpc to ecf84b..d61b3c
V 8 11:00:24.088284 routing/network_utils.cc:258]   [4896e3..a74675] sent : kCloses_Nodes_Update_Subscribe Req to   ec891d..c315f8   (id: 9192)
V 4 11:00:24.090284 routing/network_utils.cc:178]   [fd6785..dbd749] send : kCloses_Nodes_Update_Subscribe Req to   4896e3..a74675   (id: 579) --To Rudp--
V 11    11:00:24.090284 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
I 4 11:00:24.092286 routing/routing_table.cc:173] 

[fd6785..dbd749] This node's own routing table and peer connections:
Routing table size: 3
    Peer [ecf84b..d61b3c]-->ecf84b..d61b3c && xored 119fce..0dcc75 bucket 508
    Peer [ec891d..c315f8]-->ec891d..c315f8 && xored 11ee98..18c2b1 bucket 508
    Peer [4896e3..a74675]-->4896e3..a74675 && xored b5f166..7c913c bucket 511



V 17    11:00:24.092286 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kCloses_Nodes_Update_Subscribe Req from fd6785..dbd749 to ecf84b..d61b3c   (id: 296)
V 4 11:00:24.093306 routing/utils.cc:95] [fd6785..dbd749] added node to routing table.  Node ID: ec891d..c315f8
V 17    11:00:24.093306 routing/message_handler.cc:174] Message for this node. id: 296
V 11    11:00:24.094285 routing/network_utils.cc:178]   [4896e3..a74675] send : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 419) --To Rudp--
V 4 11:00:24.095285 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 7 11:00:24.096286 routing/network_utils.cc:258]   [fd6785..dbd749] sent : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 296)
V 17    11:00:24.095285 routing/group_change_handler.cc:125] [ecf84b..d61b3c] subscribing fd6785..dbd749
V 11    11:00:24.096286 routing/group_change_handler.cc:207] 4896e3..a74675 SendSubscribeRpc to fd6785..dbd749
V 4 11:00:24.097285 routing/network_utils.cc:178]   [fd6785..dbd749] send : kC-Suc-Ack Res to   ec891d..c315f8   (id: 9748) --To Rudp--
V 11    11:00:24.098286 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id fd6785..dbd749
V 15    11:00:24.098286 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kCloses_Nodes_Update_Subscribe Req from fd6785..dbd749 to ec891d..c315f8   (id: 8813)
V 13    11:00:24.099286 routing/network_utils.cc:258]   [fd6785..dbd749] sent : kCloses_Nodes_Update_Subscribe Req to   ec891d..c315f8   (id: 8813)
V 11    11:00:24.100286 routing/network_utils.cc:178]   [4896e3..a74675] send : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 7093) --To Rudp--
V 15    11:00:24.100286 routing/message_handler.cc:174] Message for this node. id: 8813
V 18    11:00:24.102286 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kCloses_Nodes_Update_Subscribe Req from 4896e3..a74675 to ec891d..c315f8   (id: 9192)
V 7 11:00:24.102286 routing/network_utils.cc:258]   [fd6785..dbd749] sent : kCloses_Nodes_Update_Subscribe Req to   4896e3..a74675   (id: 579)
V 9 11:00:24.103286 routing/routing_impl.cc:502]    [4896e3..a74675] rcvd : kCloses_Nodes_Update_Subscribe Req from fd6785..dbd749 to 4896e3..a74675   (id: 579)
V 15    11:00:24.103286 routing/group_change_handler.cc:125] [ec891d..c315f8] subscribing fd6785..dbd749
I 11    11:00:24.104286 routing/routing_table.cc:173] 

[4896e3..a74675] This node's own routing table and peer connections:
Routing table size: 3
    Peer [ec891d..c315f8]-->ec891d..c315f8 && xored a41ffe..64538d bucket 511
    Peer [ecf84b..d61b3c]-->ecf84b..d61b3c && xored a46ea8..715d49 bucket 511
    Peer [fd6785..dbd749]-->fd6785..dbd749 && xored b5f166..7c913c bucket 511



V 9 11:00:24.105286 routing/message_handler.cc:174] Message for this node. id: 579
V 18    11:00:24.105286 routing/message_handler.cc:174] Message for this node. id: 9192
V 10    11:00:24.105286 routing/network_utils.cc:258]   [4896e3..a74675] sent : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 419)
V 11    11:00:24.106287 routing/utils.cc:95] [4896e3..a74675] added node to routing table.  Node ID: ec891d..c315f8
V 18    11:00:24.107286 routing/group_change_handler.cc:125] [ec891d..c315f8] subscribing 4896e3..a74675
V 9 11:00:24.107286 routing/group_change_handler.cc:125] [4896e3..a74675] subscribing fd6785..dbd749
V 17    11:00:24.107286 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kCloses_Nodes_Update_Subscribe Req from 4896e3..a74675 to ecf84b..d61b3c   (id: 419)
V 11    11:00:24.108286 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ec891d..c315f8
V 7 11:00:24.108286 routing/network_utils.cc:258]   [fd6785..dbd749] sent : kC-Suc-Ack Res to   ec891d..c315f8   (id: 9748)
V 17    11:00:24.109287 routing/message_handler.cc:174] Message for this node. id: 419
V 11    11:00:24.110287 routing/network_utils.cc:178]   [4896e3..a74675] send : kC-Suc-Ack Res to   ec891d..c315f8   (id: 241) --To Rudp--
V 18    11:00:24.110287 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kC-Suc-Ack Res from fd6785..dbd749 to ec891d..c315f8   (id: 9748)
V 17    11:00:24.111287 routing/group_change_handler.cc:125] [ecf84b..d61b3c] subscribing 4896e3..a74675
V 10    11:00:24.111287 routing/network_utils.cc:258]   [4896e3..a74675] sent : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 7093)
V 18    11:00:24.112288 routing/message_handler.cc:174] Message for this node. id: 9748
V 4 11:00:24.113287 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kCloses_Nodes_Update_Subscribe Req from 4896e3..a74675 to fd6785..dbd749   (id: 7093)
I 18    11:00:24.114288 routing/response_handler.cc:298] Validation -- Need non-client's public key
V 4 11:00:24.114288 routing/message_handler.cc:174] Message for this node. id: 7093
W 18    11:00:24.115288 routing/tests/routing_api_test.cc:679] node_validation called for fd6785..dbd749
V 4 11:00:24.115288 routing/group_change_handler.cc:125] [fd6785..dbd749] subscribing 4896e3..a74675
I 18    11:00:24.115288 routing/response_handler.cc:333] Validation callback called with public key for fd6785..dbd749
V 18    11:00:24.116288 routing/group_matrix.cc:36] AddConnectedPeer : fd6785..dbd749
V 18    11:00:24.117288 routing/routing_table.cc:868] ec891d..c315f8 Updating network status !!! 3
V 18    11:00:24.118288 routing/group_change_handler.cc:177] [ec891d..c315f8] SendClosestNodesUpdateRpcs: 2
V 18    11:00:24.118288 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 2
V 18    11:00:24.119329 routing/group_change_handler.cc:207] ec891d..c315f8 SendSubscribeRpc to ecf84b..d61b3c
V 18    11:00:24.120289 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 18    11:00:24.120289 routing/network_utils.cc:178]   [ec891d..c315f8] send : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 8250) --To Rudp--
V 18    11:00:24.121332 routing/group_change_handler.cc:207] ec891d..c315f8 SendSubscribeRpc to fd6785..dbd749
V 8 11:00:24.122290 routing/network_utils.cc:258]   [4896e3..a74675] sent : kC-Suc-Ack Res to   ec891d..c315f8   (id: 241)
V 18    11:00:24.122290 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id fd6785..dbd749
V 18    11:00:24.123339 routing/network_utils.cc:178]   [ec891d..c315f8] send : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 9997) --To Rudp--
V 15    11:00:24.124290 routing/routing_impl.cc:502]    [ec891d..c315f8] rcvd : kC-Suc-Ack Res from 4896e3..a74675 to ec891d..c315f8   (id: 241)
I 18    11:00:24.124290 routing/routing_table.cc:173] 

[ec891d..c315f8] This node's own routing table and peer connections:
Routing table size: 2
    Peer [ecf84b..d61b3c]-->ecf84b..d61b3c && xored 007156..150ec4 bucket 502
    Peer [fd6785..dbd749]-->fd6785..dbd749 && xored 11ee98..18c2b1 bucket 508



V 15    11:00:24.125290 routing/message_handler.cc:174] Message for this node. id: 241
V 18    11:00:24.126290 routing/utils.cc:95] [ec891d..c315f8] added node to routing table.  Node ID: fd6785..dbd749
I 15    11:00:24.126290 routing/response_handler.cc:298] Validation -- Need non-client's public key
V 18    11:00:24.127290 routing/routing_table.cc:115] Node ecf84b..d61b3c already in routing table.
W 15    11:00:24.127290 routing/tests/routing_api_test.cc:679] node_validation called for 4896e3..a74675
V 18    11:00:24.127290 routing/response_handler.cc:212] CheckNode succeeded for node 4896e3..a74675
I 15    11:00:24.128290 routing/response_handler.cc:333] Validation callback called with public key for 4896e3..a74675
V 18    11:00:24.128290 routing/response_handler.cc:232] Already ongoing attempt to : 4896e3..a74675
V 15    11:00:24.129290 routing/group_matrix.cc:36] AddConnectedPeer : 4896e3..a74675
V 17    11:00:24.130312 routing/routing_impl.cc:502]    [ecf84b..d61b3c] rcvd : kCloses_Nodes_Update_Subscribe Req from ec891d..c315f8 to ecf84b..d61b3c   (id: 8250)
V 15    11:00:24.131290 routing/tests/routing_api_test.cc:737] node - 3joined
I 1 11:00:24.131290 routing/routing_impl.cc:142] Doing a BootstrapFromTheseEndpoints Join.  Entered first bootstrap endpoint: 192.168.0.118:31022, this node's ID: 852d46..e79bea
V 17    11:00:24.131290 routing/message_handler.cc:174] Message for this node. id: 8250
V 19    11:00:24.132290 routing/network_utils.cc:258]   [ec891d..c315f8] sent : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 8250)
V 15    11:00:24.132290 routing/routing_table.cc:868] ec891d..c315f8 Updating network status !!! 4
V 17    11:00:24.133290 routing/group_change_handler.cc:125] [ecf84b..d61b3c] subscribing ec891d..c315f8
V 4 11:00:24.134315 routing/routing_impl.cc:502]    [fd6785..dbd749] rcvd : kCloses_Nodes_Update_Subscribe Req from ec891d..c315f8 to fd6785..dbd749   (id: 9997)
V 14    11:00:24.134315 routing/network_utils.cc:258]   [ec891d..c315f8] sent : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 9997)
V 15    11:00:24.135291 routing/group_change_handler.cc:177] [ec891d..c315f8] SendClosestNodesUpdateRpcs: 3
V 4 11:00:24.136291 routing/message_handler.cc:174] Message for this node. id: 9997
V 15    11:00:24.136291 routing/group_change_handler.cc:205] SendSubscribeRpc: nodes_needing_update: 3
V 4 11:00:24.137301 routing/group_change_handler.cc:125] [fd6785..dbd749] subscribing ec891d..c315f8
V 15    11:00:24.137301 routing/group_change_handler.cc:207] ec891d..c315f8 SendSubscribeRpc to ecf84b..d61b3c
V 15    11:00:24.138291 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id ecf84b..d61b3c
V 15    11:00:24.139310 routing/network_utils.cc:178]   [ec891d..c315f8] send : kCloses_Nodes_Update_Subscribe Req to   ecf84b..d61b3c   (id: 9533) --To Rudp--
V 15    11:00:24.139310 routing/group_change_handler.cc:207] ec891d..c315f8 SendSubscribeRpc to fd6785..dbd749
V 15    11:00:24.140310 routing/network_utils.cc:266]  >>>>>>>>> rudp send message to connection id fd6785..dbd749
V 15    11:00:24.141292 routing/network_utils.cc:178]   [ec891d..c315f8] send : kCloses_Nodes_Update_Subscribe Req to   fd6785..dbd749   (id: 3127) --To Rudp--
V 15    11:00:24.142292 routing/group_change_h