NatNet [May 26 Testnet 2023] [Offline]

maidsafe · May 26, 2023, 7:41am

Time for another exciting testnet!

This time the subject is NAT (Network Address Translation), and it’s all about detecting the NAT status of a node.

When a node runs at home it is usually behind a NAT, meaning other nodes can’t reach it by default. Today is all about detecting if that is the case. In the future, these nodes should still be able to join the network by using relays and NAT traversal, but before we get there we need to know the NAT status of a node.

Technically, a home node can still join by port forwarding (check your router instructions on how to do this). This essentially means the NAT firewall is circumvented. Nodes should be able to join with such a setup.

The goal of this testnet is to:

Check that when running a node the NAT status is established correctly.
See that once we’ve determined a node is behind NAT we shutdown the node.
Find out if the testnet stays reliable and check that home (NAT) nodes do not get added to the routing table.

Note: We are not really focused on clients and adding files here. We really just want to know if our NAT detection is working!

How to participate

We’d appreciate it if folk participating could:

Run the node from home
Check if the node shuts down after a few minutes
Share the PeerId or full logs, noting if you think you are behind NAT or not (was the outcome what you expected?)

In short, running the node should discover its NAT status. This generally takes about 2 minutes, so keep it running for that amount of time at least. If the NAT status is private, the node should shut itself down automatically. The node might show some errors for outgoing probes, and that is exactly what we expect with nodes behind NAT. Other nodes can not probe us, so we are not getting successful results back, which will eventually lead us to conclude we are on a private network.

Network Address

To connect to the NatNet, we should set the network address using the SAFE_PEERS env variable or use the --peer= argument. Use any of the following address to connect to the network:

export SAFE_PEERS=/ip4/206.189.29.202/tcp/38379/p2p/12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2

# fall-back addresses
/ip4/138.68.151.254/tcp/39679/p2p/12D3KooWNorWTvVHWh7q7SLNqiPsXF6DPg8i6g67heNyrokaQYtU
/ip4/167.172.56.211/tcp/38457/p2p/12D3KooWFLvPUtXD7v6rmiGSyMJdt3w4eM466AwJRe3nnUeCY4g4
/ip4/138.68.151.132/tcp/44619/p2p/12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay

Running a Node

(You can grab the safenode binaries for your platform here)

The node connects to the Network similarly to the client, by passing the network address of any other public node. Use the SAFE_PEERS environment variable or the --peers argument as mentioned before to connect to the Network. And it’s a good idea to keep the logs in a directory.

$ safenode --log-dir=/tmp/safenode --root-dir=/tmp/safenodedata

The node should shut itself down after a few minutes in the case that you’re running at home in a NAT situation. In this case, let us know the peer ID which can be found in the first few lines of the logs.

Error: We have been determined to be behind a NAT. This means we are not reachable externally by other nodes. In the future, the network will implement relays that allow us to still join the network.

Known problems

Currently, public nodes that join the network successfully and leave might not be properly detected as such by the network. That means some IP addresses will still be known by nodes and thought to be part of the network. This might result in errors for outgoing connections (OutgoingConnectionError). We’re tracking this issue and it should be addressed in an upcoming release.

19eddyjohn75 · May 26, 2023, 7:51am

These super ants are so amazing . Jippy

Vort · May 26, 2023, 8:04am

[2023-05-26T08:03:06.062280Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(0), peer: Some(PeerId("12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2")), error: Response(DialError) }
[2023-05-26T08:03:06.062280Z INFO safenode::network::event] AutoNAT status changed: Unknown -> Private
[2023-05-26T08:03:06.062280Z WARN safenode::node::api] NAT status is determined to be private!

Looks like I need to tune firewall.

upd. After firewall tuning: AutoNAT status changed: Unknown -> Public. It works

Toivo · May 26, 2023, 8:45am

OK, tried to run a node from the City of Helsinki public open wlan, for about 6-8 minutes, then shut it down. Is this OK, or should we try to keep up what we set up?

It seems to have worked OK!

Full logs:

Summary

