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.

Sign up for GitHub

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

Jump to bottom

Connection timed out while following the hole-punching tutorial #2729

Closed
jellllly420 opened this issue Jun 25, 2022 · 10 comments
Closed

Connection timed out while following the hole-punching tutorial #2729

jellllly420 opened this issue Jun 25, 2022 · 10 comments

Comments

@jellllly420
Copy link

Summary

I followed the hole_punching tutorial, using a remote server running Ubuntu 20.04LTS as relay and two laptops running Ubuntu 20.04LTS on wsl2 as listener and dialer.

Eveything went well until the upgrade for the relayed connection. They both got each other's "observed address" and the circuit through relay server was already released. However, the connection went time out. Before that there was also MultiaddrNotSupported Error triggered by libp2p-dns.

Expected behaviour

I expected it to work just as the tutorial says.

Actual behaviour

Listener Debug Output 

RUST_LOG=libp2p=debug ./client --secret-key-seed 1 --mode listen --relay-address /ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN

[2022-06-25T15:53:59Z DEBUG libp2p_tcp] listening on 0.0.0.0:0
[2022-06-25T15:53:59Z DEBUG libp2p_tcp] New listen address: /ip4/127.0.0.1/tcp/37215
[2022-06-25T15:53:59Z DEBUG libp2p_swarm] Listener ListenerId(1); New address: "/ip4/127.0.0.1/tcp/37215"
[2022-06-25T15:53:59Z DEBUG libp2p_tcp] New listen address: /ip4/192.168.49.231/tcp/37215
[2022-06-25T15:53:59Z DEBUG libp2p_swarm] Listener ListenerId(1); New address: "/ip4/192.168.49.231/tcp/37215"
[2022-06-25T15:54:00Z DEBUG libp2p_dns] Dialing /ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN
[2022-06-25T15:54:00Z DEBUG libp2p_tcp] dialing 101.43.226.115:4001
[2022-06-25T15:54:00Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:00Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:00Z DEBUG libp2p_swarm] Connection established: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN") Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN", role_override: Dialer }; Total (peer): 1. Total non-banned (peer): 1
[2022-06-25T15:54:00Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:00Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:00Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:01Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:01Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:01Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:01Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:01Z DEBUG libp2p_swarm] Listener ListenerId(2); New address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"
[2022-06-25T15:54:16Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:16Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_swarm] Connection established: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3") Listener { local_addr: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit", send_back_addr: "/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN" }; Total (peer): 1. Total non-banned (peer): 1
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")
[2022-06-25T15:54:27Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_dns] Dialing /ip4/111.205.230.10/tcp/5466/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3
[2022-06-25T15:54:27Z DEBUG libp2p_tcp] dialing 111.205.230.10:5466
[2022-06-25T15:54:27Z DEBUG libp2p_dns] Dialing /p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3
[2022-06-25T15:54:27Z DEBUG libp2p_dns] Dial error: MultiaddrNotSupported("/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3").
[2022-06-25T15:54:31Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:31Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:42Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")
[2022-06-25T15:54:42Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")
[2022-06-25T15:54:46Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:46Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:57Z DEBUG libp2p_swarm] Connection closed with error KeepAliveTimeout: Connected { endpoint: Listener { local_addr: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit", send_back_addr: "/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN" }, peer_id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3") }; Total (peer): 0.
[2022-06-25T15:55:01Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:55:01Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:55:16Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:55:16Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:55:31Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:55:31Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:55:46Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:55:46Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:56:01Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:56:01Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:56:16Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:56:16Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:56:31Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:56:31Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:56:39Z DEBUG libp2p_dns] Dial error: Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" }).
[2022-06-25T15:56:39Z DEBUG libp2p_swarm] Connection attempt to PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3") failed with Transport([("/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3", Other(Custom { kind: Other, error: A(A(B(MultiaddrNotSupported("/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")))) })), ("/ip4/111.205.230.10/tcp/5466/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3", Other(Custom { kind: Other, error: A(A(B(Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" })))) }))]).

