Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[Issue] A recurrent issue causes a break of the network requester + socks5 client operation #4109

Closed
twofaktor opened this issue Nov 6, 2023 · 9 comments
Assignees
Labels
bug Something isn't working bug-needs-triage A bug that needs discussing and triage needs-triage qa Quality Assurance

Comments

@twofaktor
Copy link
Contributor

twofaktor commented Nov 6, 2023

Describe the issue
After starting nym-network-request randomly, it stops working after the next logs, after that, nym-socks5-client and the apps attached to it stop working too:

WARN nym_socks5_proxy_helpers::proxy_runner::outbound didn't get anything from the client on 13960325690217133581 mixnet in 300s. Shutting down the proxy.

It is solved by restarting the network request, but it happens very often, constantly requiring the intervention of the administrator.

Expected behaviour
Stability and reliability of the service

Stack Traces

Network requester:

Oct 24 22:47:19 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:47:19.633Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.05 kiB message
Oct 24 22:47:29 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:47:29.120Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.05 kiB message
Oct 24 22:47:30 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:47:30.491Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.08 kiB message
Oct 24 22:47:36 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:47:36.428Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.05 kiB message
Oct 24 22:47:37 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:47:37.117Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Oct 24 22:47:37 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:47:37.428Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Oct 24 22:47:38 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:47:38.230Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.05 kiB message
Oct 24 22:47:47 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:47:47.403Z ERROR nym_network_requester::core                                                   > error while connecting to 70.53.43.54:18333: Connection timed out (os error 110)
Oct 24 22:47:47 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:47:47.403Z INFO  unnamed-TaskClient                                                            > the task client is getting dropped
Oct 24 22:48:46 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:48:46.795Z ERROR nym_network_requester::core                                                   > error while connecting to 204.111.228.38:18333: Connection timed out (os error 110)
Oct 24 22:48:46 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:48:46.795Z INFO  unnamed-TaskClient                                                            > the task client is getting dropped
Oct 24 22:52:36 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:52:36.429Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 13960325690217133581 mixnet in 300s. Shutting down the proxy.
Oct 24 22:52:38 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:52:38.232Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 17356840665627676329 mixnet in 300s. Shutting down the proxy.
Oct 24 22:52:39 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:52:39.431Z INFO  nym_network_requester::core                                                   > Proxy for 23.93.101.158:18333 is finished  (currently there are 1 proxies being handled)
Oct 24 22:52:41 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:52:41.233Z INFO  nym_network_requester::core                                                   > Proxy for 54.89.113.236:18333 is finished  (currently there are 0 proxies being handled)
Oct 24 22:57:54 raspibolt2fa nym-network-requester[415615]:  2023-10-24T20:57:54.921Z INFO  nym_network_requester::allowed_hosts::standard_list                           > Refreshing standard allowed hosts
Oct 24 23:27:54 raspibolt2fa nym-network-requester[415615]:  2023-10-24T21:27:54.921Z INFO  nym_network_requester::allowed_hosts::standard_list                           > Refreshing standard allowed hosts
Oct 24 23:57:54 raspibolt2fa nym-network-requester[415615]:  2023-10-24T21:57:54.921Z INFO  nym_network_requester::allowed_hosts::standard_list                           > Refreshing standard allowed hosts
Oct 25 00:27:54 raspibolt2fa nym-network-requester[415615]:  2023-10-24T22:27:54.921Z INFO  nym_network_requester::allowed_hosts::standard_list                           > Refreshing standard allowed hosts
Oct 25 00:57:54 raspibolt2fa nym-network-requester[415615]:  2023-10-24T22:57:54.921Z INFO  nym_network_requester::allowed_hosts::standard_list                           > Refreshing standard allowed hosts
Oct 25 01:27:54 raspibolt2fa nym-network-requester[415615]:  2023-10-24T23:27:54.921Z INFO  nym_network_requester::allowed_hosts::standard_list                           > Refreshing standard allowed hosts
Oct 25 01:57:54 raspibolt2fa nym-network-requester[415615]:  2023-10-24T23:57:54.921Z INFO  nym_network_requester::allowed_hosts::standard_list                           > Refreshing standard allowed hosts
Oct 25 02:27:54 raspibolt2fa nym-network-requester[415615]:  2023-10-25T00:27:54.921Z INFO  nym_network_requester::allowed_hosts::standard_list                           > Refreshing standard allowed hosts
Oct 25 02:57:54 raspibolt2fa nym-network-requester[415615]:  2023-10-25T00:57:54.921Z INFO  nym_network_requester::allowed_hosts::standard_list                           > Refreshing standard allowed hosts
Nov 10 06:27:57 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:27:57.810Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.05 kiB message
Nov 10 06:27:58 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:27:58.158Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.05 kiB message
Nov 10 06:27:58 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:27:58.848Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.05 kiB message
Nov 10 06:28:08 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:28:08.548Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 10 06:32:08 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:08.402Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 17237171087460656820 mixnet in 300s. Shutting down the proxy.
Nov 10 06:32:11 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:11.402Z INFO  nym_network_requester::core                                                   > Proxy for 158.69.227.12:28533 is finished  (currently there are 7 proxies being handled)
Nov 10 06:32:18 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:18.942Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 4205135943552388837 mixnet in 300s. Shutting down the proxy.
Nov 10 06:32:21 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:21.942Z INFO  nym_network_requester::core                                                   > Proxy for 198.199.120.106:18333 is finished  (currently there are 6 proxies being handled)
Nov 10 06:32:22 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:22.723Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 4586099784181481883 mixnet in 300s. Shutting down the proxy.
Nov 10 06:32:25 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:25.434Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 3290237566327758792 mixnet in 300s. Shutting down the proxy.
Nov 10 06:32:25 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:25.725Z INFO  nym_network_requester::core                                                   > Proxy for 173.82.5.202:18333 is finished  (currently there are 5 proxies being handled)
Nov 10 06:32:28 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:28.436Z INFO  nym_network_requester::core                                                   > Proxy for 95.217.75.49:18333 is finished  (currently there are 4 proxies being handled)
Nov 10 06:32:41 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:41.222Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 378296438602193151 mixnet in 300s. Shutting down the proxy.
Nov 10 06:32:44 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:44.224Z INFO  nym_network_requester::core                                                   > Proxy for 135.181.79.230:18333 is finished  (currently there are 3 proxies being handled)
Nov 10 06:32:54 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:54.377Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 2670219862864045830 mixnet in 300s. Shutting down the proxy.
Nov 10 06:32:57 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:57.379Z INFO  nym_network_requester::core                                                   > Proxy for 18.203.237.26:18333 is finished  (currently there are 2 proxies being handled)
Nov 10 06:32:58 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:58.158Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 7374065802507395741 mixnet in 300s. Shutting down the proxy.
Nov 10 06:32:58 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:32:58.850Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 5792240049755846273 mixnet in 300s. Shutting down the proxy.
Nov 10 06:33:01 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:33:01.160Z INFO  nym_network_requester::core                                                   > Proxy for 5.188.119.196:18333 is finished  (currently there are 1 proxies being handled)
Nov 10 06:33:01 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:33:01.851Z INFO  nym_network_requester::core                                                   > Proxy for 73.11.168.43:18333 is finished  (currently there are 0 proxies being handled)
Nov 10 06:51:29 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:51:29.731Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 10 06:51:29 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:51:29.731Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 10 06:51:29 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T05:51:29.731Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration
Nov 10 07:21:29 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T06:21:29.731Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 10 07:21:29 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T06:21:29.731Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 10 07:21:29 raspibolt2fa nym-network-requester[1986556]:  2023-11-10T06:21:29.731Z WARN  nym_client_core::client::topology_control

