Skip to content
This repository has been archived by the owner on Jan 6, 2020. It is now read-only.

Assertion failed for sliding window in rUDP #103

Closed
anju-rajain opened this issue Mar 13, 2013 · 1 comment
Closed

Assertion failed for sliding window in rUDP #103

anju-rajain opened this issue Mar 13, 2013 · 1 comment
Labels
bug

Comments

@anju-rajain
Copy link

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

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
@Fraser999 Fraser999 mentioned this issue Jun 13, 2013
Open
@Fraser999
Copy link
Contributor

Migrated to maidsafe-archive/MaidSafe-RUDP#4

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees
No one assigned
Labels
bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@Fraser999 @anju-rajain