Dialer Debug Output 
RUST_LOG=libp2p=debug ./client --secret-key-seed 2 --mode dial --relay-address /ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN --remote-peer-id 12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X

[2022-06-25T15:54:26Z DEBUG libp2p_tcp] listening on 0.0.0.0:0
[2022-06-25T15:54:26Z DEBUG libp2p_tcp] New listen address: /ip4/127.0.0.1/tcp/36495
[2022-06-25T15:54:26Z DEBUG libp2p_swarm] Listener ListenerId(1); New address: "/ip4/127.0.0.1/tcp/36495"
[2022-06-25T15:54:26Z DEBUG libp2p_tcp] New listen address: /ip4/172.28.92.158/tcp/36495
[2022-06-25T15:54:26Z DEBUG libp2p_swarm] Listener ListenerId(1); New address: "/ip4/172.28.92.158/tcp/36495"
[2022-06-25T15:54:27Z DEBUG libp2p_dns] Dialing /ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN
[2022-06-25T15:54:27Z DEBUG libp2p_tcp] dialing 101.43.226.115:4001
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_swarm] Connection established: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN") Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN", role_override: Dialer }; Total (peer): 1. Total non-banned (peer): 1
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:27Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:27Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:28Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:28Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:28Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:28Z DEBUG libp2p_swarm] Connection established: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", role_override: Dialer }; Total (peer): 1. Total non-banned (peer): 1
[2022-06-25T15:54:28Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:28Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:28Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")
[2022-06-25T15:54:28Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:28Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:28Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-25T15:54:28Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")
[2022-06-25T15:54:28Z DEBUG libp2p_dns] Dialing /ip4/115.27.200.64/tcp/20115/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X
[2022-06-25T15:54:28Z DEBUG libp2p_tcp] dialing 115.27.200.64:20115
[2022-06-25T15:54:42Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:42Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:43Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")
[2022-06-25T15:54:43Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")
[2022-06-25T15:54:57Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:57Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-25T15:54:58Z DEBUG libp2p_swarm] Connection closed with error KeepAliveTimeout: Connected { endpoint: Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", role_override: Dialer }, peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") }; Total (peer): 0.
[2022-06-25T15:55:08Z DEBUG libp2p_swarm] Connection closed with error KeepAliveTimeout: Connected { endpoint: Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN", role_override: Dialer }, peer_id: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN") }; Total (peer): 0.
[2022-06-25T15:56:38Z DEBUG libp2p_dns] Dial error: Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" }).
[2022-06-25T15:56:38Z DEBUG libp2p_swarm] Connection attempt to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") failed with Transport([("/ip4/115.27.200.64/tcp/20115/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(B(Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" })))) }))]).
Server Debug Output 
./relay_v2 --port 4001 --secret-key-seed 0

opt: Opt { use_ipv6: None, secret_key_seed: 0, port: 4001 }
Local peer id: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
Listening on "/ip4/127.0.0.1/tcp/4001"
Listening on "/ip4/10.0.24.12/tcp/4001"
ReservationReqAccepted { src_peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"), renewed: false }
CircuitReqAccepted { src_peer_id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3"), dst_peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") }
CircuitClosed { src_peer_id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3"), dst_peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"), error: None }

Possible Solution

From the logs above, I suppose it's a libp2p-dns related bug?

Version

  • libp2p version (version number, commit, or branch): 0.47.0

Would you like to work on fixing this bug?

Maybe after I know where the problem exactly locates?

Thanks to anyone who is willing to help.
@dariusc93
Copy link
Member

dariusc93 commented Jun 26, 2022 •
edited
Loading

Does it occurs on the version released on crates.io? Do you have any code you could share or are you using one of the examples without modifications? I been testing relay (and dcutr) recently and i know the examples work fine for me with and without modifications

EDIT: Atleast not getting the dns timeout

@jellllly420
Copy link
Author

Does it occurs on the version released on crates.io? Do you have any code you could share or are you using one of the examples without modifications? I been testing relay (and dcutr) recently and i know the examples work fine for me with and without modifications

EDIT: Atleast not getting the dns timeout

I just cloned the main branch from Github and did as the tutorial says without any modifications.

@jellllly420
Copy link
Author

Does it occurs on the version released on crates.io? Do you have any code you could share or are you using one of the examples without modifications? I been testing relay (and dcutr) recently and i know the examples work fine for me with and without modifications

EDIT: Atleast not getting the dns timeout

  1. Just reset my all three local repositories to 0.45.1(the latest release), rebuilt and rerun following the tutorial but with the same error.

  2. I read the listener log again and found that it may not be a libp2p-dns related bug because the connection was closed due to KeepAliveTimeout error before the last dns timeout.

@mxinden
Copy link
Member

mxinden commented Jun 27, 2022

[2022-06-25T15:54:27Z DEBUG libp2p_dns] Dial error: MultiaddrNotSupported("/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3").

This is now fixed with #2696.

@mxinden
Copy link
Member

mxinden commented Jun 27, 2022

[2022-06-25T15:56:39Z DEBUG libp2p_dns] Dial error: Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" }).

This is the relevant part. The listener tried to do a coordinated upgrade, i.e. tried to connect to the dialer directly, but that connection attempt timed out.

Would you mind running with RUST_LOG=debug instead of RUST_LOG=libp2p=debug? That way the logs of the client are printed as well.

Thanks for reporting the issues! Thanks @dariusc93 for the additional help.

@jellllly420
Copy link
Author

[2022-06-25T15:56:39Z DEBUG libp2p_dns] Dial error: Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" }).

This is the relevant part. The listener tried to do a coordinated upgrade, i.e. tried to connect to the dialer directly, but that connection attempt timed out.

Would you mind running with RUST_LOG=debug instead of RUST_LOG=libp2p=debug? That way the logs of the client are printed as well.

Thanks for reporting the issues! Thanks @dariusc93 for the additional help.

Sorry for the delay. Here is the output with RUST_LOG=debug.

Dialer 
RUST_LOG=debug ./client --secret-key-seed 2 --mode dial --relay-address /ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN --remote-peer-id 12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X

[2022-06-28T12:02:52Z INFO  client] Local peer id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")
[2022-06-28T12:02:52Z DEBUG libp2p_tcp] listening on 0.0.0.0:0
[2022-06-28T12:02:52Z DEBUG netlink_proto::handle] handle: forwarding new request to connection
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG libp2p_tcp] New listen address: /ip4/127.0.0.1/tcp/41603
[2022-06-28T12:02:52Z DEBUG libp2p_swarm] Listener ListenerId(1); New address: "/ip4/127.0.0.1/tcp/41603"
[2022-06-28T12:02:52Z INFO  client] Listening on "/ip4/127.0.0.1/tcp/41603"
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG libp2p_tcp] New listen address: /ip4/192.168.54.242/tcp/41603
[2022-06-28T12:02:52Z DEBUG libp2p_swarm] Listener ListenerId(1); New address: "/ip4/192.168.54.242/tcp/41603"
[2022-06-28T12:02:52Z INFO  client] Listening on "/ip4/192.168.54.242/tcp/41603"
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:52Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:52Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z DEBUG libp2p_dns] Dialing /ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN
[2022-06-28T12:02:53Z DEBUG libp2p_tcp] dialing 101.43.226.115:4001
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /noise
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /noise
[2022-06-28T12:02:53Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /yamux/1.0.0
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /yamux/1.0.0
[2022-06-28T12:02:53Z DEBUG yamux::connection] new connection: 10779f99 (Client)
[2022-06-28T12:02:53Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:53Z DEBUG libp2p_swarm] Connection established: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN") Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN", role_override: Dialer }; Total (peer): 1. Total non-banned (peer): 1
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:53Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:53Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:53Z DEBUG yamux::connection] 10779f99: new outbound (Stream 10779f99/1) of (Connection 10779f99 Client (streams 1))
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:53Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:53Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z DEBUG yamux::connection] 10779f99: new outbound (Stream 10779f99/3) of (Connection 10779f99 Client (streams 2))
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:53Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:53Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:53Z INFO  client] Relay told us our public address: "/ip4/111.205.230.10/tcp/13189"
[2022-06-28T12:02:53Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:53Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z INFO  client] Told relay its public address.
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:53Z DEBUG yamux::connection] 10779f99: new outbound (Stream 10779f99/5) of (Connection 10779f99 Client (streams 2))
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:53Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /libp2p/circuit/relay/0.2.0/hop
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:53Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:53Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /libp2p/circuit/relay/0.2.0/hop
[2022-06-28T12:02:54Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:54Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /noise
[2022-06-28T12:02:54Z INFO  client] OutboundCircuitEstablished { relay_peer_id: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN"), limit: None }
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:54Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:54Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /noise
[2022-06-28T12:02:54Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:54Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /yamux/1.0.0
[2022-06-28T12:02:54Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /yamux/1.0.0
[2022-06-28T12:02:54Z DEBUG yamux::connection] new connection: 9886cc55 (Client)
[2022-06-28T12:02:54Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:54Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:54Z DEBUG libp2p_swarm] Connection established: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", role_override: Dialer }; Total (peer): 1. Total non-banned (peer): 1
[2022-06-28T12:02:54Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:54Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:54Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /libp2p/dcutr
[2022-06-28T12:02:54Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /libp2p/dcutr
[2022-06-28T12:02:54Z INFO  client] Established connection to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") via Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", role_override: Dialer }
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:54Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:54Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:54Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:54Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:54Z INFO  client] Sent { peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") }
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:54Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:54Z DEBUG yamux::connection] 9886cc55: new outbound (Stream 9886cc55/1) of (Connection 9886cc55 Client (streams 2))
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:54Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:54Z DEBUG yamux::connection] 9886cc55: new outbound (Stream 9886cc55/3) of (Connection 9886cc55 Client (streams 3))
[2022-06-28T12:02:54Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:54Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:54Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:54Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:54Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:54Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:54Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:54Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:54Z INFO  client] Received { peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"), info: IdentifyInfo { public_key: Ed25519(PublicKey(compressed): cecc157dc1ddd7295951c29888f095adb944d1b73d696e6df65d683bd4fc), protocol_version: "/TODO/0.0.1", agent_version: "rust-libp2p/0.36.1", listen_addrs: ["/ip4/114.254.0.89/tcp/4054", "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", "/ip4/127.0.0.1/tcp/34271", "/ip4/172.28.92.158/tcp/34271", "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"], protocols: ["/libp2p/circuit/relay/0.2.0/stop", "/ipfs/ping/1.0.0", "/ipfs/id/1.0.0", "/ipfs/id/push/1.0.0", "/libp2p/dcutr"], observed_addr: "/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN" } }
[2022-06-28T12:02:54Z INFO  client] RemoteInitiatedDirectConnectionUpgrade { remote_peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"), remote_relayed_addr: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X" }
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:54Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:54Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:54Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:55Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:55Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:55Z DEBUG libp2p_dns] Dialing /ip4/114.254.0.89/tcp/4054/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:55Z DEBUG libp2p_tcp] dialing 114.254.0.89:4054
[2022-06-28T12:03:08Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:08Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:08Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:08Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:08Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:10Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:10Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:10Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:10Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:10Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:23Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:23Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:23Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:23Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:23Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:24Z DEBUG libp2p_swarm] Connection closed with error KeepAliveTimeout: Connected { endpoint: Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", role_override: Dialer }, peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") }; Total (peer): 0.
[2022-06-28T12:03:24Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:24Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:24Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:24Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:24Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:24Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:34Z DEBUG libp2p_swarm] Connection closed with error KeepAliveTimeout: Connected { endpoint: Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN", role_override: Dialer }, peer_id: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN") }; Total (peer): 0.
[2022-06-28T12:03:34Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:34Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:34Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:34Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:34Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:34Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:05:06Z DEBUG libp2p_dns] Dial error: Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" }).
[2022-06-28T12:05:06Z DEBUG libp2p_swarm] Connection attempt to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") failed with Transport([("/ip4/114.254.0.89/tcp/4054/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(B(Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" })))) }))]).
[2022-06-28T12:05:06Z INFO  client] Outgoing connection error to Some(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")): Transport([("/ip4/114.254.0.89/tcp/4054/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(B(Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" })))) }))])
[2022-06-28T12:05:06Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:05:06Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:05:06Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:05:06Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:05:06Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:05:06Z DEBUG netlink_proto::connection] sending messages
^C
Listener 
RUST_LOG=debug ./client --secret-key-seed 1 --mode listen --relay-address /ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN

[2022-06-28T12:02:39Z INFO  client] Local peer id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")
[2022-06-28T12:02:39Z DEBUG libp2p_tcp] listening on 0.0.0.0:0
[2022-06-28T12:02:39Z DEBUG netlink_proto::handle] handle: forwarding new request to connection
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling messages (request id = RequestId { sequence_number: 1, port: 0 })
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::protocol::protocol] done handling response to request RequestId { sequence_number: 1, port: 0 }
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG libp2p_tcp] New listen address: /ip4/127.0.0.1/tcp/34271
[2022-06-28T12:02:39Z DEBUG libp2p_swarm] Listener ListenerId(1); New address: "/ip4/127.0.0.1/tcp/34271"
[2022-06-28T12:02:39Z INFO  client] Listening on "/ip4/127.0.0.1/tcp/34271"
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG libp2p_tcp] New listen address: /ip4/172.28.92.158/tcp/34271
[2022-06-28T12:02:39Z DEBUG libp2p_swarm] Listener ListenerId(1); New address: "/ip4/172.28.92.158/tcp/34271"
[2022-06-28T12:02:39Z INFO  client] Listening on "/ip4/172.28.92.158/tcp/34271"
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:39Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:39Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:40Z DEBUG libp2p_dns] Dialing /ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:40Z DEBUG libp2p_tcp] dialing 101.43.226.115:4001
[2022-06-28T12:02:40Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /noise
[2022-06-28T12:02:40Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /noise
[2022-06-28T12:02:40Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:40Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /yamux/1.0.0
[2022-06-28T12:02:40Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /yamux/1.0.0
[2022-06-28T12:02:40Z DEBUG yamux::connection] new connection: eb66492d (Client)
[2022-06-28T12:02:40Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:40Z DEBUG libp2p_swarm] Connection established: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN") Dialer { address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN", role_override: Dialer }; Total (peer): 1. Total non-banned (peer): 1
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:40Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:40Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:40Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:40Z DEBUG yamux::connection] eb66492d: new outbound (Stream eb66492d/1) of (Connection eb66492d Client (streams 1))
[2022-06-28T12:02:40Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:40Z DEBUG yamux::connection] eb66492d: new outbound (Stream eb66492d/3) of (Connection eb66492d Client (streams 2))
[2022-06-28T12:02:40Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:40Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:40Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:40Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:40Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:40Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:40Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:40Z INFO  client] Relay told us our public address: "/ip4/114.254.0.89/tcp/4054"
[2022-06-28T12:02:40Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:40Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:40Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:40Z INFO  client] Told relay its public address.
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:40Z DEBUG yamux::connection] eb66492d: new outbound (Stream eb66492d/5) of (Connection eb66492d Client (streams 2))
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:40Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /libp2p/circuit/relay/0.2.0/hop
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:40Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:40Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:41Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /libp2p/circuit/relay/0.2.0/hop
[2022-06-28T12:02:41Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:41Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:41Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:41Z INFO  client] Relay accepted our reservation request.
[2022-06-28T12:02:41Z DEBUG libp2p_swarm] Listener ListenerId(2); New address: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"
[2022-06-28T12:02:41Z INFO  client] Listening on "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:41Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:41Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:55Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /libp2p/circuit/relay/0.2.0/stop
[2022-06-28T12:02:55Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /libp2p/circuit/relay/0.2.0/stop
[2022-06-28T12:02:55Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:55Z INFO  client] InboundCircuitEstablished { src_peer_id: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN"), limit: Some(Limit { duration: Some(120s), data_in_bytes: Some(131072) }) }
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:55Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:55Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:55Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:55Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /noise
[2022-06-28T12:02:55Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /noise
[2022-06-28T12:02:55Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:56Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:56Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:56Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /yamux/1.0.0
[2022-06-28T12:02:56Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /yamux/1.0.0
[2022-06-28T12:02:56Z DEBUG yamux::connection] new connection: 4ddcd705 (Server)
[2022-06-28T12:02:56Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:56Z DEBUG yamux::connection] 4ddcd705: new outbound (Stream 4ddcd705/2) of (Connection 4ddcd705 Server (streams 0))
[2022-06-28T12:02:56Z DEBUG libp2p_swarm] Connection established: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3") Listener { local_addr: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit", send_back_addr: "/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN" }; Total (peer): 1. Total non-banned (peer): 1
[2022-06-28T12:02:56Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:56Z INFO  client] Established connection to PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3") via Listener { local_addr: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit", send_back_addr: "/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN" }
[2022-06-28T12:02:56Z DEBUG yamux::connection] 4ddcd705: new outbound (Stream 4ddcd705/4) of (Connection 4ddcd705 Server (streams 1))
[2022-06-28T12:02:56Z INFO  client] InitiatedDirectConnectionUpgrade { remote_peer_id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3"), local_relayed_addr: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit" }
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:56Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /libp2p/dcutr
[2022-06-28T12:02:56Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:56Z DEBUG yamux::connection] 4ddcd705: new outbound (Stream 4ddcd705/6) of (Connection 4ddcd705 Server (streams 2))
[2022-06-28T12:02:56Z DEBUG multistream_select::dialer_select] Dialer: Proposed protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:56Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:56Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:56Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:56Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:56Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /libp2p/dcutr
[2022-06-28T12:02:56Z DEBUG multistream_select::dialer_select] Dialer: Received confirmation for protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:56Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:56Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:56Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /ipfs/ping/1.0.0
[2022-06-28T12:02:56Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:56Z INFO  client] Received { peer_id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3"), info: IdentifyInfo { public_key: Ed25519(PublicKey(compressed): 6b79c57e6a95239282c4818e96112f3f3a401ba97a564c23852a3f1ea5fc), protocol_version: "/TODO/0.0.1", agent_version: "rust-libp2p/0.36.1", listen_addrs: ["/ip4/111.205.230.10/tcp/13189", "/ip4/127.0.0.1/tcp/41603", "/ip4/192.168.54.242/tcp/41603"], protocols: ["/libp2p/circuit/relay/0.2.0/stop", "/ipfs/ping/1.0.0", "/ipfs/id/1.0.0", "/ipfs/id/push/1.0.0", "/libp2p/dcutr"], observed_addr: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X" } }
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:56Z DEBUG multistream_select::listener_select] Listener: confirming protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:56Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:56Z DEBUG multistream_select::listener_select] Listener: sent confirmed protocol: /ipfs/id/1.0.0
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:56Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:56Z INFO  client] Sent { peer_id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3") }
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:56Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:56Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")
[2022-06-28T12:02:56Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:56Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:56Z DEBUG libp2p_core::upgrade::apply] Successfully applied negotiated protocol
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:02:56Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:02:56Z DEBUG libp2p_dns] Dialing /ip4/111.205.230.10/tcp/13189/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:02:56Z DEBUG libp2p_tcp] dialing 111.205.230.10:13189
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:02:56Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:02:56Z DEBUG libp2p_dns] Dialing /p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3
[2022-06-28T12:02:56Z DEBUG libp2p_dns] Dial error: MultiaddrNotSupported("/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3").
[2022-06-28T12:03:11Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:11Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:11Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:11Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:11Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")
[2022-06-28T12:03:11Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:11Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:11Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:26Z DEBUG libp2p_swarm] Connection closed with error KeepAliveTimeout: Connected { endpoint: Listener { local_addr: "/ip4/101.43.226.115/tcp/4001/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p-circuit", send_back_addr: "/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN" }, peer_id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3") }; Total (peer): 0.
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:26Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:26Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:26Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:26Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:26Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:26Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:41Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:41Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:41Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:41Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:41Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:56Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:56Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:03:56Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:03:56Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:03:56Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:04:11Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:04:11Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:04:11Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:04:11Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:04:11Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:04:11Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:04:11Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:04:12Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:04:12Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:04:12Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:04:12Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:04:12Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:04:12Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:04:12Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:04:26Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:04:26Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:04:26Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:04:26Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:04:26Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:04:26Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:04:26Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:04:27Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:04:27Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:04:27Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:04:27Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:04:27Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:04:27Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:04:27Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:04:42Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:04:42Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:04:42Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:04:42Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:04:42Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:04:57Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:04:57Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:04:57Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:04:57Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:04:57Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:05:07Z DEBUG libp2p_dns] Dial error: Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" }).
[2022-06-28T12:05:07Z DEBUG libp2p_swarm] Connection attempt to PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3") failed with Transport([("/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3", Other(Custom { kind: Other, error: A(A(B(MultiaddrNotSupported("/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")))) })), ("/ip4/111.205.230.10/tcp/13189/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3", Other(Custom { kind: Other, error: A(A(B(Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" })))) }))]).
[2022-06-28T12:05:07Z INFO  client] Outgoing connection error to Some(PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")): Transport([("/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3", Other(Custom { kind: Other, error: A(A(B(MultiaddrNotSupported("/p2p/12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3")))) })), ("/ip4/111.205.230.10/tcp/13189/p2p/12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3", Other(Custom { kind: Other, error: A(A(B(Transport(Os { code: 110, kind: TimedOut, message: "Connection timed out" })))) }))])
[2022-06-28T12:05:07Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:05:07Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:05:07Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:05:07Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:05:07Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:05:07Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:05:12Z DEBUG libp2p_ping] Ping received from PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:05:12Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] sending messages
[2022-06-28T12:05:12Z DEBUG libp2p_ping] Ping sent to PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] reading incoming messages
[2022-06-28T12:05:12Z DEBUG netlink_proto::codecs] NetlinkCodec: decoding next message
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] forwarding unsolicited messages to the connection handle
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] forwaring responses to previous requests to the connection handle
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] handling requests
[2022-06-28T12:05:12Z DEBUG netlink_proto::connection] sending messages
^C
Relay Sever 
./relay_v2 --port 4001 --secret-key-seed 0