[2023-05-26T08:34:46.815353Z DEBUG safenode] Current build's git commit hash: ---- No git commit hash found ----
[2023-05-26T08:34:46.815704Z INFO safenode::peers_acquisition] Using contact peers from $SAFE_PEERS env var to intitiate contact with the network: ["/ip4/206.189.29.202/tcp/38379/p2p/12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2"]
[2023-05-26T08:34:46.815750Z INFO safenode] 
Running safenode v0.1.0
=======================
[2023-05-26T08:34:46.816810Z INFO safenode] Starting node ...
[2023-05-26T08:34:46.816992Z INFO safenode::network] Node (PID: 8772) with PeerId: 12D3KooWL1mAGa14tdedU8FCutieSHKS52Eg4vXqiXuyRvTKaNX5
[2023-05-26T08:34:46.825912Z INFO safenode::network::event] Local node is listening on "/ip4/10.46.14.182/tcp/61442/p2p/12D3KooWL1mAGa14tdedU8FCutieSHKS52Eg4vXqiXuyRvTKaNX5"
[2023-05-26T08:34:46.825964Z INFO safenode::network::event] Local node is listening on "/ip4/127.0.0.1/tcp/61442/p2p/12D3KooWL1mAGa14tdedU8FCutieSHKS52Eg4vXqiXuyRvTKaNX5"
[2023-05-26T08:34:46.826390Z ERROR safenode::node::api] Failed to dial 12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2: AlreadyDialingPeer(PeerId("12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2"))
[2023-05-26T08:34:49.822805Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(0), peer: None, error: NoServer }
[2023-05-26T08:35:07.829239Z DEBUG safenode::node::api] No network activity in the past 21s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 133, 27, 244, 19, 101, 15, 128, 33, 17, 144, 38, 251, 111, 80, 233, 33, 219, 135, 19, 184, 98, 208, 69, 251, 184, 140, 163, 1, 160, 16, 150, 97])
[2023-05-26T08:35:07.829294Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 133, 27, 244, 19, 101, 15, 128, 33, 17, 144, 38, 251, 111, 80, 233, 33, 219, 135, 19, 184, 98, 208, 69, 251, 184, 140, 163, 1, 160, 16, 150, 97])
[2023-05-26T08:35:07.829713Z TRACE safenode::network::event] Query task QueryId(0) returned with peers GetClosestPeersOk { key: [0, 32, 133, 27, 244, 19, 101, 15, 128, 33, 17, 144, 38, 251, 111, 80, 233, 33, 219, 135, 19, 184, 98, 208, 69, 251, 184, 140, 163, 1, 160, 16, 150, 97], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1424881.1211759s }), end: Some(Instant { t: 1424881.1211759s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:35:07.829810Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:35:08.991266Z WARN safenode::network::event] Having OutgoingConnectionError Some(PeerId("12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2")) - Transport([("/ip4/206.189.29.202/tcp/38379/p2p/12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2", Other(Custom { kind: Other, error: Right(Select(ProtocolError(IoError(Os { code: 10054, kind: ConnectionReset, message: "An existing connection was forcibly closed by the remote host." })))) }))])
[2023-05-26T08:35:08.991649Z ERROR safenode::node::api] Failed to dial 12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2: DialError(Transport([("/ip4/206.189.29.202/tcp/38379/p2p/12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2", Other(Custom { kind: Other, error: Right(Select(ProtocolError(IoError(Os { code: 10054, kind: ConnectionReset, message: "An existing connection was forcibly closed by the remote host." })))) }))]))
[2023-05-26T08:35:30.843449Z DEBUG safenode::node::api] No network activity in the past 23s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 66, 54, 82, 2, 110, 24, 63, 209, 245, 196, 5, 19, 157, 250, 134, 83, 152, 76, 48, 219, 38, 71, 188, 165, 52, 22, 18, 240, 43, 80, 167, 104])
[2023-05-26T08:35:30.843480Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 66, 54, 82, 2, 110, 24, 63, 209, 245, 196, 5, 19, 157, 250, 134, 83, 152, 76, 48, 219, 38, 71, 188, 165, 52, 22, 18, 240, 43, 80, 167, 104])
[2023-05-26T08:35:30.843603Z TRACE safenode::network::event] Query task QueryId(1) returned with peers GetClosestPeersOk { key: [0, 32, 66, 54, 82, 2, 110, 24, 63, 209, 245, 196, 5, 19, 157, 250, 134, 83, 152, 76, 48, 219, 38, 71, 188, 165, 52, 22, 18, 240, 43, 80, 167, 104], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1424904.1352104s }), end: Some(Instant { t: 1424904.1352104s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:35:30.843619Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:36:03.848745Z DEBUG safenode::node::api] No network activity in the past 33s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 122, 134, 231, 222, 43, 236, 138, 169, 78, 158, 200, 99, 237, 71, 171, 177, 182, 72, 65, 234, 147, 106, 214, 177, 71, 177, 122, 187, 111, 179, 165, 181])
[2023-05-26T08:36:03.848762Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 122, 134, 231, 222, 43, 236, 138, 169, 78, 158, 200, 99, 237, 71, 171, 177, 182, 72, 65, 234, 147, 106, 214, 177, 71, 177, 122, 187, 111, 179, 165, 181])
[2023-05-26T08:36:03.848838Z TRACE safenode::network::event] Query task QueryId(2) returned with peers GetClosestPeersOk { key: [0, 32, 122, 134, 231, 222, 43, 236, 138, 169, 78, 158, 200, 99, 237, 71, 171, 177, 182, 72, 65, 234, 147, 106, 214, 177, 71, 177, 122, 187, 111, 179, 165, 181], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1424937.1404697s }), end: Some(Instant { t: 1424937.1404697s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:36:03.848855Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:36:19.830577Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(1), peer: None, error: NoServer }
[2023-05-26T08:36:26.862283Z DEBUG safenode::node::api] No network activity in the past 23s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 86, 38, 128, 98, 65, 160, 166, 32, 227, 99, 16, 43, 141, 208, 166, 108, 98, 116, 148, 185, 217, 27, 74, 255, 125, 203, 137, 197, 180, 56, 30, 62])
[2023-05-26T08:36:26.862305Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 86, 38, 128, 98, 65, 160, 166, 32, 227, 99, 16, 43, 141, 208, 166, 108, 98, 116, 148, 185, 217, 27, 74, 255, 125, 203, 137, 197, 180, 56, 30, 62])
[2023-05-26T08:36:26.862413Z TRACE safenode::network::event] Query task QueryId(3) returned with peers GetClosestPeersOk { key: [0, 32, 86, 38, 128, 98, 65, 160, 166, 32, 227, 99, 16, 43, 141, 208, 166, 108, 98, 116, 148, 185, 217, 27, 74, 255, 125, 203, 137, 197, 180, 56, 30, 62], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1424960.1540433s }), end: Some(Instant { t: 1424960.1540433s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:36:26.862447Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:36:58.874375Z DEBUG safenode::node::api] No network activity in the past 32s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 213, 174, 213, 249, 184, 40, 90, 200, 70, 182, 79, 32, 124, 144, 166, 177, 72, 151, 109, 163, 171, 159, 181, 63, 231, 187, 197, 16, 74, 19, 28, 247])
[2023-05-26T08:36:58.874391Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 213, 174, 213, 249, 184, 40, 90, 200, 70, 182, 79, 32, 124, 144, 166, 177, 72, 151, 109, 163, 171, 159, 181, 63, 231, 187, 197, 16, 74, 19, 28, 247])
[2023-05-26T08:36:58.874500Z TRACE safenode::network::event] Query task QueryId(4) returned with peers GetClosestPeersOk { key: [0, 32, 213, 174, 213, 249, 184, 40, 90, 200, 70, 182, 79, 32, 124, 144, 166, 177, 72, 151, 109, 163, 171, 159, 181, 63, 231, 187, 197, 16, 74, 19, 28, 247], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1424992.1661282s }), end: Some(Instant { t: 1424992.1661282s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:36:58.874528Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:37:23.884343Z DEBUG safenode::node::api] No network activity in the past 25s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 208, 53, 123, 244, 89, 192, 213, 240, 84, 123, 210, 197, 185, 99, 162, 143, 242, 77, 58, 6, 76, 53, 123, 145, 201, 38, 164, 183, 200, 226, 52, 201])
[2023-05-26T08:37:23.884361Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 208, 53, 123, 244, 89, 192, 213, 240, 84, 123, 210, 197, 185, 99, 162, 143, 242, 77, 58, 6, 76, 53, 123, 145, 201, 38, 164, 183, 200, 226, 52, 201])
[2023-05-26T08:37:23.884457Z TRACE safenode::network::event] Query task QueryId(5) returned with peers GetClosestPeersOk { key: [0, 32, 208, 53, 123, 244, 89, 192, 213, 240, 84, 123, 210, 197, 185, 99, 162, 143, 242, 77, 58, 6, 76, 53, 123, 145, 201, 38, 164, 183, 200, 226, 52, 201], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425017.1760845s }), end: Some(Instant { t: 1425017.1760845s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:37:23.884480Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:37:49.838343Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(2), peer: None, error: NoServer }
[2023-05-26T08:37:56.887363Z DEBUG safenode::node::api] No network activity in the past 33s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 115, 30, 137, 49, 95, 254, 97, 210, 249, 77, 10, 217, 47, 174, 250, 145, 196, 176, 101, 231, 60, 112, 76, 177, 30, 116, 212, 176, 82, 224, 10, 73])
[2023-05-26T08:37:56.887390Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 115, 30, 137, 49, 95, 254, 97, 210, 249, 77, 10, 217, 47, 174, 250, 145, 196, 176, 101, 231, 60, 112, 76, 177, 30, 116, 212, 176, 82, 224, 10, 73])
[2023-05-26T08:37:56.887541Z TRACE safenode::network::event] Query task QueryId(6) returned with peers GetClosestPeersOk { key: [0, 32, 115, 30, 137, 49, 95, 254, 97, 210, 249, 77, 10, 217, 47, 174, 250, 145, 196, 176, 101, 231, 60, 112, 76, 177, 30, 116, 212, 176, 82, 224, 10, 73], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425050.1791637s }), end: Some(Instant { t: 1425050.1791637s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:37:56.887581Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:38:35.888821Z DEBUG safenode::node::api] No network activity in the past 39s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 188, 217, 8, 144, 101, 135, 55, 186, 64, 81, 53, 95, 46, 135, 252, 99, 3, 176, 97, 3, 45, 93, 120, 26, 127, 60, 57, 72, 114, 6, 19, 47])
[2023-05-26T08:38:35.888875Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 188, 217, 8, 144, 101, 135, 55, 186, 64, 81, 53, 95, 46, 135, 252, 99, 3, 176, 97, 3, 45, 93, 120, 26, 127, 60, 57, 72, 114, 6, 19, 47])
[2023-05-26T08:38:35.889066Z TRACE safenode::network::event] Query task QueryId(7) returned with peers GetClosestPeersOk { key: [0, 32, 188, 217, 8, 144, 101, 135, 55, 186, 64, 81, 53, 95, 46, 135, 252, 99, 3, 176, 97, 3, 45, 93, 120, 26, 127, 60, 57, 72, 114, 6, 19, 47], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425089.1806878s }), end: Some(Instant { t: 1425089.1806878s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:38:35.889095Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:39:05.900926Z DEBUG safenode::node::api] No network activity in the past 30s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 138, 223, 71, 241, 25, 95, 169, 221, 210, 155, 116, 64, 224, 190, 116, 159, 155, 9, 243, 73, 11, 163, 102, 227, 153, 227, 244, 206, 130, 69, 142, 113])
[2023-05-26T08:39:05.900962Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 138, 223, 71, 241, 25, 95, 169, 221, 210, 155, 116, 64, 224, 190, 116, 159, 155, 9, 243, 73, 11, 163, 102, 227, 153, 227, 244, 206, 130, 69, 142, 113])
[2023-05-26T08:39:05.901038Z TRACE safenode::network::event] Query task QueryId(8) returned with peers GetClosestPeersOk { key: [0, 32, 138, 223, 71, 241, 25, 95, 169, 221, 210, 155, 116, 64, 224, 190, 116, 159, 155, 9, 243, 73, 11, 163, 102, 227, 153, 227, 244, 206, 130, 69, 142, 113], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425119.1926695s }), end: Some(Instant { t: 1425119.1926695s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:39:05.901051Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:39:19.839984Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(3), peer: None, error: NoServer }
[2023-05-26T08:39:36.907160Z DEBUG safenode::node::api] No network activity in the past 31s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 152, 13, 155, 147, 4, 187, 92, 66, 32, 90, 143, 218, 235, 177, 2, 67, 79, 9, 249, 125, 171, 192, 166, 164, 144, 130, 125, 100, 143, 103, 185, 180])
[2023-05-26T08:39:36.907192Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 152, 13, 155, 147, 4, 187, 92, 66, 32, 90, 143, 218, 235, 177, 2, 67, 79, 9, 249, 125, 171, 192, 166, 164, 144, 130, 125, 100, 143, 103, 185, 180])
[2023-05-26T08:39:36.907291Z TRACE safenode::network::event] Query task QueryId(9) returned with peers GetClosestPeersOk { key: [0, 32, 152, 13, 155, 147, 4, 187, 92, 66, 32, 90, 143, 218, 235, 177, 2, 67, 79, 9, 249, 125, 171, 192, 166, 164, 144, 130, 125, 100, 143, 103, 185, 180], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425150.1989224s }), end: Some(Instant { t: 1425150.1989224s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:39:36.907334Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:40:13.913241Z DEBUG safenode::node::api] No network activity in the past 37s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 164, 79, 60, 96, 132, 165, 98, 47, 91, 125, 10, 164, 28, 129, 110, 210, 229, 174, 112, 51, 184, 146, 175, 179, 107, 139, 248, 224, 1, 108, 228, 107])
[2023-05-26T08:40:13.913261Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 164, 79, 60, 96, 132, 165, 98, 47, 91, 125, 10, 164, 28, 129, 110, 210, 229, 174, 112, 51, 184, 146, 175, 179, 107, 139, 248, 224, 1, 108, 228, 107])
[2023-05-26T08:40:13.913352Z TRACE safenode::network::event] Query task QueryId(10) returned with peers GetClosestPeersOk { key: [0, 32, 164, 79, 60, 96, 132, 165, 98, 47, 91, 125, 10, 164, 28, 129, 110, 210, 229, 174, 112, 51, 184, 146, 175, 179, 107, 139, 248, 224, 1, 108, 228, 107], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425187.2049845s }), end: Some(Instant { t: 1425187.2049845s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:40:13.913366Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:40:36.928624Z DEBUG safenode::node::api] No network activity in the past 23s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 250, 225, 13, 189, 109, 195, 16, 247, 190, 230, 51, 243, 214, 199, 142, 218, 254, 220, 47, 150, 215, 101, 209, 1, 137, 47, 69, 74, 199, 63, 58, 138])
[2023-05-26T08:40:36.928677Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 250, 225, 13, 189, 109, 195, 16, 247, 190, 230, 51, 243, 214, 199, 142, 218, 254, 220, 47, 150, 215, 101, 209, 1, 137, 47, 69, 74, 199, 63, 58, 138])
[2023-05-26T08:40:36.928823Z TRACE safenode::network::event] Query task QueryId(11) returned with peers GetClosestPeersOk { key: [0, 32, 250, 225, 13, 189, 109, 195, 16, 247, 190, 230, 51, 243, 214, 199, 142, 218, 254, 220, 47, 150, 215, 101, 209, 1, 137, 47, 69, 74, 199, 63, 58, 138], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425210.2204408s }), end: Some(Instant { t: 1425210.2204408s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:40:36.928859Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:40:49.853379Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(4), peer: None, error: NoServer }
[2023-05-26T08:41:04.938231Z DEBUG safenode::node::api] No network activity in the past 28s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 60, 159, 135, 200, 255, 238, 30, 138, 51, 209, 108, 236, 102, 151, 61, 137, 253, 130, 41, 85, 186, 182, 240, 166, 59, 56, 229, 164, 121, 65, 19, 220])
[2023-05-26T08:41:04.938272Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 60, 159, 135, 200, 255, 238, 30, 138, 51, 209, 108, 236, 102, 151, 61, 137, 253, 130, 41, 85, 186, 182, 240, 166, 59, 56, 229, 164, 121, 65, 19, 220])
[2023-05-26T08:41:04.938385Z TRACE safenode::network::event] Query task QueryId(12) returned with peers GetClosestPeersOk { key: [0, 32, 60, 159, 135, 200, 255, 238, 30, 138, 51, 209, 108, 236, 102, 151, 61, 137, 253, 130, 41, 85, 186, 182, 240, 166, 59, 56, 229, 164, 121, 65, 19, 220], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425238.2300124s }), end: Some(Instant { t: 1425238.2300124s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:41:04.938417Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:41:38.940189Z DEBUG safenode::node::api] No network activity in the past 34s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 71, 216, 222, 73, 46, 63, 182, 19, 214, 108, 171, 123, 104, 182, 229, 66, 36, 41, 211, 32, 31, 215, 109, 232, 75, 169, 53, 49, 19, 97, 6, 67])
[2023-05-26T08:41:38.940221Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 71, 216, 222, 73, 46, 63, 182, 19, 214, 108, 171, 123, 104, 182, 229, 66, 36, 41, 211, 32, 31, 215, 109, 232, 75, 169, 53, 49, 19, 97, 6, 67])
[2023-05-26T08:41:38.940305Z TRACE safenode::network::event] Query task QueryId(13) returned with peers GetClosestPeersOk { key: [0, 32, 71, 216, 222, 73, 46, 63, 182, 19, 214, 108, 171, 123, 104, 182, 229, 66, 36, 41, 211, 32, 31, 215, 109, 232, 75, 169, 53, 49, 19, 97, 6, 67], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425272.2319375s }), end: Some(Instant { t: 1425272.2319375s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:41:38.940323Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:42:05.948693Z DEBUG safenode::node::api] No network activity in the past 27s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 165, 145, 207, 113, 168, 66, 132, 202, 215, 105, 172, 182, 196, 75, 174, 215, 64, 201, 106, 49, 135, 200, 53, 84, 53, 18, 91, 110, 217, 134, 18, 204])
[2023-05-26T08:42:05.948718Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 165, 145, 207, 113, 168, 66, 132, 202, 215, 105, 172, 182, 196, 75, 174, 215, 64, 201, 106, 49, 135, 200, 53, 84, 53, 18, 91, 110, 217, 134, 18, 204])
[2023-05-26T08:42:05.948847Z TRACE safenode::network::event] Query task QueryId(14) returned with peers GetClosestPeersOk { key: [0, 32, 165, 145, 207, 113, 168, 66, 132, 202, 215, 105, 172, 182, 196, 75, 174, 215, 64, 201, 106, 49, 135, 200, 53, 84, 53, 18, 91, 110, 217, 134, 18, 204], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425299.2404714s }), end: Some(Instant { t: 1425299.2404714s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:42:05.948873Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:42:19.859261Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(5), peer: None, error: NoServer }
[2023-05-26T08:42:29.952886Z DEBUG safenode::node::api] No network activity in the past 24s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 25, 99, 4, 218, 184, 74, 198, 6, 89, 33, 30, 38, 91, 163, 172, 171, 99, 99, 23, 52, 159, 74, 147, 224, 188, 219, 59, 99, 199, 223, 174, 139])
[2023-05-26T08:42:29.952915Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 25, 99, 4, 218, 184, 74, 198, 6, 89, 33, 30, 38, 91, 163, 172, 171, 99, 99, 23, 52, 159, 74, 147, 224, 188, 219, 59, 99, 199, 223, 174, 139])
[2023-05-26T08:42:29.953037Z TRACE safenode::network::event] Query task QueryId(15) returned with peers GetClosestPeersOk { key: [0, 32, 25, 99, 4, 218, 184, 74, 198, 6, 89, 33, 30, 38, 91, 163, 172, 171, 99, 99, 23, 52, 159, 74, 147, 224, 188, 219, 59, 99, 199, 223, 174, 139], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425323.244662s }), end: Some(Instant { t: 1425323.244662s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:42:29.953064Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T08:42:49.954204Z DEBUG safenode::node::api] No network activity in the past 20s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 42, 140, 165, 224, 72, 82, 228, 32, 252, 188, 217, 3, 196, 112, 29, 32, 157, 219, 4, 47, 122, 229, 126, 164, 106, 158, 17, 139, 242, 61, 166, 29])
[2023-05-26T08:42:49.954229Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 42, 140, 165, 224, 72, 82, 228, 32, 252, 188, 217, 3, 196, 112, 29, 32, 157, 219, 4, 47, 122, 229, 126, 164, 106, 158, 17, 139, 242, 61, 166, 29])
[2023-05-26T08:42:49.954396Z TRACE safenode::network::event] Query task QueryId(16) returned with peers GetClosestPeersOk { key: [0, 32, 42, 140, 165, 224, 72, 82, 228, 32, 252, 188, 217, 3, 196, 112, 29, 32, 157, 219, 4, 47, 122, 229, 126, 164, 106, 158, 17, 139, 242, 61, 166, 29], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1425343.2460239s }), end: Some(Instant { t: 1425343.2460239s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T08:42:49.954422Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request

Vort · May 26, 2023, 9:01am

I don’t think so.
It looks more like you have found a bug.
Despite having errors with probing, node is not shutting down:
AutoNAT outbound probe: Error { probe_id: ProbeId(4), peer: None, error: NoServer }

Toivo · May 26, 2023, 9:15am

In that case a bit more knowledge: it was on Windows.

Tried again via my phone’s wifi hotspot. Similar results, logs:

Summary

[2023-05-26T09:43:17.725120Z DEBUG safenode] Current build's git commit hash: ---- No git commit hash found ----
[2023-05-26T09:43:17.725699Z WARN safenode::peers_acquisition] No SAFE_PEERS env var found. As `local-discovery` feature is disabled, we will not be able to connect to the network. 
[2023-05-26T09:43:17.725720Z INFO safenode] 
Running safenode v0.1.0
=======================
[2023-05-26T09:43:17.727164Z INFO safenode] Starting node ...
[2023-05-26T09:43:17.727366Z INFO safenode::network] Node (PID: 51852) with PeerId: 12D3KooWSCf9HBrrFixGnpkQtuN3PQG2pdVxS56u58emEc54BEx2
[2023-05-26T09:43:17.740934Z INFO safenode::network::event] Local node is listening on "/ip4/192.168.41.208/tcp/63463/p2p/12D3KooWSCf9HBrrFixGnpkQtuN3PQG2pdVxS56u58emEc54BEx2"
[2023-05-26T09:43:17.741012Z INFO safenode::network::event] Local node is listening on "/ip4/127.0.0.1/tcp/63463/p2p/12D3KooWSCf9HBrrFixGnpkQtuN3PQG2pdVxS56u58emEc54BEx2"
[2023-05-26T09:43:20.735966Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(0), peer: None, error: NoServer }
[2023-05-26T09:43:37.756896Z DEBUG safenode::node::api] No network activity in the past 20s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 94, 36, 42, 0, 204, 78, 28, 213, 113, 196, 240, 69, 229, 159, 12, 131, 237, 220, 203, 182, 243, 43, 97, 234, 120, 203, 143, 5, 164, 242, 11, 73])
[2023-05-26T09:43:37.757558Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 94, 36, 42, 0, 204, 78, 28, 213, 113, 196, 240, 69, 229, 159, 12, 131, 237, 220, 203, 182, 243, 43, 97, 234, 120, 203, 143, 5, 164, 242, 11, 73])
[2023-05-26T09:43:37.764018Z TRACE safenode::network::event] Query task QueryId(0) returned with peers GetClosestPeersOk { key: [0, 32, 94, 36, 42, 0, 204, 78, 28, 213, 113, 196, 240, 69, 229, 159, 12, 131, 237, 220, 203, 182, 243, 43, 97, 234, 120, 203, 143, 5, 164, 242, 11, 73], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1428994.166559s }), end: Some(Instant { t: 1428994.166559s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:43:37.767604Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:44:02.781794Z DEBUG safenode::node::api] No network activity in the past 25s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 164, 100, 173, 146, 175, 144, 111, 203, 166, 176, 231, 217, 226, 11, 79, 53, 70, 181, 188, 63, 152, 113, 103, 29, 145, 2, 52, 23, 0, 183, 30, 29])
[2023-05-26T09:44:02.781884Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 164, 100, 173, 146, 175, 144, 111, 203, 166, 176, 231, 217, 226, 11, 79, 53, 70, 181, 188, 63, 152, 113, 103, 29, 145, 2, 52, 23, 0, 183, 30, 29])
[2023-05-26T09:44:02.782217Z TRACE safenode::network::event] Query task QueryId(1) returned with peers GetClosestPeersOk { key: [0, 32, 164, 100, 173, 146, 175, 144, 111, 203, 166, 176, 231, 217, 226, 11, 79, 53, 70, 181, 188, 63, 152, 113, 103, 29, 145, 2, 52, 23, 0, 183, 30, 29], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429019.1865809s }), end: Some(Instant { t: 1429019.1865809s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:44:02.782260Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:44:34.783740Z DEBUG safenode::node::api] No network activity in the past 32s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 223, 24, 139, 219, 184, 56, 26, 179, 49, 129, 106, 16, 211, 38, 115, 252, 145, 192, 29, 197, 10, 209, 159, 195, 140, 194, 166, 90, 124, 26, 76, 135])
[2023-05-26T09:44:34.783788Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 223, 24, 139, 219, 184, 56, 26, 179, 49, 129, 106, 16, 211, 38, 115, 252, 145, 192, 29, 197, 10, 209, 159, 195, 140, 194, 166, 90, 124, 26, 76, 135])
[2023-05-26T09:44:34.783981Z TRACE safenode::network::event] Query task QueryId(2) returned with peers GetClosestPeersOk { key: [0, 32, 223, 24, 139, 219, 184, 56, 26, 179, 49, 129, 106, 16, 211, 38, 115, 252, 145, 192, 29, 197, 10, 209, 159, 195, 140, 194, 166, 90, 124, 26, 76, 135], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429051.1883624s }), end: Some(Instant { t: 1429051.1883624s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:44:34.784042Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:44:50.735083Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(1), peer: None, error: NoServer }
[2023-05-26T09:44:58.789962Z DEBUG safenode::node::api] No network activity in the past 24s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 75, 139, 244, 183, 95, 130, 79, 9, 221, 180, 170, 250, 179, 157, 138, 140, 14, 8, 151, 19, 69, 17, 177, 246, 174, 117, 105, 174, 11, 1, 35, 196])
[2023-05-26T09:44:58.790003Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 75, 139, 244, 183, 95, 130, 79, 9, 221, 180, 170, 250, 179, 157, 138, 140, 14, 8, 151, 19, 69, 17, 177, 246, 174, 117, 105, 174, 11, 1, 35, 196])
[2023-05-26T09:44:58.790208Z TRACE safenode::network::event] Query task QueryId(3) returned with peers GetClosestPeersOk { key: [0, 32, 75, 139, 244, 183, 95, 130, 79, 9, 221, 180, 170, 250, 179, 157, 138, 140, 14, 8, 151, 19, 69, 17, 177, 246, 174, 117, 105, 174, 11, 1, 35, 196], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429075.1945879s }), end: Some(Instant { t: 1429075.1945879s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:44:58.790271Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:45:33.793792Z DEBUG safenode::node::api] No network activity in the past 35s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 56, 23, 67, 216, 64, 150, 96, 229, 202, 240, 30, 47, 235, 205, 125, 185, 185, 114, 220, 36, 110, 173, 33, 6, 205, 15, 237, 190, 63, 188, 5, 209])
[2023-05-26T09:45:33.793958Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 56, 23, 67, 216, 64, 150, 96, 229, 202, 240, 30, 47, 235, 205, 125, 185, 185, 114, 220, 36, 110, 173, 33, 6, 205, 15, 237, 190, 63, 188, 5, 209])
[2023-05-26T09:45:33.794602Z TRACE safenode::network::event] Query task QueryId(4) returned with peers GetClosestPeersOk { key: [0, 32, 56, 23, 67, 216, 64, 150, 96, 229, 202, 240, 30, 47, 235, 205, 125, 185, 185, 114, 220, 36, 110, 173, 33, 6, 205, 15, 237, 190, 63, 188, 5, 209], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429110.1989206s }), end: Some(Instant { t: 1429110.1989206s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:45:33.794717Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:46:03.800584Z DEBUG safenode::node::api] No network activity in the past 30s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 74, 201, 10, 117, 226, 24, 226, 51, 31, 182, 87, 154, 17, 82, 110, 98, 55, 23, 163, 95, 185, 160, 228, 103, 82, 162, 212, 235, 214, 88, 12, 21])
[2023-05-26T09:46:03.800661Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 74, 201, 10, 117, 226, 24, 226, 51, 31, 182, 87, 154, 17, 82, 110, 98, 55, 23, 163, 95, 185, 160, 228, 103, 82, 162, 212, 235, 214, 88, 12, 21])
[2023-05-26T09:46:03.800975Z TRACE safenode::network::event] Query task QueryId(5) returned with peers GetClosestPeersOk { key: [0, 32, 74, 201, 10, 117, 226, 24, 226, 51, 31, 182, 87, 154, 17, 82, 110, 98, 55, 23, 163, 95, 185, 160, 228, 103, 82, 162, 212, 235, 214, 88, 12, 21], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429140.2053211s }), end: Some(Instant { t: 1429140.2053211s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:46:03.801079Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:46:20.744784Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(2), peer: None, error: NoServer }
[2023-05-26T09:46:39.815611Z DEBUG safenode::node::api] No network activity in the past 36s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 41, 137, 219, 240, 42, 255, 141, 56, 7, 60, 62, 244, 24, 9, 191, 79, 105, 35, 203, 115, 239, 200, 103, 106, 164, 73, 164, 192, 15, 109, 32, 164])
[2023-05-26T09:46:39.815694Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 41, 137, 219, 240, 42, 255, 141, 56, 7, 60, 62, 244, 24, 9, 191, 79, 105, 35, 203, 115, 239, 200, 103, 106, 164, 73, 164, 192, 15, 109, 32, 164])
[2023-05-26T09:46:39.815995Z TRACE safenode::network::event] Query task QueryId(6) returned with peers GetClosestPeersOk { key: [0, 32, 41, 137, 219, 240, 42, 255, 141, 56, 7, 60, 62, 244, 24, 9, 191, 79, 105, 35, 203, 115, 239, 200, 103, 106, 164, 73, 164, 192, 15, 109, 32, 164], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429176.220346s }), end: Some(Instant { t: 1429176.220346s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:46:39.816107Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:47:10.830822Z DEBUG safenode::node::api] No network activity in the past 31s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 41, 252, 222, 122, 251, 42, 202, 203, 5, 165, 32, 113, 55, 73, 108, 145, 95, 22, 53, 222, 166, 153, 73, 90, 27, 12, 146, 144, 110, 28, 70, 10])
[2023-05-26T09:47:10.830883Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 41, 252, 222, 122, 251, 42, 202, 203, 5, 165, 32, 113, 55, 73, 108, 145, 95, 22, 53, 222, 166, 153, 73, 90, 27, 12, 146, 144, 110, 28, 70, 10])
[2023-05-26T09:47:10.831176Z TRACE safenode::network::event] Query task QueryId(7) returned with peers GetClosestPeersOk { key: [0, 32, 41, 252, 222, 122, 251, 42, 202, 203, 5, 165, 32, 113, 55, 73, 108, 145, 95, 22, 53, 222, 166, 153, 73, 90, 27, 12, 146, 144, 110, 28, 70, 10], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429207.2355255s }), end: Some(Instant { t: 1429207.2355255s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:47:10.831279Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:47:41.838615Z DEBUG safenode::node::api] No network activity in the past 31s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 181, 32, 248, 132, 157, 132, 102, 49, 146, 228, 123, 129, 47, 180, 250, 73, 59, 19, 40, 36, 163, 208, 48, 219, 155, 55, 90, 4, 179, 122, 29, 198])
[2023-05-26T09:47:41.838689Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 181, 32, 248, 132, 157, 132, 102, 49, 146, 228, 123, 129, 47, 180, 250, 73, 59, 19, 40, 36, 163, 208, 48, 219, 155, 55, 90, 4, 179, 122, 29, 198])
[2023-05-26T09:47:41.839017Z TRACE safenode::network::event] Query task QueryId(8) returned with peers GetClosestPeersOk { key: [0, 32, 181, 32, 248, 132, 157, 132, 102, 49, 146, 228, 123, 129, 47, 180, 250, 73, 59, 19, 40, 36, 163, 208, 48, 219, 155, 55, 90, 4, 179, 122, 29, 198], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429238.2433686s }), end: Some(Instant { t: 1429238.2433686s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:47:41.839127Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:47:50.760641Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(3), peer: None, error: NoServer }
[2023-05-26T09:48:04.847380Z DEBUG safenode::node::api] No network activity in the past 23s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 127, 31, 67, 180, 167, 196, 230, 238, 64, 144, 73, 224, 253, 17, 82, 156, 198, 144, 71, 179, 25, 234, 120, 34, 25, 202, 152, 130, 17, 26, 47, 170])
[2023-05-26T09:48:04.847459Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 127, 31, 67, 180, 167, 196, 230, 238, 64, 144, 73, 224, 253, 17, 82, 156, 198, 144, 71, 179, 25, 234, 120, 34, 25, 202, 152, 130, 17, 26, 47, 170])
[2023-05-26T09:48:04.847757Z TRACE safenode::network::event] Query task QueryId(9) returned with peers GetClosestPeersOk { key: [0, 32, 127, 31, 67, 180, 167, 196, 230, 238, 64, 144, 73, 224, 253, 17, 82, 156, 198, 144, 71, 179, 25, 234, 120, 34, 25, 202, 152, 130, 17, 26, 47, 170], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429261.2521033s }), end: Some(Instant { t: 1429261.2521033s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:48:04.847851Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:48:40.854125Z DEBUG safenode::node::api] No network activity in the past 36s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 68, 248, 196, 163, 230, 61, 3, 147, 153, 13, 103, 21, 36, 38, 168, 156, 205, 134, 8, 80, 144, 192, 192, 156, 33, 156, 61, 149, 207, 240, 83, 106])
[2023-05-26T09:48:40.854183Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 68, 248, 196, 163, 230, 61, 3, 147, 153, 13, 103, 21, 36, 38, 168, 156, 205, 134, 8, 80, 144, 192, 192, 156, 33, 156, 61, 149, 207, 240, 83, 106])
[2023-05-26T09:48:40.854419Z TRACE safenode::network::event] Query task QueryId(10) returned with peers GetClosestPeersOk { key: [0, 32, 68, 248, 196, 163, 230, 61, 3, 147, 153, 13, 103, 21, 36, 38, 168, 156, 205, 134, 8, 80, 144, 192, 192, 156, 33, 156, 61, 149, 207, 240, 83, 106], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429297.2587889s }), end: Some(Instant { t: 1429297.2587889s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:48:40.854496Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:49:11.866762Z DEBUG safenode::node::api] No network activity in the past 31s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 159, 212, 96, 130, 225, 232, 38, 45, 53, 3, 183, 73, 24, 111, 4, 68, 98, 156, 66, 48, 239, 207, 8, 83, 164, 215, 207, 148, 12, 100, 208, 10])
[2023-05-26T09:49:11.866844Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 159, 212, 96, 130, 225, 232, 38, 45, 53, 3, 183, 73, 24, 111, 4, 68, 98, 156, 66, 48, 239, 207, 8, 83, 164, 215, 207, 148, 12, 100, 208, 10])
[2023-05-26T09:49:11.867072Z TRACE safenode::network::event] Query task QueryId(11) returned with peers GetClosestPeersOk { key: [0, 32, 159, 212, 96, 130, 225, 232, 38, 45, 53, 3, 183, 73, 24, 111, 4, 68, 98, 156, 66, 48, 239, 207, 8, 83, 164, 215, 207, 148, 12, 100, 208, 10], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429328.2714339s }), end: Some(Instant { t: 1429328.2714339s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:49:11.867174Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:49:20.772726Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(4), peer: None, error: NoServer }
[2023-05-26T09:49:46.875063Z DEBUG safenode::node::api] No network activity in the past 35s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 109, 204, 147, 8, 189, 129, 90, 228, 55, 95, 33, 69, 188, 152, 107, 41, 25, 210, 213, 161, 146, 61, 55, 201, 100, 39, 3, 125, 111, 149, 116, 46])
[2023-05-26T09:49:46.875144Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 109, 204, 147, 8, 189, 129, 90, 228, 55, 95, 33, 69, 188, 152, 107, 41, 25, 210, 213, 161, 146, 61, 55, 201, 100, 39, 3, 125, 111, 149, 116, 46])
[2023-05-26T09:49:46.875453Z TRACE safenode::network::event] Query task QueryId(12) returned with peers GetClosestPeersOk { key: [0, 32, 109, 204, 147, 8, 189, 129, 90, 228, 55, 95, 33, 69, 188, 152, 107, 41, 25, 210, 213, 161, 146, 61, 55, 201, 100, 39, 3, 125, 111, 149, 116, 46], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429363.2798129s }), end: Some(Instant { t: 1429363.2798129s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:49:46.875560Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:50:12.884437Z DEBUG safenode::node::api] No network activity in the past 26s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 11, 233, 5, 168, 83, 142, 147, 99, 123, 8, 164, 85, 53, 187, 149, 99, 225, 104, 156, 248, 114, 113, 199, 249, 150, 151, 159, 204, 250, 197, 8, 210])
[2023-05-26T09:50:12.884480Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 11, 233, 5, 168, 83, 142, 147, 99, 123, 8, 164, 85, 53, 187, 149, 99, 225, 104, 156, 248, 114, 113, 199, 249, 150, 151, 159, 204, 250, 197, 8, 210])
[2023-05-26T09:50:12.884645Z TRACE safenode::network::event] Query task QueryId(13) returned with peers GetClosestPeersOk { key: [0, 32, 11, 233, 5, 168, 83, 142, 147, 99, 123, 8, 164, 85, 53, 187, 149, 99, 225, 104, 156, 248, 114, 113, 199, 249, 150, 151, 159, 204, 250, 197, 8, 210], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429389.2890259s }), end: Some(Instant { t: 1429389.2890259s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:50:12.884685Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:50:43.891373Z DEBUG safenode::node::api] No network activity in the past 31s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 218, 111, 53, 0, 235, 8, 247, 228, 162, 0, 207, 61, 13, 234, 139, 244, 107, 232, 66, 178, 200, 32, 241, 161, 150, 34, 31, 163, 40, 215, 215, 190])
[2023-05-26T09:50:43.891427Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 218, 111, 53, 0, 235, 8, 247, 228, 162, 0, 207, 61, 13, 234, 139, 244, 107, 232, 66, 178, 200, 32, 241, 161, 150, 34, 31, 163, 40, 215, 215, 190])
[2023-05-26T09:50:43.891742Z TRACE safenode::network::event] Query task QueryId(14) returned with peers GetClosestPeersOk { key: [0, 32, 218, 111, 53, 0, 235, 8, 247, 228, 162, 0, 207, 61, 13, 234, 139, 244, 107, 232, 66, 178, 200, 32, 241, 161, 150, 34, 31, 163, 40, 215, 215, 190], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429420.2961171s }), end: Some(Instant { t: 1429420.2961171s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:50:43.891847Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:50:50.784115Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(5), peer: None, error: NoServer }
[2023-05-26T09:51:07.894323Z DEBUG safenode::node::api] No network activity in the past 24s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 138, 3, 102, 48, 53, 224, 201, 157, 81, 143, 186, 38, 162, 230, 202, 106, 20, 182, 140, 189, 46, 126, 204, 167, 174, 2, 88, 27, 112, 32, 145, 190])
[2023-05-26T09:51:07.894386Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 138, 3, 102, 48, 53, 224, 201, 157, 81, 143, 186, 38, 162, 230, 202, 106, 20, 182, 140, 189, 46, 126, 204, 167, 174, 2, 88, 27, 112, 32, 145, 190])
[2023-05-26T09:51:07.894731Z TRACE safenode::network::event] Query task QueryId(15) returned with peers GetClosestPeersOk { key: [0, 32, 138, 3, 102, 48, 53, 224, 201, 157, 81, 143, 186, 38, 162, 230, 202, 106, 20, 182, 140, 189, 46, 126, 204, 167, 174, 2, 88, 27, 112, 32, 145, 190], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429444.2990817s }), end: Some(Instant { t: 1429444.2990817s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:51:07.894855Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:51:37.905316Z DEBUG safenode::node::api] No network activity in the past 30s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 255, 246, 23, 168, 61, 59, 124, 7, 215, 79, 192, 161, 182, 153, 31, 117, 54, 228, 177, 78, 112, 130, 75, 56, 61, 169, 234, 230, 213, 34, 31, 152])
[2023-05-26T09:51:37.905401Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 255, 246, 23, 168, 61, 59, 124, 7, 215, 79, 192, 161, 182, 153, 31, 117, 54, 228, 177, 78, 112, 130, 75, 56, 61, 169, 234, 230, 213, 34, 31, 152])
[2023-05-26T09:51:37.905835Z TRACE safenode::network::event] Query task QueryId(16) returned with peers GetClosestPeersOk { key: [0, 32, 255, 246, 23, 168, 61, 59, 124, 7, 215, 79, 192, 161, 182, 153, 31, 117, 54, 228, 177, 78, 112, 130, 75, 56, 61, 169, 234, 230, 213, 34, 31, 152], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429474.3101635s }), end: Some(Instant { t: 1429474.3101635s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:51:37.905972Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:52:14.917102Z DEBUG safenode::node::api] No network activity in the past 37s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 153, 135, 168, 42, 182, 216, 133, 76, 115, 22, 207, 180, 42, 145, 207, 27, 99, 25, 7, 153, 85, 84, 244, 161, 158, 204, 202, 196, 91, 23, 190, 27])
[2023-05-26T09:52:14.917158Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 153, 135, 168, 42, 182, 216, 133, 76, 115, 22, 207, 180, 42, 145, 207, 27, 99, 25, 7, 153, 85, 84, 244, 161, 158, 204, 202, 196, 91, 23, 190, 27])
[2023-05-26T09:52:14.917326Z TRACE safenode::network::event] Query task QueryId(17) returned with peers GetClosestPeersOk { key: [0, 32, 153, 135, 168, 42, 182, 216, 133, 76, 115, 22, 207, 180, 42, 145, 207, 27, 99, 25, 7, 153, 85, 84, 244, 161, 158, 204, 202, 196, 91, 23, 190, 27], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429511.3217117s }), end: Some(Instant { t: 1429511.3217117s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:52:14.917372Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:52:20.791565Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(6), peer: None, error: NoServer }
[2023-05-26T09:52:45.922396Z DEBUG safenode::node::api] No network activity in the past 31s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 206, 220, 122, 133, 96, 148, 103, 1, 88, 91, 136, 142, 34, 149, 156, 142, 119, 154, 123, 89, 90, 255, 212, 18, 140, 183, 206, 42, 159, 216, 131, 249])
[2023-05-26T09:52:45.922467Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 206, 220, 122, 133, 96, 148, 103, 1, 88, 91, 136, 142, 34, 149, 156, 142, 119, 154, 123, 89, 90, 255, 212, 18, 140, 183, 206, 42, 159, 216, 131, 249])
[2023-05-26T09:52:45.922814Z TRACE safenode::network::event] Query task QueryId(18) returned with peers GetClosestPeersOk { key: [0, 32, 206, 220, 122, 133, 96, 148, 103, 1, 88, 91, 136, 142, 34, 149, 156, 142, 119, 154, 123, 89, 90, 255, 212, 18, 140, 183, 206, 42, 159, 216, 131, 249], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429542.3271544s }), end: Some(Instant { t: 1429542.3271544s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:52:45.922924Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:53:11.926656Z DEBUG safenode::node::api] No network activity in the past 26s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 101, 25, 142, 38, 154, 245, 203, 201, 63, 169, 105, 97, 180, 90, 156, 71, 141, 93, 164, 64, 188, 251, 69, 41, 59, 189, 134, 1, 178, 24, 61, 225])
[2023-05-26T09:53:11.926750Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 101, 25, 142, 38, 154, 245, 203, 201, 63, 169, 105, 97, 180, 90, 156, 71, 141, 93, 164, 64, 188, 251, 69, 41, 59, 189, 134, 1, 178, 24, 61, 225])
[2023-05-26T09:53:11.927084Z TRACE safenode::network::event] Query task QueryId(19) returned with peers GetClosestPeersOk { key: [0, 32, 101, 25, 142, 38, 154, 245, 203, 201, 63, 169, 105, 97, 180, 90, 156, 71, 141, 93, 164, 64, 188, 251, 69, 41, 59, 189, 134, 1, 178, 24, 61, 225], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429568.331439s }), end: Some(Instant { t: 1429568.331439s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:53:11.927197Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:53:38.941119Z DEBUG safenode::node::api] No network activity in the past 27s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 98, 209, 96, 70, 203, 6, 76, 21, 53, 7, 73, 134, 167, 159, 235, 254, 28, 137, 231, 54, 169, 244, 144, 178, 14, 159, 54, 100, 74, 98, 72, 246])
[2023-05-26T09:53:38.941179Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 98, 209, 96, 70, 203, 6, 76, 21, 53, 7, 73, 134, 167, 159, 235, 254, 28, 137, 231, 54, 169, 244, 144, 178, 14, 159, 54, 100, 74, 98, 72, 246])
[2023-05-26T09:53:38.941406Z TRACE safenode::network::event] Query task QueryId(20) returned with peers GetClosestPeersOk { key: [0, 32, 98, 209, 96, 70, 203, 6, 76, 21, 53, 7, 73, 134, 167, 159, 235, 254, 28, 137, 231, 54, 169, 244, 144, 178, 14, 159, 54, 100, 74, 98, 72, 246], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429595.3457779s }), end: Some(Instant { t: 1429595.3457779s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:53:38.941456Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:53:50.797871Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(7), peer: None, error: NoServer }
[2023-05-26T09:54:17.948181Z DEBUG safenode::node::api] No network activity in the past 39s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 36, 221, 31, 155, 59, 239, 213, 8, 157, 41, 77, 31, 31, 220, 245, 68, 162, 153, 248, 215, 191, 12, 194, 171, 59, 79, 127, 23, 129, 15, 76, 2])
[2023-05-26T09:54:17.948254Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 36, 221, 31, 155, 59, 239, 213, 8, 157, 41, 77, 31, 31, 220, 245, 68, 162, 153, 248, 215, 191, 12, 194, 171, 59, 79, 127, 23, 129, 15, 76, 2])
[2023-05-26T09:54:17.948620Z TRACE safenode::network::event] Query task QueryId(21) returned with peers GetClosestPeersOk { key: [0, 32, 36, 221, 31, 155, 59, 239, 213, 8, 157, 41, 77, 31, 31, 220, 245, 68, 162, 153, 248, 215, 191, 12, 194, 171, 59, 79, 127, 23, 129, 15, 76, 2], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429634.3528929s }), end: Some(Instant { t: 1429634.3528929s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:54:17.948694Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request
[2023-05-26T09:54:56.962630Z DEBUG safenode::node::api] No network activity in the past 39s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 255, 47, 8, 44, 127, 250, 31, 52, 34, 247, 241, 83, 159, 221, 38, 225, 99, 226, 219, 143, 69, 0, 177, 124, 143, 74, 243, 45, 67, 242, 138, 30])
[2023-05-26T09:54:56.962696Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 255, 47, 8, 44, 127, 250, 31, 52, 34, 247, 241, 83, 159, 221, 38, 225, 99, 226, 219, 143, 69, 0, 177, 124, 143, 74, 243, 45, 67, 242, 138, 30])
[2023-05-26T09:54:56.962978Z TRACE safenode::network::event] Query task QueryId(22) returned with peers GetClosestPeersOk { key: [0, 32, 255, 47, 8, 44, 127, 250, 31, 52, 34, 247, 241, 83, 159, 221, 38, 225, 99, 226, 219, 143, 69, 0, 177, 124, 143, 74, 243, 45, 67, 242, 138, 30], peers: [] }, QueryStats { requests: 0, success: 0, failure: 0, start: Some(Instant { t: 1429673.3673366s }), end: Some(Instant { t: 1429673.3673366s }) } - ProgressStep { count: 1, last: true }
[2023-05-26T09:54:56.963057Z WARN safenode::network] Not enough peers in the k-bucket to satisfy the request