Socks5-client after network requester fails:

Nov 10 06:32:44 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:32:44.813Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 10 06:32:47 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:32:47.814Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 135.181.79.230:18333 is finished (id: 378296438602193151)
Nov 10 06:32:47 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:32:47.909Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 87.249.133.140:18333 (id: 7457261373097561039)
Nov 10 06:32:48 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:32:48.371Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.08 kiB message
Nov 10 06:32:50 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:32:50.893Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 99.157.195.248:18333 (id: 10307818591420859076)
Nov 10 06:32:52 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:32:52.706Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.08 kiB message
Nov 10 06:32:57 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:32:57.685Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 10 06:32:58 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:32:58.457Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 10 06:33:00 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:00.686Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 18.203.237.26:18333 is finished (id: 2670219862864045830)
Nov 10 06:33:01 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:01.597Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 10 06:33:02 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:02.109Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 10 06:33:04 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:04.599Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 5.188.119.196:18333 is finished (id: 7374065802507395741)
Nov 10 06:33:05 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:05.110Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 73.11.168.43:18333 is finished (id: 5792240049755846273)
Nov 10 06:33:12 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:12.934Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 180.233.124.141:18333 (id: 1575113055389223500)
Nov 10 06:33:13 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:13.435Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 129.80.132.114:18333 (id: 11765103142410229533)
Nov 10 06:33:16 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:16.043Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 178.63.87.163:18333 (id: 12712382713437167698)
Nov 10 06:33:21 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:21.045Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 146.70.134.140:18333 is finished (id: 8046315455218183944)
Nov 10 06:33:26 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:26.017Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 35.236.111.88:18333 (id: 8726405043651984706)
Nov 10 06:33:31 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:31.018Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 95.183.121.203:18333 is finished (id: 14450232515384898085)
Nov 10 06:33:48 raspibolt2fa nym-socks5-client[1986571]:  2023-11-10T05:33:48.028Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 2.86.54.134:18333 (id: 10738655446608722914)