opt: Opt { use_ipv6: None, secret_key_seed: 0, port: 4001 }
Local peer id: PeerId("12D3KooWDpJ7As7BWAwRMfu1VU2WCqNjvq387JEYKDBj4kx6nXTN")
Listening on "/ip4/127.0.0.1/tcp/4001"
Listening on "/ip4/10.0.24.12/tcp/4001"
ReservationReqAccepted { src_peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"), renewed: false }
CircuitReqAccepted { src_peer_id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3"), dst_peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") }
CircuitClosed { src_peer_id: PeerId("12D3KooWH3uVF6wv47WnArKHk5p6cvgCJEb74UTmxztmQDc298L3"), dst_peer_id: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"), error: None }
^C

@mxinden
Copy link
Member

mxinden commented Jul 1, 2022

Thanks @jellllly420. Unfortunately I don't see additional insights based on the new logs. The connection is timing out. That can be due to many reasons, e.g. the host itself, but maybe as well the NAT, e.g. being a symetric NAT or not adding port mappings in time.

Are you able to reproduce the same failures in other setups?

@mxinden
Copy link
Member

mxinden commented Oct 5, 2022

Friendly ping @jellllly420.

@jellllly420
Copy link
Author

Sorry for the delay. I finally found the reason why this happened is that one of my local laptop was under the subnet of my portable hotspot using cellular network, which means that it was behind a symmetric NAT. Sorry again for the delay.

@mxinden
Copy link
Member

mxinden commented Jan 16, 2023

Thanks for reporting back @jellllly420!

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

No branches or pull requests
3 participants
@dariusc93 @mxinden @jellllly420