JPL · May 26, 2023, 10:23am

The node is not shutting down for me. I tried with both a VPN and with no VPN. Linux Mint.

Logs

VPN
====
user@Mint-user:~$ cat /tmp/safenode/safenode.log 
[2023-05-26T09:21:43.737748Z INFO safenode] 
Running safenode v0.1.0
=======================
[2023-05-26T09:21:43.743067Z INFO safenode] Starting node ...
[2023-05-26T09:21:43.747871Z INFO safenode::network] Node (PID: 32053) with PeerId: 12D3KooWQgNdPjMMFmkhuDD9MXXH3CkR1zs1NeQ19VPniEcJ4XCU
[2023-05-26T09:21:43.769660Z DEBUG safenode::domain::fees::spend_queue] Starting fee: current_fee 4000.
[2023-05-26T09:21:43.769670Z DEBUG safenode::domain::fees::spend_queue] stats: highest 10494, high 8000, medium_high 6333, avg 4667, medium_low 3333, low 2000, lowest 1662, std_dev 2494, len 3, queue [8000, 4000, 2000]
[2023-05-26T09:21:43.772480Z TRACE safenode::network] received a swarm event Some(NewListenAddr { listener_id: ListenerId(5171242616954037405), address: "/ip4/127.0.0.1/udp/37372/quic-v1" })
[2023-05-26T09:21:43.772504Z INFO safenode::network::event] Local node is listening on "/ip4/127.0.0.1/udp/37372/quic-v1/p2p/12D3KooWQgNdPjMMFmkhuDD9MXXH3CkR1zs1NeQ19VPniEcJ4XCU"
[2023-05-26T09:21:43.772529Z TRACE safenode::network] received a swarm event Some(NewListenAddr { listener_id: ListenerId(5171242616954037405), address: "/ip4/192.168.1.181/udp/37372/quic-v1" })
[2023-05-26T09:21:43.772535Z INFO safenode::network::event] Local node is listening on "/ip4/192.168.1.181/udp/37372/quic-v1/p2p/12D3KooWQgNdPjMMFmkhuDD9MXXH3CkR1zs1NeQ19VPniEcJ4XCU"
[2023-05-26T09:21:43.772550Z TRACE safenode::network] received a swarm event Some(NewListenAddr { listener_id: ListenerId(5171242616954037405), address: "/ip4/192.168.1.102/udp/37372/quic-v1" })
[2023-05-26T09:21:43.772553Z INFO safenode::network::event] Local node is listening on "/ip4/192.168.1.102/udp/37372/quic-v1/p2p/12D3KooWQgNdPjMMFmkhuDD9MXXH3CkR1zs1NeQ19VPniEcJ4XCU"
[2023-05-26T09:21:43.772567Z TRACE safenode::network] received a swarm event Some(NewListenAddr { listener_id: ListenerId(5171242616954037405), address: "/ip4/172.17.0.1/udp/37372/quic-v1" })
[2023-05-26T09:21:43.772572Z INFO safenode::network::event] Local node is listening on "/ip4/172.17.0.1/udp/37372/quic-v1/p2p/12D3KooWQgNdPjMMFmkhuDD9MXXH3CkR1zs1NeQ19VPniEcJ4XCU"
[2023-05-26T09:21:43.772585Z TRACE safenode::network] received a swarm event Some(NewListenAddr { listener_id: ListenerId(5171242616954037405), address: "/ip4/10.10.42.98/udp/37372/quic-v1" })
[2023-05-26T09:21:43.772590Z INFO safenode::network::event] Local node is listening on "/ip4/10.10.42.98/udp/37372/quic-v1/p2p/12D3KooWQgNdPjMMFmkhuDD9MXXH3CkR1zs1NeQ19VPniEcJ4XCU"