Steps to Reproduce

  • Start network requester and start to use, wait, no more observed pattern

Screenshots

Network requester:
imagen

imagen

Socks5-client after network requester fails:
imagen

Which area of Nym were you using?

  • Application: nym-network-requester + nym-socks5-client
  • OS: Raspberry Pi OS arm64 (bullseye)

Issue related: #3719

@mfahampshire
Copy link
Contributor

mfahampshire commented Nov 13, 2023

How often are you restarting your NR service?

And is the flow here that you:

  • have the NR running
  • start the socks5 client + make some network requests via the NR
  • leave the socks client running in the background
  • try and make more requests but the requests fail (?)

The error messages are interesting. Task client getting dropped is something that should only really be seen on disconnecting the client. The errors regarding the network topology I think are unrelated, there has been some issue with the caching timeout recently cropping up.

@twofaktor
Copy link
Contributor Author

twofaktor commented Nov 13, 2023

How often are you restarting your NR service?

lately, almost every day

And is the flow here that you:

* have the NR running

* start the socks5 client + make some network requests via the NR

* leave the socks client running in the background

* try and make more requests but the requests fail (?)

Correct, and if I consult the NR logs and socks5 client, I can see these logs

E.g, consulted a moment ago:

NR:

Nov 12 06:11:39 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:11:39.331Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.12 kiB message
Nov 12 06:11:39 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:11:39.333Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.12 kiB message
Nov 12 06:11:39 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:11:39.481Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.12 kiB message
Nov 12 06:16:34 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:34.432Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 6422734223845150336 mixnet in 300s. Shutting down the proxy.
Nov 12 06:16:37 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:37.433Z INFO  nym_network_requester::core                                                   > Proxy for 95.217.75.49:18333 is finished  (currently there are 5 proxies being handled)
Nov 12 06:16:39 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:39.241Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 10751847867185638336 mixnet in 300s. Shutting down the proxy.
Nov 12 06:16:39 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:39.285Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 8390942684149384349 mixnet in 300s. Shutting down the proxy.
Nov 12 06:16:39 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:39.332Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 15852338770590228031 mixnet in 300s. Shutting down the proxy.
Nov 12 06:16:39 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:39.335Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 16377612509233043174 mixnet in 300s. Shutting down the proxy.
Nov 12 06:16:39 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:39.482Z WARN  nym_socks5_proxy_helpers::proxy_runner::outbound                              > didn't get anything from the client on 7197177420155267418 mixnet in 300s. Shutting down the proxy.
Nov 12 06:16:42 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:42.242Z INFO  nym_network_requester::core                                                   > Proxy for 84.250.74.122:18333 is finished  (currently there are 4 proxies being handled)
Nov 12 06:16:42 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:42.286Z INFO  nym_network_requester::core                                                   > Proxy for 5.32.74.252:18333 is finished  (currently there are 3 proxies being handled)
Nov 12 06:16:42 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:42.333Z INFO  nym_network_requester::core                                                   > Proxy for 62.210.222.73:18333 is finished  (currently there are 2 proxies being handled)
Nov 12 06:16:42 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:42.336Z INFO  nym_network_requester::core                                                   > Proxy for 85.10.203.112:18333 is finished  (currently there are 1 proxies being handled)
Nov 12 06:16:42 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:16:42.483Z INFO  nym_network_requester::core                                                   > Proxy for 65.21.15.203:18333 is finished  (currently there are 0 proxies being handled)
Nov 12 06:36:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:36:02.405Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 12 06:36:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:36:02.405Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 12 06:36:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T05:36:02.405Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration
Nov 12 10:51:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T09:51:02.404Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 12 10:51:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T09:51:02.404Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 12 10:51:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T09:51:02.404Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration
Nov 12 15:21:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T14:21:02.404Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 12 15:21:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T14:21:02.404Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 12 15:21:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T14:21:02.404Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration
Nov 12 16:06:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T15:06:02.405Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 12 16:06:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T15:06:02.405Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 12 16:06:02 raspibolt2fa nym-network-requester[2197804]:  2023-11-12T15:06:02.405Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration

Socks5 client:

Nov 12 06:16:37 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:37.074Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.08 kiB message
Nov 12 06:16:37 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:37.451Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.08 kiB message
Nov 12 06:16:37 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:37.875Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 12 06:16:40 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:40.876Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 95.217.75.49:18333 is finished (id: 6422734223845150336)
Nov 12 06:16:40 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:40.877Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 197.210.227.200:18333 (id: 11925192221077331415)
Nov 12 06:16:42 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:42.568Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 12 06:16:42 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:42.809Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 12 06:16:42 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:42.853Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 12 06:16:42 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:42.879Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 12 06:16:42 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:42.901Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 12 06:16:42 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:42.954Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 12 06:16:45 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:45.854Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 5.32.74.252:18333 is finished (id: 8390942684149384349)
Nov 12 06:16:45 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:45.857Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 18.141.234.127:18333 (id: 3763692073380664418)
Nov 12 06:16:45 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:45.880Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 65.21.15.203:18333 is finished (id: 7197177420155267418)
Nov 12 06:16:45 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:45.955Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 62.210.222.73:18333 is finished (id: 15852338770590228031)
Nov 12 06:16:46 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:46.358Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 52.215.205.249:18333 (id: 8639478094141446537)
Nov 12 06:16:46 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:46.859Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 78.47.119.18:18333 (id: 4256031356067915034)
Nov 12 06:16:47 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:47.360Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 139.144.22.237:18333 (id: 14776803444353730992)
Nov 12 06:16:51 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:16:51.008Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 52.50.219.237:18333 is finished (id: 5216928615890191040)
Nov 12 06:17:26 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:17:26.555Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 43.206.229.230:8333 (id: 11483755584863877191)
Nov 12 06:17:31 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:17:31.013Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 51.38.63.166:18333 is finished (id: 10118591970768953254)
Nov 12 06:17:41 raspibolt2fa nym-socks5-client[2197892]:  2023-11-12T05:17:41.018Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 191.113.250.117:8333 (id: 13027769596242673185)

Also, socks5 applications attached (telegram, keybase, etc) stop working to be able to connect to the socks5 client (socks5 client stops working if NR stops working)

The error messages are interesting. Task client getting dropped is something that should only really be seen on disconnecting the client. The errors regarding the network topology I think are unrelated, there has been some issue with the caching timeout recently cropping up.

PS: Today I decided to stop using NYM, I stopped my network requester and the socks5 client, as well as the applications connected to it. When this problem is solved I will use NYM again, these nodes were never very stable for me, but recently with the latest releases, now it is unfeasible to operate them if I have to restart them every day. I hope this will fixed soon

@mfahampshire
Copy link
Contributor

I'm very sorry to hear that but understand. Once the nodes are combined in the coming months then this problem should disappear hopefully, although its something to be looking into. Thanks for the update.

One final question re: "but recently with the latest releases, now it is unfeasible to operate them" - is this something that has become worse recently? Can you pinpoint (or have a rough idea) of the version that this became worse at?

@twofaktor
Copy link
Contributor Author

twofaktor commented Nov 16, 2023

I'm very sorry to hear that but understand. Once the nodes are combined in the coming months then this problem should disappear hopefully, although its something to be looking into. Thanks for the update.

