Closed ghost closed 11 years ago
Dan-Schmidt
commented
11 years ago This looks like the network doesn't set up. The operations are timing out. Near the end of the given output there is a result that shows ANMID: -200003, ANSMID: -200003, ANTMID: -200003, PMID path: -200003. This are the first packets to be stored and they fail.
dirvine
commented
11 years ago Seems there is no working invigilator, can you detail the setup of this test or provide a minimal test case to show the error.
ghost
commented
11 years ago I set up 11 vaults on a local network then ran the invigilator_test exe followed by the lifestuff gui from VS. The lifestuff gui was created as a console app and provided the output above. The invigilator_test exe stopped working when lifestuff was started giving the initial output, however, when restarted lifestuff registers with the invigilator, the instance of S 5 above. Although the vaults are running and can store/retrieve chunks if tested with pd_key_helper it appears that the invigilator and hence lifestuff is not aware that it's there. The global_config.dat file never gets updated so maybe a path is set wrong or is not what Windows expects and the bootstrap file is not read. An endpoint to bootstrap with is found but network health is zero, were unable to store anything and the routing table remains empty.
Dan-Schmidt
commented
11 years ago TESTlifestuff_api.exe --gtest_filter=*FUNC_CreateLogoutLoginLogout sets up vaults and an invigilator. It passes with the new work that Fraser has done with the now LS-mgr as of version 0.1.001: https://github.com/maidsafe/MaidSafe/commit/bb075db28bfdcd8c426a6aeabbee04344169fdfe
I think we should start using it as a marker, along with the scripts that are being done in QA, to assess the state of the create user process.
When creating a new user in LifeStuff the following message results, Error Code 207016 : Create User Failed. During the process storing the anonymous packets fails with individual results -200003, kRemoteChunkStoreFailure. The output from the console window is,
E 2 15:36:13.660622 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 2 15:36:14.672680 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 2 15:36:15.676737 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 2 15:36:16.680794 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 2 15:36:17.688852 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 2 15:36:18.692909 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 2 15:36:19.695967 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 2 15:36:20.700024 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 3 15:36:20.702024 private/process_management/client_controller.cc:119] ClientController failed to connect to Invigilator on all ports in range 5483 to 5490 E 3 15:36:20.704025 private/process_management/client_controller.cc:168] ClientController is uni nitialised. E 3 15:36:20.706025 private/process_management/client_controller.cc:62] Failed to connect to inv igilator. Object useless. E 3 15:36:20.708025 private/process_management/client_controller.cc:79] Not connected to Invigil ator. W 3 15:36:20.710025 lifestuff/lifestuff_impl.cc:139] Failure to initialise client controller. Tr y #1 E 3 15:36:24.137221 private/process_management/client_controller.cc:160] Timed out waiting for C lientController initialisation. E 4 15:36:25.136278 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 4 15:36:26.140335 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 4 15:36:27.143393 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 4 15:36:28.147450 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 4 15:36:29.151508 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 4 15:36:30.154565 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 4 15:36:31.158622 private/process_management/tcp_connection.cc:58] Failed to connect: No conne ction could be made because the target machine actively refused it E 3 15:36:31.162623 private/process_management/client_controller.cc:119] ClientController failed to connect to Invigilator on all ports in range 5483 to 5490 E 3 15:36:31.165623 private/process_management/client_controller.cc:168] ClientController is uni nitialised. E 3 15:36:31.169623 private/process_management/client_controller.cc:62] Failed to connect to inv igilator. Object useless. E 3 15:36:31.211626 private/process_management/client_controller.cc:79] Not connected to Invigil ator. W 3 15:36:31.215626 lifestuff/lifestuff_impl.cc:139] Failure to initialise client controller. Tr y #2 E 5 15:36:31.512643 private/process_management/client_controller.cc:212] New version file missin g: "" S 5 15:36:31.517643 private/process_management/client_controller.cc:227] Successfully registered with Invigilator on port 5483 I 6 15:37:09.118794 pd/client/node.cc:58] Starting client node ANON ... W 6 15:37:15.673169 pd/client/node.cc:74] Init - No keys set, node will be anonymous. I 6 15:37:15.799176 pd/client/node.cc:83] Init - Chunk store has 0 chunks (0 B of 340 GiB). I 6 15:37:17.658282 routing/routing_impl.cc:74] Anonymous node id: 6ded42..e26687, connection id 3371df..19d1b4 I 6 15:37:17.660282 routing/routing_impl.cc:115] Doing a BootstrapFromTheseEndpoints Join. Ente red first bootstrap endpoint: 192.168.0.10:50503, this node's ID: 6ded42..e26687 Client S 7 15:37:17.697284 rudp/transport.cc:420] Successfully made Bootstrapping connection from [3371 df. - 0.0.0.0:0 / 192.168.0.10:61896] to [505c7e. - 192.168.0.10:50503] I 6 15:37:17.700285 routing/network_utils.cc:115] Bootstrap successful, bootstrap connection id
[6ded42..e26687] This node's own routing table and peer connections: Routing table size: 0
W 8 16:13:27.716402 routing/routing_impl.cc:462] [6ded42..e26687]Lost temporary connection with bootstrap node. connection id :505c7e..a9386c E 8 16:13:27.719403 routing/routing_impl.cc:472] Anonymous Session Ended, Send not allowed anymo re W 8 16:13:27.723403 pd/common/routing_message_handler.cc:80] Init - ANON - Network is down (-303 007) I 6 16:16:03.679323 private/chunk_store/remote_chunk_store.cc:239] Store - 6u2y8vs..98pt7id I 6 16:16:04.257356 routing/timer.cc:67] AddTask added a task, with id 724829943 E 6 16:16:04.287358 rudp/managed_connections.cc:611] Can't send from 3371df..19d1b4 to 000000..0 00000 - not in map. E 9 16:16:06.572488 routing/routing_impl.cc:368] Anonymous Session Ended, Send not allowed anymo re I 6 16:16:18.195153 private/chunk_store/remote_chunk_store.cc:239] Store - d45n3rp..ds4kyad I 8 16:16:04.296358 routing/timer.cc:104] Cancelled task 724829943 W 9 16:16:18.198153 pd/common/routing_message_handler.cc:80] Init - ANON - Network is down (-303 007) I 6 16:16:18.215154 routing/timer.cc:67] AddTask added a task, with id 724829944 W 10 16:16:18.217154 pd/common/rpc_handler.cc:124] CheckResponse - ANON - RPC 1100 to group aroun d e4b16f..fcd8f5 failed (-1), trying again... E 6 16:16:18.219155 rudp/managed_connections.cc:611] Can't send from 3371df..19d1b4 to 000000..0 00000 - not in map. E 9 16:16:18.222155 routing/routing_impl.cc:368] Anonymous Session Ended, Send not allowed anymo re I 8 16:16:18.222155I 6 16:16:18.223155 routing/timer.cc:104] Cancelled task 724829944 private/chunk_store/remote_chunk_store.cc:239] Store - 8gdfhun..bs8fdsd W 9 16:16:18.227155 pd/common/routing_message_handler.cc:80] Init - ANON - Network is down (-303 007) W 11 16:16:18.231155 pd/common/rpc_handler.cc:124] CheckResponse - ANON - RPC 1100 to group aroun d 1eb6cc..e1a558 failed (-1), trying again... I 6 16:16:18.240156 routing/timer.cc:67] AddTask added a task, with id 724829945 E 6 16:16:18.242156 rudp/managed_connections.cc:611] Can't send from 3371df..19d1b4 to 000000..0 00000 - not in map. I 8 16:16:18.244156 routing/timer.cc:104] Cancelled task 724829945 E 9 16:16:18.244156 routing/routing_impl.cc:368] Anonymous Session Ended, Send not allowed anymo re I 6 16:16:18.245156 private/chunk_store/remote_chunk_store.cc:239] Store - siwf2m7..4fiesid W 11 16:16:18.246156 pd/common/rpc_handler.cc:124] CheckResponse - ANON - RPC 1100 to group aroun d f18653..61e28e failed (-1), trying again... W 9 16:16:18.247156 pd/common/routing_message_handler.cc:80] Init - ANON - Network is down (-303 007) I 6 16:16:18.255157 routing/timer.cc:67] AddTask added a task, with id 724829946 E 6 16:16:18.257157 rudp/managed_connections.cc:611] Can't send from 3371df..19d1b4 to 000000..0 00000 - not in map. I 8 16:16:18.261157 routing/timer.cc:104] Cancelled task 724829946 I 6 16:16:18.261157 private/utils/utilities.cc:61] Element 0 still pending. E 9 16:16:18.260157 routing/routing_impl.cc:368] Anonymous Session Ended, Send not allowed anymo re W 10 16:16:18.265157 pd/common/rpc_handler.cc:124] CheckResponse - ANON - RPC 1100 to group aroun d 82285c..8a8241 failed (-1), trying again... W 9 16:16:18.283158 pd/common/routing_message_handler.cc:80] Init - ANON - Network is down (-303 007) I 12 16:16:20.221269 routing/timer.cc:67] AddTask added a task, with id 724829947 E 12 16:16:20.225269 rudp/managed_connections.cc:611] Can't send from 3371df..19d1b4 to 000000..0 00000 - not in map. E 9 16:16:20.228269 routing/routing_impl.cc:368] Anonymous Session Ended, Send not allowed anymo re I 8 16:16:20.229270 routing/timer.cc:104] Cancelled task 724829947 W 9 16:16:20.233270 pd/common/routing_message_handler.cc:80] Init - ANON - Network is down (-303 007) I 13 16:16:20.243270 routing/timer.cc:67] AddTask added a task, with id 724829948 E 13 16:16:20.246270 rudp/managed_connections.cc:611] Can't send from 3371df..19d1b4 to 000000..0 00000 - not in map. E 12 16:16:20.249271 pd/common/rpc_handler.cc:132] CheckResponse - ANON - RPC 1100 to group aroun d e4b16f..fcd8f5 failed (-1) after 2 attempts. I 14 16:16:20.251271 routing/timer.cc:67] AddTask added a task, with id 724829949 E 9 16:16:20.258271 routing/routing_impl.cc:368] Anonymous Session Ended, Send not allowed anymo re I 8 16:16:20.258271 routing/timer.cc:104] Cancelled task 724829948 E 14 16:16:20.258271 rudp/managed_connections.cc:611] Can't send from 3371df..19d1b4 to 000000..0 00000 - not in map. W 9 16:16:20.267272 pd/common/routing_message_handler.cc:80] Init - ANON - Network is down (-303 007) E 13 16:16:20.270272 pd/common/rpc_handler.cc:132] CheckResponse - ANON - RPC 1100 to group aroun d 1eb6cc..e1a558 failed (-1) after 2 attempts. E 8 16:16:20.273272 routing/routing_impl.cc:368] Anonymous Session Ended, Send not allowed anymo re I 9 16:16:20.273272 routing/timer.cc:104] Cancelled task 724829949 E 12 16:16:20.275272 pd/common/rpc_handler.cc:768] ProcessResponse - ANON - ChunkInfo/AddReferenc e RPC failed. E 13 16:16:20.275272 pd/common/rpc_handler.cc:768] ProcessResponse - ANON - ChunkInfo/AddReferenc e RPC failed. W 8 16:16:20.279272 pd/common/routing_message_handler.cc:80] Init - ANON - Network is down (-303 007) E 14 16:16:20.280272 pd/common/rpc_handler.cc:132] CheckResponse - ANON - RPC 1100 to group aroun d f18653..61e28e failed (-1) after 2 attempts. E 14 16:16:20.283273 pd/common/rpc_handler.cc:768] ProcessResponse - ANON - ChunkInfo/AddReferenc e RPC failed. I 11 16:16:20.284273 routing/timer.cc:67] AddTask added a task, with id 724829950 E 11 16:16:20.286273 rudp/managed_connections.cc:611] Can't send from 3371df..19d1b4 to 000000..0 00000 - not in map. E 8 16:16:20.288273 routing/routing_impl.cc:368] Anonymous Session Ended, Send not allowed anymo re I 9 16:16:20.288273 routing/timer.cc:104] Cancelled task 724829950 W 8 16:16:20.290273 pd/common/routing_message_handler.cc:80] Init - ANON - Network is down (-303 007) E 11 16:16:20.291273 pd/common/rpc_handler.cc:132] CheckResponse - ANON - RPC 1100 to group aroun d 82285c..8a8241 failed (-1) after 2 attempts. E 11 16:16:20.293273 pd/common/rpc_handler.cc:768] ProcessResponse - ANON - ChunkInfo/AddReferenc e RPC failed. E 11 16:16:20.307274 pd/client/vault_chunk_manager.cc:306] AddReferenceCallback - ANON - AddRefer ence RPCs failed for siwf2m7..4fiesid~SIG E 13 16:16:20.308274 pd/client/vault_chunk_manager.cc:306] AddReferenceCallback - ANON - AddRefer ence RPCs failed for d45n3rp..ds4kyad~SIG E 14 16:16:20.308274 pd/client/vault_chunk_manager.cc:306] AddReferenceCallback - ANON - AddRefer ence RPCs failed for 8gdfhun..bs8fdsd~SIG E 12 16:16:20.308274 pd/client/vault_chunk_manager.cc:306] AddReferenceCallback - ANON - AddRefer ence RPCs failed for 6u2y8vs..98pt7id~SIG E 11 16:16:20.313274 private/chunk_store/remote_chunk_store.cc:435] OnOpResult - Failed to store siwf2m7..4fiesid (-1) I 11 16:16:20.318275 private/chunk_store/remote_chunk_store.cc:442] OnOpResult - Store done, dele ting siwf2m7..4fiesid I 11 16:16:20.321275 private/chunk_store/file_chunk_store.cc:560] Heuristic to prevent iteration for the most common case: "C:\Users\Brian Smith.lifestuff\QN0GSNMiWUoGXynw\buffered_chunk_store\s\i \w\f\2\m7twcrbmdfkdhehftceeyuhm6nqwrj29jirrr329ma9tjtwu3x74tax5unrqcwaed9ynt8dixcd7tcpbmsx5denyycmb4 fiesid" E 11 16:16:20.348276 lifestuff/detail/user_credentials_impl.cc:660] Anmaid failed to store. E 13 16:16:20.348276 private/chunk_store/remote_chunk_store.cc:435] OnOpResult - Failed to store d45n3rp..ds4kyad (-1) I 6 16:16:20.350276 private/utils/utilities.cc:61] Element 0 still pending. I 13 16:16:20.351276 private/chunk_store/remote_chunk_store.cc:442] OnOpResult - Store done, dele ting d45n3rp..ds4kyad I 13 16:16:20.354277 private/chunk_store/file_chunk_store.cc:560] Heuristic to prevent iteration for the most common case: "C:\Users\Brian Smith.lifestuff\QN0GSNMiWUoGXynw\buffered_chunk_store\d\4 \5\n\3\rp249ggaid7usxisk4bbc7jjxtkz7raf4yyeunpze5jzwseezq2e86jet85dtu5tns9zbwp9ctiryunrdpuu87b6wbzud s4kyad" I 6 16:16:20.356277 private/utils/utilities.cc:61] Element 0 still pending. E 12 16:16:20.357277 private/chunk_store/remote_chunk_store.cc:435] OnOpResult - Failed to store 6u2y8vs..98pt7id (-1) I 12 16:16:20.359277 private/chunk_store/remote_chunk_store.cc:442] OnOpResult - Store done, dele ting 6u2y8vs..98pt7id I 12 16:16:20.361277 private/chunk_store/file_chunk_store.cc:560] Heuristic to prevent iteration for the most common case: "C:\Users\Brian Smith.lifestuff\QN0GSNMiWUoGXynw\buffered_chunk_store\6\u \2\y\8\vsbduht2c4y2xhhwrbntyx2ctqa83yd6437muimvi4qjx5anqgyi3k6ak6s5as2gdex5m68nrz7wts4zc6ahi6u2jihs9 8pt7id" I 6 16:16:20.363277 private/utils/utilities.cc:61] Element 2 still pending. E 14 16:16:20.364277 private/chunk_store/remote_chunk_store.cc:435] OnOpResult - Failed to store 8gdfhun..bs8fdsd (-1) I 14 16:16:20.366277 private/chunk_store/remote_chunk_store.cc:442] OnOpResult - Store done, dele ting 8gdfhun..bs8fdsd I 14 16:16:20.368277 private/chunk_store/file_chunk_store.cc:560] Heuristic to prevent iteration for the most common case: "C:\Users\Brian Smith.lifestuff\QN0GSNMiWUoGXynw\buffered_chunk_store\8\g \d\f\h\un53xb5qmrz8g67ar8g4iqwudx7yfaemc4nmt95tg9it9dkfdqfrid2zh9yswrgpphp7anxci4jtuf4yak4aawsuqhv7b s8fdsd" I 6 16:16:32.318961 lifestuff/detail/user_credentials_impl.cc:595] ANMID: -200003, ANSMID: -2000 03, ANTMID: -200003, PMID path: -200003 E 6 16:17:56.578780 lifestuff/detail/user_credentials_impl.cc:602] One of the operations for Ano nymous Packets failed. Turn on INFO for feedback on which one. E 6 16:17:56.582781 lifestuff/detail/user_credentials_impl.cc:557] Failure to Store Anonymous pa ckets: -207014 E 6 16:17:56.585781 lifestuff/detail/user_credentials_impl.cc:521] Failed processing signature p ackets: -207014 E 6 16:17:56.606782 lifestuff/lifestuff_impl.cc:240] Failed to Create User with result: -207016