No VPN
======

user@Mint-user:~$ cat /tmp/safenode2/safenode.log 
[2023-05-26T10:10:55.040617Z INFO safenode] 
Running safenode v0.1.0
=======================
[2023-05-26T10:10:55.041413Z INFO safenode] Starting node ...
[2023-05-26T10:10:55.041498Z INFO safenode::network] Node (PID: 43587) with PeerId: 12D3KooWALuq5cYgk6sXANZkSSq6ix9BFKtQiwdNQE18i5joMhr3
[2023-05-26T10:10:55.042774Z DEBUG safenode::domain::fees::spend_queue] Starting fee: current_fee 4000.
[2023-05-26T10:10:55.042779Z DEBUG safenode::domain::fees::spend_queue] stats: highest 10494, high 8000, medium_high 6333, avg 4667, medium_low 3333, low 2000, lowest 1662, std_dev 2494, len 3, queue [8000, 4000, 2000]
[2023-05-26T10:10:55.043358Z TRACE safenode::network] received a swarm event Some(NewListenAddr { listener_id: ListenerId(32148781590066033), address: "/ip4/127.0.0.1/udp/45295/quic-v1" })
[2023-05-26T10:10:55.043377Z INFO safenode::network::event] Local node is listening on "/ip4/127.0.0.1/udp/45295/quic-v1/p2p/12D3KooWALuq5cYgk6sXANZkSSq6ix9BFKtQiwdNQE18i5joMhr3"
[2023-05-26T10:10:55.043410Z TRACE safenode::network] received a swarm event Some(NewListenAddr { listener_id: ListenerId(32148781590066033), address: "/ip4/192.168.1.181/udp/45295/quic-v1" })
[2023-05-26T10:10:55.043419Z INFO safenode::network::event] Local node is listening on "/ip4/192.168.1.181/udp/45295/quic-v1/p2p/12D3KooWALuq5cYgk6sXANZkSSq6ix9BFKtQiwdNQE18i5joMhr3"
[2023-05-26T10:10:55.043446Z TRACE safenode::network] received a swarm event Some(NewListenAddr { listener_id: ListenerId(32148781590066033), address: "/ip4/192.168.1.102/udp/45295/quic-v1" })
[2023-05-26T10:10:55.043453Z INFO safenode::network::event] Local node is listening on "/ip4/192.168.1.102/udp/45295/quic-v1/p2p/12D3KooWALuq5cYgk6sXANZkSSq6ix9BFKtQiwdNQE18i5joMhr3"
[2023-05-26T10:10:55.043479Z TRACE safenode::network] received a swarm event Some(NewListenAddr { listener_id: ListenerId(32148781590066033), address: "/ip4/172.17.0.1/udp/45295/quic-v1" })
[2023-05-26T10:10:55.043485Z INFO safenode::network::event] Local node is listening on "/ip4/172.17.0.1/udp/45295/quic-v1/p2p/12D3KooWALuq5cYgk6sXANZkSSq6ix9BFKtQiwdNQE18i5joMhr3"