One final question re: "but recently with the latest releases, now it is unfeasible to operate them" - is this something that has become worse recently? Can you pinpoint (or have a rough idea) of the version that this became worse at?

Uhm... I'm sorry I don't remember exactly but I think since 2 releases before to current maybe

@twofaktor
Copy link
Contributor Author

twofaktor commented Nov 16, 2023

BTW: To discard possible error triggers, I'm testing running NR and socks5 client, but not connecting any persistent app to the socks5 let's see if it continues to fail or not. I will bring back conclusions

Attach here some socks5 client logs that appear to me since I'm testing that:

Nov 16 02:56:51 raspibolt2fa nym-socks5-client[2758711]:  2023-11-16T01:56:51.433Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 16 02:56:51 raspibolt2fa nym-socks5-client[2758711]:  2023-11-16T01:56:51.433Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 16 02:56:51 raspibolt2fa nym-socks5-client[2758711]:  2023-11-16T01:56:51.433Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the ld topology for this iteration
Nov 16 03:16:51 raspibolt2fa nym-socks5-client[2758711]:  2023-11-16T02:16:51.433Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 16 03:16:51 raspibolt2fa nym-socks5-client[2758711]:  2023-11-16T02:16:51.433Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 16 03:16:51 raspibolt2fa nym-socks5-client[2758711]:  2023-11-16T02:16:51.433Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration
Nov 16 10:16:51 raspibolt2fa nym-socks5-client[2758711]:  2023-11-16T09:16:51.432Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 16 10:16:51 raspibolt2fa nym-socks5-client[2758711]:  2023-11-16T09:16:51.433Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 16 10:16:51 raspibolt2fa nym-socks5-client[2758711]:  2023-11-16T09:16:51.433Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration

@mfahampshire
Copy link
Contributor

Interesting. We've had a few timeout issues crop up in channels recently - this should be sorted once we have the nym-api operating in a decentralised manner early next year I imagine.

@twofaktor
Copy link
Contributor Author

twofaktor commented Nov 24, 2023

More logs about nym-socks5-client without to request connections to it during 2 days:

Nov 22 14:43:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T13:43:46.291Z INFO  nym_socks5_client_core::socks::server                                         > Listening on 0.0.0.0:1080
Nov 22 14:43:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T13:43:46.291Z INFO  nym_socks5_client_core                                                        > Client startup finished!
Nov 22 14:43:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T13:43:46.291Z INFO  nym_socks5_client_core                                                        > The address of this client is: GSLV7VcEjgBD9B2pY4wXxwqsgQuYnP6pSQ1JoLr2uBHJ.9qGidgnVCZRnGRALT6CmuGzcVi4meVeqHtUgbR3wyzCm@2xU4CBE6QiiYt6EyBXSALwxkNvM7gqJfjHXaMkjiFmYW
Nov 22 14:43:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T13:43:46.291Z INFO  nym_socks5_client_core::socks::server                                         > Serving Connections...
Nov 22 15:03:47 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:03:47.272Z WARN  nym_topology                                                                  > Gateway 3hy1Wac6SCJbCm55Bkg7KCtTozqh13TgpDQ6Riw6dvK8 is malformed: '103.43.75.206 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 22 15:03:47 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:03:47.272Z WARN  nym_topology                                                                  > Gateway 4Q9fSjd12vhRqBdjaqZfhraP7tYznSWR31Js9EhNkcRZ is malformed: '139.84.168.85 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 22 15:08:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:08:46.655Z WARN  nym_topology                                                                  > Gateway 3hy1Wac6SCJbCm55Bkg7KCtTozqh13TgpDQ6Riw6dvK8 is malformed: '103.43.75.206 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 22 15:08:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:08:46.657Z WARN  nym_topology                                                                  > Gateway 4Q9fSjd12vhRqBdjaqZfhraP7tYznSWR31Js9EhNkcRZ is malformed: '139.84.168.85 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 22 15:13:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:13:46.778Z WARN  nym_topology                                                                  > Gateway 3hy1Wac6SCJbCm55Bkg7KCtTozqh13TgpDQ6Riw6dvK8 is malformed: '103.43.75.206 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 22 15:13:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:13:46.778Z WARN  nym_topology                                                                  > Gateway 4Q9fSjd12vhRqBdjaqZfhraP7tYznSWR31Js9EhNkcRZ is malformed: '139.84.168.85 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 22 15:18:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:18:46.741Z WARN  nym_topology                                                                  > Gateway 3hy1Wac6SCJbCm55Bkg7KCtTozqh13TgpDQ6Riw6dvK8 is malformed: '103.43.75.206 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 22 15:18:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:18:46.741Z WARN  nym_topology                                                                  > Gateway 4Q9fSjd12vhRqBdjaqZfhraP7tYznSWR31Js9EhNkcRZ is malformed: '139.84.168.85 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 22 15:23:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:23:46.783Z WARN  nym_topology                                                                  > Gateway 3hy1Wac6SCJbCm55Bkg7KCtTozqh13TgpDQ6Riw6dvK8 is malformed: '103.43.75.206 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 22 15:23:46 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T14:23:46.783Z WARN  nym_topology                                                                  > Gateway 4Q9fSjd12vhRqBdjaqZfhraP7tYznSWR31Js9EhNkcRZ is malformed: '139.84.168.85 ' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 23 00:18:49 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T23:18:49.123Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 23 00:18:49 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T23:18:49.123Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 23 00:18:49 raspibolt2fa nym-socks5-client[3527966]:  2023-11-22T23:18:49.123Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration
Nov 23 16:38:49 raspibolt2fa nym-socks5-client[3527966]:  2023-11-23T15:38:49.122Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: error sending request for url (https://validator.nymtech.net/api/v1/mixnodes/active): operation timed out
Nov 23 16:38:49 raspibolt2fa nym-socks5-client[3527966]:  2023-11-23T15:38:49.123Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 23 16:38:49 raspibolt2fa nym-socks5-client[3527966]:  2023-11-23T15:38:49.123Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration
Nov 23 20:08:49 raspibolt2fa nym-socks5-client[3527966]:  2023-11-23T19:08:49.121Z ERROR nym_client_core::client::topology_control::nym_api_provider                   > failed to get network mixnodes - nym api request failed - there was an issue with the REST request: request or response body error: operation timed out
Nov 23 20:08:49 raspibolt2fa nym-socks5-client[3527966]:  2023-11-23T19:08:49.123Z WARN  nym_client_core::client::topology_control                                     > failed to obtain new network topology
Nov 23 20:08:49 raspibolt2fa nym-socks5-client[3527966]:  2023-11-23T19:08:49.123Z WARN  nym_client_core::client::topology_control                                     > we're going to keep on using the old topology for this iteration
Nov 24 14:14:23 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:23.021Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 149.154.167.92:443 (id: 3140636024500063216)
Nov 24 14:14:25 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:25.246Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 91.108.56.132:443 (id: 8441757044158710509)
Nov 24 14:14:25 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:25.250Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 149.154.167.92:443 (id: 8127750316522054422)
Nov 24 14:14:25 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:25.250Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 149.154.167.92:80 (id: 14966136336461490388)
Nov 24 14:14:25 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:25.250Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 91.108.56.132:80 (id: 7885538073508181634)
Nov 24 14:14:26 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:26.247Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 91.108.56.132:443 (id: 5658942149310723502)
Nov 24 14:14:26 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:26.247Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 149.154.167.92:443 (id: 10736726213066789159)
Nov 24 14:14:26 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:26.249Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 149.154.167.92:80 (id: 15789225153227750626)
Nov 24 14:14:26 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:26.249Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 91.108.56.132:80 (id: 11780026155352478567)
Nov 24 14:14:28 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:28.243Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 149.154.167.92:443 (id: 17881830286982145144)
Nov 24 14:14:28 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:28.247Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 91.108.56.132:443 (id: 10196055820447677619)
Nov 24 14:14:28 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:28.247Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 91.108.56.132:80 (id: 16495805519092614060)
Nov 24 14:14:28 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:28.248Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 149.154.167.92:80 (id: 17550815718292552379)
Nov 24 14:14:31 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:31.247Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 91.108.56.132:443 is finished (id: 8441757044158710509)
Nov 24 14:14:31 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:31.249Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 149.154.167.92:443 is finished (id: 8127750316522054422)
Nov 24 14:14:31 raspibolt2fa nym-socks5-client[3527966]:  2023-11-24T13:14:31.257Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 91.108.56.132:80 is finished (id: 7885538073508181634)
Nov 26 12:32:38 raspibolt2fa nym-socks5-client[3979369]:  2023-11-26T11:32:38.511Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 149.154.167.92:80 is finished (id: 8508153809523907405)
Nov 26 12:32:39 raspibolt2fa nym-socks5-client[3979369]:  2023-11-26T11:32:39.408Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 26 12:32:39 raspibolt2fa nym-socks5-client[3979369]:  2023-11-26T11:32:39.494Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.12 kiB message
Nov 26 12:32:43 raspibolt2fa nym-socks5-client[3979369]:  2023-11-26T11:32:43.294Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 26 12:32:43 raspibolt2fa nym-socks5-client[3979369]:  2023-11-26T11:32:43.294Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 149.154.167.92:443 is finished (id: 8615822870012851608)
Nov 26 12:32:43 raspibolt2fa nym-socks5-client[3979369]:  2023-11-26T11:32:43.418Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 26 12:32:43 raspibolt2fa nym-socks5-client[3979369]:  2023-11-26T11:32:43.418Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 149.154.167.92:443 is finished (id: 3207456049779862891)
Nov 26 21:47:31 raspibolt2fa nym-socks5-client[3979369]:  2023-11-26T20:47:31.893Z WARN  nym_topology                                                                  > Gateway AhCir6XNpxWFpT4LAVJ1v1NwvqJh3LeNQqzU4Lb3mPP8 is malformed: 'Lightnode' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 26 21:52:32 raspibolt2fa nym-socks5-client[3979369]:  2023-11-26T20:52:32.431Z WARN  nym_topology                                                                  > Gateway AhCir6XNpxWFpT4LAVJ1v1NwvqJh3LeNQqzU4Lb3mPP8 is malformed: 'Lightnode' is not a valid gateway address - failed to lookup address information: Name or service not known
Nov 27 09:23:44 raspibolt2fa nym-socks5-client[3979369]:  2023-11-27T08:23:44.332Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for 149.154.167.92:443     (id: 11094594414915626545)
Nov 27 09:23:45 raspibolt2fa nym-socks5-client[3979369]:  2023-11-27T08:23:45.181Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.13 kiB message
Nov 27 09:23:45 raspibolt2fa nym-socks5-client[3979369]:  2023-11-27T08:23:45.592Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 27 09:23:49 raspibolt2fa nym-socks5-client[3979369]:  2023-11-27T08:23:49.496Z INFO  nym_client_core::client::received_buffer                                      > received plain 0.02 kiB message
Nov 27 09:23:49 raspibolt2fa nym-socks5-client[3979369]:  2023-11-27T08:23:49.497Z INFO  nym_socks5_client_core::socks::client                                         > Proxy for 149.154.167.92:443 is finished (id: 11094594414915626545)
Nov 28 09:39:14 raspibolt2fa nym-socks5-client[3979369]:  2023-11-28T08:39:14.371Z INFO  nym_socks5_client_core::socks::client                                         > Starting proxy for push.services.mozilla.com:443 (id: 15567475869929769801)

It makes me think that there is also a problem with the nym-socks5 client, when I restart the nym-socks5 client the problem connecting to it is resolved

@twofaktor twofaktor changed the title [Issue] A recurrent issue causes a break of the network requester operation - [didn't get anything from the client on....] [Issue] A recurrent issue causes a break of the network requester + socks5 client operation Nov 26, 2023
@tommyv1987
Copy link
Contributor

@twofaktor have you ran into this since, there's been a lot of releases since this event happened, i will close the ticket, but if you comment and re-open it we can investigate.

@twofaktor
Copy link
Contributor Author

twofaktor commented Sep 10, 2024

@twofaktor have you ran into this since, there's been a lot of releases since this event happened, i will close the ticket, but if you comment and re-open it we can investigate.

Sometimes (randomly and not so frequently) socks5 + requester stop working, and restarting the requester is fixed, i think it's a requester issue, but I can't find the reason in the logs anymore. If I find the reason ever, I would open an issue again with the new report. Thanks for your attention

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working bug-needs-triage A bug that needs discussing and triage needs-triage qa Quality Assurance
Projects
None yet
Development

No branches or pull requests

3 participants