Edit - tried again from the download directory rather than using the binary I copied to /usr/local/bin, so
./safenode --log-dir=/tmp/safenode4 --root-dir=/tmp/safenodedata4

This time the log looks very different.

Summary

user@Mint-user:~$ cat /tmp/safenode4/safenode.log 
==========
last few lines
==========
 110, 20, 47])
[2023-05-26T10:30:36.349502Z TRACE safenode::network::event] Query task QueryId(2) returned with peers GetClosestPeersOk { key: [0, 32, 150, 149, 199, 0, 250, 77, 44, 195, 13, 34, 169, 154, 249, 12, 28, 158, 9, 180, 190, 1, 221, 28, 23, 215, 10, 78, 51, 205, 55, 110, 20, 47], peers: [PeerId("12D3KooWCv2tmdf36tBhtnnWPvz6LuDoA2uz4QVArjfttVDfG9Yr"), PeerId("12D3KooWNWaEZZuwxxSoXA8femh9ShianvKXNAQvZhvNVmpCnoU8"), PeerId("12D3KooWCCPGiEdyM5N6dMijXE9i42Kbq7ZNCfHkDWLeUckEpveJ"), PeerId("12D3KooWNahaJmDLtZasmQqPB4jhotMhZ47iFGFgdvV7wNuZdmak"), PeerId("12D3KooWFLvPUtXD7v6rmiGSyMJdt3w4eM466AwJRe3nnUeCY4g4"), PeerId("12D3KooWPyUPc1DZKkrN5MnXeEUAxLg5v3DfKajZnrz5gHhUor8g"), PeerId("12D3KooWDr4eh5RTZX7t9X3Xm2SwXpe9BA9Qbp4b13AiGJtrEhC2"), PeerId("12D3KooWGRmfEFqZNNrPJVLa97CsDaVKeFedvnFfP45UxRpqc31n")] }, QueryStats { requests: 12, success: 12, failure: 0, start: Some(Instant { tv_sec: 11476, tv_nsec: 974441740 }), end: Some(Instant { tv_sec: 11478, tv_nsec: 28567532 }) } - ProgressStep { count: 1, last: true }
[2023-05-26T10:30:36.349606Z DEBUG safenode::node::api] Network inactivity: get_closest returned [PeerId("12D3KooWCv2tmdf36tBhtnnWPvz6LuDoA2uz4QVArjfttVDfG9Yr"), PeerId("12D3KooWLRoruwz1bRfGEfRo33yziyCP5XH6pGfAyYm8ZA6r1WeN"), PeerId("12D3KooWNWaEZZuwxxSoXA8femh9ShianvKXNAQvZhvNVmpCnoU8"), PeerId("12D3KooWCCPGiEdyM5N6dMijXE9i42Kbq7ZNCfHkDWLeUckEpveJ"), PeerId("12D3KooWNahaJmDLtZasmQqPB4jhotMhZ47iFGFgdvV7wNuZdmak"), PeerId("12D3KooWFLvPUtXD7v6rmiGSyMJdt3w4eM466AwJRe3nnUeCY4g4"), PeerId("12D3KooWPyUPc1DZKkrN5MnXeEUAxLg5v3DfKajZnrz5gHhUor8g"), PeerId("12D3KooWDr4eh5RTZX7t9X3Xm2SwXpe9BA9Qbp4b13AiGJtrEhC2")]
[2023-05-26T10:30:49.791869Z TRACE safenode::network::event] AutoNAT outbound probe: Request { probe_id: ProbeId(1), peer: PeerId("12D3KooWRNtxc4z3dfd7poZeGjLyrxi4G22H1XBVpMP6v8GohSVx") }

digipl · May 26, 2023, 10:56am

Error: We have been determined to be behind a NAT. This means we are not reachable externally by other nodes. In the future, the network will implement relays that allow us to still join the network.

[2023-05-26T10:43:03.856939Z INFO safenode] Starting node …
[2023-05-26T10:43:03.857394Z INFO safenode::network] Node (PID: 5560) with PeerId: 12D3KooWM1Hc455VSMcLiPvpkfEZKAZ9L5fCx1991dQ3kHVR1Tev

happybeing · May 26, 2023, 11:00am

Using my mobile broadband hotspot it looks like it believes this is behind a NAT and my node does shut down:

Final part of the log:

[2023-05-26T10:48:21.449423Z INFO safenode::network::event] AutoNAT status changed: Unknown -> Private
[2023-05-26T10:48:21.449457Z WARN safenode::node::api] NAT status is determined to be private!
[2023-05-26T10:48:21.449548Z INFO safenode] Node is stopping in 1s...

safenode:

Error: We have been determined to be behind a NAT. This means we are not reachable externally by other nodes. In the future, the network will implement relays that allow us to still join the network.

The PeerID from the first line in the logfile (is that what you want?) is:
12D3KooWGfCyys3X7f7NSc28239oFbsaKqSFFvRVLkuiXsCpBpCF

Full log:
safenode-log.zip (5.7 KB)

bzee · May 26, 2023, 11:58am

In your phone hotspot testcase the second log line shows you didn’t provide a peer to contact:

[2023-05-26T09:43:17.725699Z WARN safenode::peers_acquisition] No SAFE_PEERS env var found. As `local-discovery` feature is disabled, we will not be able to connect to the network.

Your first test on the public Helsinki wifi is interesting, it seems it can’t connect to the bootstrap node:

[2023-05-26T08:35:08.991266Z WARN safenode::network::event] Having OutgoingConnectionError Some(PeerId("12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2")) - Transport([("/ip4/206.189.29.202/tcp/38379/p2p/12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2", Other(Custom { kind: Other, error: Right(Select(ProtocolError(IoError(Os { code: 10054, kind: ConnectionReset, message: "An existing connection was forcibly closed by the remote host." })))) }))])
[2023-05-26T08:35:08.991649Z ERROR safenode::node::api] Failed to dial 12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2: DialError(Transport([("/ip4/206.189.29.202/tcp/38379/p2p/12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2", Other(Custom { kind: Other, error: Right(Select(ProtocolError(IoError(Os { code: 10054, kind: ConnectionReset, message: "An existing connection was forcibly closed by the remote host." })))) }))]))

Notably:

An existing connection was forcibly closed by the remote host

Because of that it can’t do any probing, similar to your phone hotspot scenario.

bzee · May 26, 2023, 12:07pm

That’s not your peer ID, it’s normally the 6th line and looks like this in your case:

[2023-05-26T10:46:08.539272Z INFO safenode::network] Node (PID: 121950) with PeerId: 12D3KooW9x8YYToqEbuohAjCzfwX6d4FnkgPRfA6VTw4NPGsJeML

Looking at the logs of the testnet nodes, it seems your node was ‘successfully’ not added to the network. Good news!

Josh · May 26, 2023, 12:41pm

I did not get this Error, instead it stopped at:

[2023-05-26T12:30:01.277531Z WARN safenode::node::api] NAT status is determined to be private!
[2023-05-26T12:30:01.277650Z INFO safenode] Node is stopping in 1s…

Logs

[2023-05-26T12:28:36.303932Z DEBUG safenode::node::api] PeerAdded: 12D3KooWGRmfEFqZNNrPJVLa97CsDaVKeFedvnFfP45UxRpqc31n [2023-05-26T12:28:36.303953Z DEBUG safenode::node::api] PeerAdded: 12D3KooWFLvPUtXD7v6rmiGSyMJdt3w4eM466AwJRe3nnUeCY4g4 [2023-05-26T12:28:36.303965Z DEBUG safenode::node::api] PeerAdded: 12D3KooWA3HaETuj7daLyKk87WfdLNBhX7mci91ocS8pfNAkV8qW [2023-05-26T12:28:36.303979Z DEBUG safenode::node::api] PeerAdded: 12D3KooWBKmpRtnvn2DnFLsB8zaqe5MkYsuyFfCeYo8qxCoyBp7v [2023-05-26T12:28:36.303993Z DEBUG safenode::node::api] PeerAdded: 12D3KooWPyUPc1DZKkrN5MnXeEUAxLg5v3DfKajZnrz5gHhUor8g [2023-05-26T12:28:36.304008Z DEBUG safenode::node::api] PeerAdded: 12D3KooWRNtxc4z3dfd7poZeGjLyrxi4G22H1XBVpMP6v8GohSVx [2023-05-26T12:28:36.304024Z DEBUG safenode::node::api] PeerAdded: 12D3KooWNahaJmDLtZasmQqPB4jhotMhZ47iFGFgdvV7wNuZdmak [2023-05-26T12:28:36.475506Z DEBUG safenode::node::api] PeerAdded: 12D3KooWNWaEZZuwxxSoXA8femh9ShianvKXNAQvZhvNVmpCnoU8 [2023-05-26T12:28:36.841824Z INFO safenode::network::event] identify: received info peer_id=12D3KooWNWaEZZuwxxSoXA8femh9ShianvKXNAQvZhvNVmpCnoU8 info=Info { public_key: Ed25519(PublicKey(compressed): bc991e4c495adaae473d757fa594bee010a910d3d8aecb79afd45934d59163b5), protocol_version: "safe/0.1.0", agent_version: "safe/node/0.1.0", listen_addrs: ["/ip4/10.131.0.22/tcp/38943", "/ip4/127.0.0.1/tcp/38943", "/ip4/138.68.179.78/tcp/38943", "/ip4/10.16.0.26/tcp/38943", "/ip4/138.68.179.78/tcp/38943", "/ip4/10.131.0.22/tcp/38943"], protocols: ["/safe/1", "/ipfs/kad/1.0.0", "/ipfs/id/1.0.0", "/ipfs/id/push/1.0.0", "/libp2p/autonat/1.0.0"], observed_addr: "/ip4/47.207.67.50/tcp/35124" } [2023-05-26T12:28:36.841921Z INFO safenode::network::event] identify: adding addresses to routing table peer_id=12D3KooWNWaEZZuwxxSoXA8femh9ShianvKXNAQvZhvNVmpCnoU8 addrs=["/ip4/138.68.179.78/tcp/38943"] [2023-05-26T12:29:04.476798Z DEBUG safenode::node::api] No network activity in the past 28s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 172, 146, 64, 129, 122, 22, 55, 74, 233, 87, 146, 176, 178, 155, 23, 83, 206, 14, 174, 242, 211, 138, 201, 138, 179, 43, 18, 174, 23, 30, 82, 206]) [2023-05-26T12:29:04.476837Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 172, 146, 64, 129, 122, 22, 55, 74, 233, 87, 146, 176, 178, 155, 23, 83, 206, 14, 174, 242, 211, 138, 201, 138, 179, 43, 18, 174, 23, 30, 82, 206]) [2023-05-26T12:29:05.284711Z INFO safenode::network::event] Dialing 12D3KooWDRmefn1drUhjHxcnE4WjkSsbiwS82iK7Uu9zRBvS1Jch [2023-05-26T12:29:05.284825Z INFO safenode::network::event] Dialing 12D3KooWNorWTvVHWh7q7SLNqiPsXF6DPg8i6g67heNyrokaQYtU [2023-05-26T12:29:05.399099Z WARN safenode::network::event] Having OutgoingConnectionError Some(PeerId("12D3KooWDRmefn1drUhjHxcnE4WjkSsbiwS82iK7Uu9zRBvS1Jch")) - Transport([("/ip4/178.128.47.181/tcp/42735/p2p/12D3KooWDRmefn1drUhjHxcnE4WjkSsbiwS82iK7Uu9zRBvS1Jch", Other(Custom { kind: Other, error: Left(Left(Os { code: 111, kind: ConnectionRefused, message: "Connection refused" })) }))]) [2023-05-26T12:29:05.399190Z INFO safenode::network::event] OutgoingConnectionError is due to non pending_dial to 12D3KooWDRmefn1drUhjHxcnE4WjkSsbiwS82iK7Uu9zRBvS1Jch [2023-05-26T12:29:06.462903Z INFO safenode::network::event] Connected with PeerId("12D3KooWNorWTvVHWh7q7SLNqiPsXF6DPg8i6g67heNyrokaQYtU") [2023-05-26T12:29:06.463426Z INFO safenode::network::event] identify: Sent { peer_id: PeerId("12D3KooWNorWTvVHWh7q7SLNqiPsXF6DPg8i6g67heNyrokaQYtU") } [2023-05-26T12:29:07.264288Z TRACE safenode::network::event] Query task QueryId(2) returned with peers GetClosestPeersOk { key: [0, 32, 172, 146, 64, 129, 122, 22, 55, 74, 233, 87, 146, 176, 178, 155, 23, 83, 206, 14, 174, 242, 211, 138, 201, 138, 179, 43, 18, 174, 23, 30, 82, 206], peers: [PeerId("12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2"), PeerId("12D3KooWA3iCfANYfqdijMznXdLXEtT4LrktJMj8goRePdyMWSws"), PeerId("12D3KooWGfCyys3X7f7NSc28239oFbsaKqSFFvRVLkuiXsCpBpCF"), PeerId("12D3KooWQvyuDKvca4qxQf9ncheY1S7xYewbfdAstap6PZpS4xqX"), PeerId("12D3KooWPgALRGTEznXYsw2soNn33wZd7VkzW4xzz68Q4b157wbp"), PeerId("12D3KooWT3Ne7cGAtbcKe9kYoPJ5PGjNCBxtMKhfvZUSEfjN3G1J"), PeerId("12D3KooWPuLcWkz2Uc5HKiuCapPzDMSSe7vfykqfLgALzKWZ9JEd"), PeerId("12D3KooWNorWTvVHWh7q7SLNqiPsXF6DPg8i6g67heNyrokaQYtU")] }, QueryStats { requests: 19, success: 17, failure: 1, start: Some(Instant { tv_sec: 759, tv_nsec: 669068871 }), end: Some(Instant { tv_sec: 762, tv_nsec: 456257374 }) } - ProgressStep { count: 1, last: true } [2023-05-26T12:29:07.264476Z INFO safenode::network::event] identify: received info peer_id=12D3KooWNorWTvVHWh7q7SLNqiPsXF6DPg8i6g67heNyrokaQYtU info=Info { public_key: Ed25519(PublicKey(compressed): c1664b4d48383abcdb98574e1673284835cc3876433142546bdd72f3d5dd8d), protocol_version: "safe/0.1.0", agent_version: "safe/node/0.1.0", listen_addrs: ["/ip4/138.68.151.254/tcp/39679", "/ip4/127.0.0.1/tcp/39679", "/ip4/10.131.0.13/tcp/39679", "/ip4/10.16.0.17/tcp/39679", "/ip4/138.68.151.254/tcp/39679", "/ip4/10.131.0.13/tcp/39679"], protocols: ["/safe/1", "/ipfs/kad/1.0.0", "/ipfs/id/1.0.0", "/ipfs/id/push/1.0.0", "/libp2p/autonat/1.0.0"], observed_addr: "/ip4/47.207.67.50/tcp/39060" } [2023-05-26T12:29:07.264585Z INFO safenode::network::event] identify: adding addresses to routing table peer_id=12D3KooWNorWTvVHWh7q7SLNqiPsXF6DPg8i6g67heNyrokaQYtU addrs=["/ip4/138.68.151.254/tcp/39679"] [2023-05-26T12:29:07.264750Z DEBUG safenode::node::api] Network inactivity: get_closest returned [PeerId("12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2"), PeerId("12D3KooWA3iCfANYfqdijMznXdLXEtT4LrktJMj8goRePdyMWSws"), PeerId("12D3KooWGfCyys3X7f7NSc28239oFbsaKqSFFvRVLkuiXsCpBpCF"), PeerId("12D3KooWLRCA3kKH3NrYTnfJYXbDYy92wNTbALphYrLFJmdzQL8Z"), PeerId("12D3KooWQvyuDKvca4qxQf9ncheY1S7xYewbfdAstap6PZpS4xqX"), PeerId("12D3KooWPgALRGTEznXYsw2soNn33wZd7VkzW4xzz68Q4b157wbp"), PeerId("12D3KooWT3Ne7cGAtbcKe9kYoPJ5PGjNCBxtMKhfvZUSEfjN3G1J"), PeerId("12D3KooWPuLcWkz2Uc5HKiuCapPzDMSSe7vfykqfLgALzKWZ9JEd")] [2023-05-26T12:29:07.264862Z DEBUG safenode::node::api] PeerAdded: 12D3KooWNorWTvVHWh7q7SLNqiPsXF6DPg8i6g67heNyrokaQYtU [2023-05-26T12:29:20.346774Z TRACE safenode::network::event] AutoNAT outbound probe: Request { probe_id: ProbeId(1), peer: PeerId("12D3KooWQvyuDKvca4qxQf9ncheY1S7xYewbfdAstap6PZpS4xqX") } [2023-05-26T12:29:44.265741Z DEBUG safenode::node::api] No network activity in the past 37s, performing a random get_closest query to target: NetworkAddress::PeerId([0, 32, 56, 173, 80, 214, 9, 28, 125, 53, 224, 144, 254, 202, 55, 83, 114, 168, 238, 171, 160, 201, 60, 101, 159, 224, 183, 79, 174, 197, 130, 161, 11, 234]) [2023-05-26T12:29:44.265780Z DEBUG safenode::network] Getting the closest peers to NetworkAddress::PeerId([0, 32, 56, 173, 80, 214, 9, 28, 125, 53, 224, 144, 254, 202, 55, 83, 114, 168, 238, 171, 160, 201, 60, 101, 159, 224, 183, 79, 174, 197, 130, 161, 11, 234]) [2023-05-26T12:29:45.053878Z INFO safenode::network::event] Dialing 12D3KooWCCPGiEdyM5N6dMijXE9i42Kbq7ZNCfHkDWLeUckEpveJ [2023-05-26T12:29:46.207229Z INFO safenode::network::event] Connected with PeerId("12D3KooWCCPGiEdyM5N6dMijXE9i42Kbq7ZNCfHkDWLeUckEpveJ") [2023-05-26T12:29:46.207700Z INFO safenode::network::event] identify: Sent { peer_id: PeerId("12D3KooWCCPGiEdyM5N6dMijXE9i42Kbq7ZNCfHkDWLeUckEpveJ") } [2023-05-26T12:29:46.640115Z INFO safenode::network::event] Dialing 12D3KooWCzzdEYhARxj3nndhwYoU17AXHKwFx9fhAJfF47Wwb1mw [2023-05-26T12:29:46.988575Z INFO safenode::network::event] identify: received info peer_id=12D3KooWCCPGiEdyM5N6dMijXE9i42Kbq7ZNCfHkDWLeUckEpveJ info=Info { public_key: Ed25519(PublicKey(compressed): 235b66106d57f5a24524a21e9cb3f748e2519120cde152be7e77ac0e528272f), protocol_version: "safe/0.1.0", agent_version: "safe/node/0.1.0", listen_addrs: ["/ip4/138.68.151.223/tcp/46803", "/ip4/127.0.0.1/tcp/46803", "/ip4/10.16.0.15/tcp/46803", "/ip4/10.131.0.11/tcp/46803", "/ip4/138.68.151.223/tcp/46803", "/ip4/10.131.0.11/tcp/46803"], protocols: ["/safe/1", "/ipfs/kad/1.0.0", "/ipfs/id/1.0.0", "/ipfs/id/push/1.0.0", "/libp2p/autonat/1.0.0"], observed_addr: "/ip4/47.207.67.50/tcp/43432" } [2023-05-26T12:29:46.988672Z INFO safenode::network::event] identify: adding addresses to routing table peer_id=12D3KooWCCPGiEdyM5N6dMijXE9i42Kbq7ZNCfHkDWLeUckEpveJ addrs=["/ip4/138.68.151.223/tcp/46803"] [2023-05-26T12:29:57.976581Z TRACE safenode::network::event] Query task QueryId(3) returned with peers GetClosestPeersOk { key: [0, 32, 56, 173, 80, 214, 9, 28, 125, 53, 224, 144, 254, 202, 55, 83, 114, 168, 238, 171, 160, 201, 60, 101, 159, 224, 183, 79, 174, 197, 130, 161, 11, 234], peers: [PeerId("12D3KooWA3HaETuj7daLyKk87WfdLNBhX7mci91ocS8pfNAkV8qW"), PeerId("12D3KooWBKmpRtnvn2DnFLsB8zaqe5MkYsuyFfCeYo8qxCoyBp7v"), PeerId("12D3KooWRNtxc4z3dfd7poZeGjLyrxi4G22H1XBVpMP6v8GohSVx"), PeerId("12D3KooWGRmfEFqZNNrPJVLa97CsDaVKeFedvnFfP45UxRpqc31n"), PeerId("12D3KooWDr4eh5RTZX7t9X3Xm2SwXpe9BA9Qbp4b13AiGJtrEhC2"), PeerId("12D3KooWPyUPc1DZKkrN5MnXeEUAxLg5v3DfKajZnrz5gHhUor8g"), PeerId("12D3KooWFLvPUtXD7v6rmiGSyMJdt3w4eM466AwJRe3nnUeCY4g4"), PeerId("12D3KooWNahaJmDLtZasmQqPB4jhotMhZ47iFGFgdvV7wNuZdmak")] }, QueryStats { requests: 20, success: 16, failure: 0, start: Some(Instant { tv_sec: 799, tv_nsec: 457984023 }), end: Some(Instant { tv_sec: 813, tv_nsec: 168543663 }) } - ProgressStep { count: 1, last: true } [2023-05-26T12:29:57.976795Z TRACE safenode::network::event] AutoNAT inbound probe: Request { probe_id: ProbeId(2), peer: PeerId("12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay"), addresses: ["/ip4/138.68.151.132/tcp/44619/p2p/12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay"] } [2023-05-26T12:29:57.976936Z INFO safenode::network::event] Dialing 12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay [2023-05-26T12:29:57.977119Z DEBUG safenode::node::api] Network inactivity: get_closest returned [PeerId("12D3KooWA3HaETuj7daLyKk87WfdLNBhX7mci91ocS8pfNAkV8qW"), PeerId("12D3KooWBKmpRtnvn2DnFLsB8zaqe5MkYsuyFfCeYo8qxCoyBp7v"), PeerId("12D3KooWRNtxc4z3dfd7poZeGjLyrxi4G22H1XBVpMP6v8GohSVx"), PeerId("12D3KooWGRmfEFqZNNrPJVLa97CsDaVKeFedvnFfP45UxRpqc31n"), PeerId("12D3KooWDr4eh5RTZX7t9X3Xm2SwXpe9BA9Qbp4b13AiGJtrEhC2"), PeerId("12D3KooWPyUPc1DZKkrN5MnXeEUAxLg5v3DfKajZnrz5gHhUor8g"), PeerId("12D3KooWFLvPUtXD7v6rmiGSyMJdt3w4eM466AwJRe3nnUeCY4g4"), PeerId("12D3KooWNahaJmDLtZasmQqPB4jhotMhZ47iFGFgdvV7wNuZdmak")] [2023-05-26T12:29:57.977263Z DEBUG safenode::node::api] PeerAdded: 12D3KooWCCPGiEdyM5N6dMijXE9i42Kbq7ZNCfHkDWLeUckEpveJ [2023-05-26T12:29:59.136900Z INFO safenode::network::event] Connected with PeerId("12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay") [2023-05-26T12:29:59.136957Z TRACE safenode::network::event] AutoNAT inbound probe: Response { probe_id: ProbeId(2), peer: PeerId("12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay"), address: "/ip4/138.68.151.132/tcp/44619/p2p/12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay" } [2023-05-26T12:29:59.137338Z INFO safenode::network::event] identify: Sent { peer_id: PeerId("12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay") } [2023-05-26T12:30:00.009587Z INFO safenode::network::event] identify: received info peer_id=12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay info=Info { public_key: Ed25519(PublicKey(compressed): 868877bd56a7ba95e178c705de2d21c44a0bcc38c7c9c17438c7fbc755056), protocol_version: "safe/0.1.0", agent_version: "safe/node/0.1.0", listen_addrs: ["/ip4/10.16.0.13/tcp/44619", "/ip4/127.0.0.1/tcp/44619", "/ip4/138.68.151.132/tcp/44619", "/ip4/10.131.0.9/tcp/44619", "/ip4/138.68.151.132/tcp/44619", "/ip4/10.131.0.9/tcp/44619"], protocols: ["/safe/1", "/ipfs/kad/1.0.0", "/ipfs/id/1.0.0", "/ipfs/id/push/1.0.0", "/libp2p/autonat/1.0.0"], observed_addr: "/ip4/47.207.67.50/tcp/44164" } [2023-05-26T12:30:00.009690Z INFO safenode::network::event] identify: adding addresses to routing table peer_id=12D3KooWJsX74Kx6RBGzEFtjjsFZ66D32euVZy4zbhtaEpeXh2ay addrs=["/ip4/138.68.151.132/tcp/44619"] [2023-05-26T12:30:01.277442Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(0), peer: Some(PeerId("12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2")), error: Response(DialError) } [2023-05-26T12:30:01.277499Z INFO safenode::network::event] AutoNAT status changed: Unknown -> Private [2023-05-26T12:30:01.277531Z WARN safenode::node::api] NAT status is determined to be private! [2023-05-26T12:30:01.277650Z INFO safenode] Node is stopping in 1s...

happybeing · May 26, 2023, 12:45pm

The first error shows in the console where your node was started. Is safenode still running there?

Josh · May 26, 2023, 12:48pm

Bingo,

Toivo · May 26, 2023, 12:51pm

Corrected that, and got the right message:

Error: We have been determined to be behind a NAT. This means we are not reachable externally by other nodes. In the future, the network will implement relays that allow us to still join the network.

Logs:

Summary

[2023-05-26T12:24:00.996431Z DEBUG safenode] Current build's git commit hash: ---- No git commit hash found ----
[2023-05-26T12:24:00.996948Z INFO safenode::peers_acquisition] Using contact peers from $SAFE_PEERS env var to intitiate contact with the network: ["/ip4/206.189.29.202/tcp/38379/p2p/12D3KooWN7AJe7wxVckZ6SF3ybYXVgetQ9hkYWHCHLokpSRC31W2"]
[2023-05-26T12:24:00.996986Z INFO safenode] 
Running safenode v0.1.0
=======================
[2023-05-26T12:24:00.998319Z INFO safenode] Starting node ...
[2023-05-26T12:24:00.998468Z INFO safenode::network] Node (PID: 28068) with PeerId: 12D3KooWHcrsqYnXEACWZUL237aZMTbm12GU21WCaeyJyy3gVk5J

Next my home LAN without proper port forwarding:

Error: We have been determined to be behind a NAT. This means we are not reachable externally by other nodes. In the future, the network will implement relays that allow us to still join the network.

[2023-05-26T12:31:22.492508Z INFO safenode::network] Node (PID: 11104) with PeerId: 12D3KooWG2Rm5cBCDHffYx3qdCa4KtwLotE1e52xYVZuNipfp8Co

Southside · May 26, 2023, 12:57pm

I think its behaving as expected for me

[2023-05-26T12:50:05.720762Z WARN safenode::node::api] NAT status is determined to be private!



[2023-05-26T12:49:41.357860Z INFO safenode::network::event] identify: received info peer_id=12D3KooWPuLcWkz2Uc5HKiuCapPzDMSSe7vfykqfLgALzKWZ9JEd info=Info { public_key: Ed25519(PublicKey(compressed): d149c31117106846c61b9995b8f9c0e4a38a6c43bac6a494e6da206ecfaf60da), protocol_version: "safe/0.1.0", agent_version: "safe/node/0.1.0", listen_addrs: ["/ip4/127.0.0.1/tcp/43469", "/ip4/10.131.0.8/tcp/43469", "/ip4/10.16.0.11/tcp/43469", "/ip4/138.68.151.93/tcp/43469", "/ip4/138.68.151.93/tcp/43469", "/ip4/10.131.0.8/tcp/43469"], protocols: ["/safe/1", "/ipfs/kad/1.0.0", "/ipfs/id/1.0.0", "/ipfs/id/push/1.0.0", "/libp2p/autonat/1.0.0"], observed_addr: "/ip4/92.27.103.225/tcp/41334" }
[2023-05-26T12:49:41.357909Z INFO safenode::network::event] identify: adding addresses to routing table peer_id=12D3KooWPuLcWkz2Uc5HKiuCapPzDMSSe7vfykqfLgALzKWZ9JEd addrs=["/ip4/138.68.151.93/tcp/43469"]
[2023-05-26T12:50:05.720719Z TRACE safenode::network::event] AutoNAT outbound probe: Error { probe_id: ProbeId(0), peer: Some(PeerId("12D3KooWPgALRGTEznXYsw2soNn33wZd7VkzW4xzz68Q4b157wbp")), error: Response(DialError) }
[2023-05-26T12:50:05.720746Z INFO safenode::network::event] AutoNAT status changed: Unknown -> Private
[2023-05-26T12:50:05.720762Z WARN safenode::node::api] NAT status is determined to be private!
[2023-05-26T12:50:05.720804Z INFO safenode] Node is stopping in 1s...

and in the console…

willie@gagarin:~/.safe$ ./safenode --log-dir=/tmp/safenode --root-dir=/tmp/safenodedata
Removed old logs from directory: "/tmp/safenode"
Starting logging to directory: "/tmp/safenode"
Node is stopping in 1s... Node log path: /tmp/safenode
Error: We have been determined to be behind a NAT. This means we are not reachable externally by other nodes. In the future, the network will implement relays that allow us to still join the network.

Location:
    safenode/src/bin/safenode/main.rs:235:36

EDIT : the client seems to work OK on a quick up/download of a 4Mb directory

Toivo · May 26, 2023, 1:03pm

With my Ubuntu from home and port forwading correctly set (I guess), it seems to be working, right?

[2023-05-26T12:58:27.863706Z INFO safenode] 
Running safenode v0.1.0
=======================
[2023-05-26T12:58:27.866968Z INFO safenode] Starting node ...
[2023-05-26T12:58:27.869107Z INFO safenode::network] Node (PID: 23265) with PeerId: 12D3KooWBjvLnyGSmR2y2efY4uhgvLCmDfUwhLh4JvkiNP1kJQVM
[2023-05-26T12:58:27.869125Z INFO safenode::network] PeerId converted to XorName: 12D3KooWBjvLnyGSmR2y2efY4uhgvLCmDfUwhLh4JvkiNP1kJQVM - 600920(01100000)..
[2023-05-26T12:58:27.878250Z DEBUG safenode::domain::fees::spend_queue] Starting fee: current_fee 4000.
[2023-05-26T12:58:27.878265Z DEBUG safenode::domain::fees::spend_queue] stats: highest 10494, high 8000, medium_high 6333, avg 4667, medium_low 3333, low 2000, lowest 1662, std_dev 2494, len 3, queue [8000, 4000, 2000]
[2023-05-26T12:58:27.880239Z INFO safenode::network::event] Local node is listening on "/ip4/127.0.0.1/udp/40193/quic-v1/p2p/12D3KooWBjvLnyGSmR2y2efY4uhgvLCmDfUwhLh4JvkiNP1kJQVM"
[2023-05-26T12:58:27.880295Z INFO safenode::network::event] Local node is listening on "/ip4/192.168.1.104/udp/40193/quic-v1/p2p/12D3KooWBjvLnyGSmR2y2efY4uhgvLCmDfUwhLh4JvkiNP1kJQVM"

Is there a spesific port that needs to be opened? Is that 12000? Because it seems to me that the only difference between my Ubnuntu and Win is that Ubuntu connects via 12000 and Win fails via 12001. But I can very well be wrong, not seeing something else.

bzee · May 26, 2023, 1:20pm

To use port forwarding, you can use the --port flag:

safenode --log-dir=/tmp/safenode --port=22000

In case of the above port 22000, this is then the port that you forward to the machine. You’ll see in the logs that the log lines with Local node is listening on "<addr>" will be with port 22000.

By the way, in your logs it shows your machine is using UDP, which suggests you are using an old version of safenode.

Josh · May 26, 2023, 1:32pm

Nice, I am in!

Topic		Replies	Views
Update 01 June, 2023 Updates	54	2644	June 25, 2023
Update 27 April, 2023 Updates	25	2147	May 7, 2023
Will the next testnet still need port forwarding? Beginners	10	697	April 10, 2024
NAT Traversal ETA? Development	13	994	February 12, 2024
Update 11 May, 2023 Updates	29	1938	May 21, 2023

NatNet [May 26 Testnet 2023] [Offline]

How to participate

Network Address

Running a Node

Known problems

Related topics