Unknown peer on BootstrapOk

[Freyskeyd]

Hello,

I was trying to improve the bootstrap part of my p2p network and I find out that during the bootstrap I receive BootstrapOk from peers that are not part of my network. It occurs only for some peer_id. I would love to understand what is going on but I don't find any relevant resources to understand the bootstrap process.

I'm generating the boot node key with a seed 1 being the first u8 of the slice passed to identity::ed25519::SecretKey::from_bytes.
The full logs detail are here:

2023-01-20T11:43:01.642667Z  INFO libp2p_bootstrap: I am 12D3KooWFFehYddGiX86tLFYPQ7BvWxhz6jNq4zQTBKgAGjDhuD3
2023-01-20T11:43:01.644953Z  INFO libp2p_bootstrap: Kademlia:  ---- adding peer: 12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X at /ip4/127.0.0.1/tcp/30002
2023-01-20T11:43:01.647109Z TRACE trust_dns_resolver::async_resolver: handle passed back
2023-01-20T11:43:01.647970Z  INFO libp2p_bootstrap: Starting
2023-01-20T11:43:01.648018Z DEBUG libp2p_tcp: listening on 0.0.0.0:0
2023-01-20T11:43:01.648356Z TRACE mio::poll: registering event source with poller: token=Token(0), interests=READABLE | WRITABLE
2023-01-20T11:43:01.648755Z  INFO libp2p_bootstrap: Event: Behaviour(Discovery(RoutingUpdated { peer: PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"), is_new_peer: true, addresses: ["/ip4/127.0.0.1/tcp/30002"], bucket_range: (Distance(904625697166532776746648320380374280103671755200316906558262375061821325312), Distance(1809251394333065553493296640760748560207343510400633813116524750123642650623)), old_peer: None }))
2023-01-20T11:43:01.648995Z DEBUG libp2p_tcp: dialing 127.0.0.1:30002
2023-01-20T11:43:01.649063Z  INFO libp2p_bootstrap: Event: Dialing(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"))
2023-01-20T11:43:01.649117Z DEBUG libp2p_tcp: New listen address: /ip4/127.0.0.1/tcp/61206
2023-01-20T11:43:01.649133Z DEBUG libp2p_swarm: Listener ListenerId(11355849460409025136); New address: "/ip4/127.0.0.1/tcp/61206"
2023-01-20T11:43:01.649153Z  INFO libp2p_bootstrap: Event: NewListenAddr { listener_id: ListenerId(11355849460409025136), address: "/ip4/127.0.0.1/tcp/61206" }
2023-01-20T11:43:01.649175Z DEBUG libp2p_tcp: New listen address: /ip4/my-ip/tcp/61206
2023-01-20T11:43:01.649188Z DEBUG libp2p_swarm: Listener ListenerId(11355849460409025136); New address: "/ip4/my-ip/tcp/61206"
2023-01-20T11:43:01.649203Z  INFO libp2p_bootstrap: Event: NewListenAddr { listener_id: ListenerId(11355849460409025136), address: "/ip4/my-ip/tcp/61206" }
2023-01-20T11:43:01.649206Z TRACE mio::poll: registering event source with poller: token=Token(1), interests=READABLE | WRITABLE
2023-01-20T11:43:01.649223Z DEBUG libp2p_tcp: New listen address: /ip4/my-ip/tcp/61206
2023-01-20T11:43:01.649248Z DEBUG libp2p_swarm: Listener ListenerId(11355849460409025136); New address: "/ip4/my-ip/tcp/61206"
2023-01-20T11:43:01.649263Z  INFO libp2p_bootstrap: Event: NewListenAddr { listener_id: ListenerId(11355849460409025136), address: "/ip4/my-ip/tcp/61206" }
2023-01-20T11:43:01.649300Z TRACE mio::poll: deregistering event source from poller
2023-01-20T11:43:01.649574Z DEBUG libp2p_kad::behaviour: Last remaining address '/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' of peer '12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' is unreachable.
2023-01-20T11:43:01.649668Z DEBUG libp2p_swarm: Connection attempt to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") failed with Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]).
2023-01-20T11:43:01.649747Z  INFO libp2p_bootstrap: Event: OutgoingConnectionError { peer_id: Some(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")), error: Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]) }
2023-01-20T11:43:01.649833Z TRACE libp2p_kad::behaviour: Query QueryId(0) finished.
2023-01-20T11:43:01.651065Z  INFO libp2p_bootstrap: Event: Behaviour(Discovery(OutboundQueryCompleted { id: QueryId(0), result: Bootstrap(Ok(BootstrapOk { peer: PeerId("12D3KooWFFehYddGiX86tLFYPQ7BvWxhz6jNq4zQTBKgAGjDhuD3"), num_remaining: 6 })), stats: QueryStats { requests: 1, success: 0, failure: 1, start: Some(Instant { t: 11670595619220 }), end: Some(Instant { t: 11670595641166 }) } }))
2023-01-20T11:43:01.651140Z DEBUG libp2p_tcp: dialing 127.0.0.1:30002
2023-01-20T11:43:01.651182Z  INFO libp2p_bootstrap: Event: Dialing(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"))
2023-01-20T11:43:01.651241Z TRACE mio::poll: registering event source with poller: token=Token(16777217), interests=READABLE | WRITABLE
2023-01-20T11:43:01.651307Z TRACE mio::poll: deregistering event source from poller
2023-01-20T11:43:01.651363Z DEBUG libp2p_kad::behaviour: Last remaining address '/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' of peer '12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' is unreachable.
2023-01-20T11:43:01.651445Z DEBUG libp2p_swarm: Connection attempt to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") failed with Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]).
2023-01-20T11:43:01.651517Z  INFO libp2p_bootstrap: Event: OutgoingConnectionError { peer_id: Some(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")), error: Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]) }
2023-01-20T11:43:01.651595Z TRACE libp2p_kad::behaviour: Query QueryId(0) finished.
2023-01-20T11:43:01.651772Z  INFO libp2p_bootstrap: Event: Behaviour(Discovery(OutboundQueryCompleted { id: QueryId(0), result: Bootstrap(Ok(BootstrapOk { peer: PeerId("1AYf95axaLiWHK5JyBd5QgHAcCFBxZZiVnmMFtCC6t2nTp"), num_remaining: 5 })), stats: QueryStats { requests: 1, success: 0, failure: 1, start: Some(Instant { t: 11670595671987 }), end: Some(Instant { t: 11670595683561 }) } }))
2023-01-20T11:43:01.651842Z DEBUG libp2p_tcp: dialing 127.0.0.1:30002
2023-01-20T11:43:01.651877Z  INFO libp2p_bootstrap: Event: Dialing(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"))
2023-01-20T11:43:01.651912Z TRACE mio::poll: registering event source with poller: token=Token(33554433), interests=READABLE | WRITABLE
2023-01-20T11:43:01.651953Z TRACE mio::poll: deregistering event source from poller
2023-01-20T11:43:01.652002Z DEBUG libp2p_kad::behaviour: Last remaining address '/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' of peer '12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' is unreachable.
2023-01-20T11:43:01.652082Z DEBUG libp2p_swarm: Connection attempt to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") failed with Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]).
2023-01-20T11:43:01.652152Z  INFO libp2p_bootstrap: Event: OutgoingConnectionError { peer_id: Some(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")), error: Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]) }
2023-01-20T11:43:01.652229Z TRACE libp2p_kad::behaviour: Query QueryId(0) finished.
2023-01-20T11:43:01.652403Z  INFO libp2p_bootstrap: Event: Behaviour(Discovery(OutboundQueryCompleted { id: QueryId(0), result: Bootstrap(Ok(BootstrapOk { peer: PeerId("1Ae3d1aQH9awtUCQogzKDsnfVh5vNWPeXTtQwaKk7Evdcc"), num_remaining: 4 })), stats: QueryStats { requests: 1, success: 0, failure: 1, start: Some(Instant { t: 11670595688864 }), end: Some(Instant { t: 11670595698793 }) } }))
2023-01-20T11:43:01.652470Z DEBUG libp2p_tcp: dialing 127.0.0.1:30002
2023-01-20T11:43:01.652526Z TRACE mio::poll: registering event source with poller: token=Token(50331649), interests=READABLE | WRITABLE
2023-01-20T11:43:01.652502Z  INFO libp2p_bootstrap: Event: Dialing(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"))
2023-01-20T11:43:01.652595Z TRACE mio::poll: deregistering event source from poller
2023-01-20T11:43:01.652646Z DEBUG libp2p_kad::behaviour: Last remaining address '/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' of peer '12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' is unreachable.
2023-01-20T11:43:01.652729Z DEBUG libp2p_swarm: Connection attempt to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") failed with Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]).
2023-01-20T11:43:01.652800Z  INFO libp2p_bootstrap: Event: OutgoingConnectionError { peer_id: Some(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")), error: Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]) }
2023-01-20T11:43:01.652877Z TRACE libp2p_kad::behaviour: Query QueryId(0) finished.
2023-01-20T11:43:01.653053Z  INFO libp2p_bootstrap: Event: Behaviour(Discovery(OutboundQueryCompleted { id: QueryId(0), result: Bootstrap(Ok(BootstrapOk { peer: PeerId("1AivUFFGmxbCyaYj3kRDfJEYCp43t2fADnCDBCv65ZUHwV"), num_remaining: 3 })), stats: QueryStats { requests: 1, success: 0, failure: 1, start: Some(Instant { t: 11670595704004 }), end: Some(Instant { t: 11670595714335 }) } }))
2023-01-20T11:43:01.653123Z DEBUG libp2p_tcp: dialing 127.0.0.1:30002
2023-01-20T11:43:01.653154Z  INFO libp2p_bootstrap: Event: Dialing(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"))
2023-01-20T11:43:01.653178Z TRACE mio::poll: registering event source with poller: token=Token(67108865), interests=READABLE | WRITABLE
2023-01-20T11:43:01.653213Z TRACE mio::poll: deregistering event source from poller
2023-01-20T11:43:01.653254Z DEBUG libp2p_kad::behaviour: Last remaining address '/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' of peer '12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' is unreachable.
2023-01-20T11:43:01.653333Z DEBUG libp2p_swarm: Connection attempt to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") failed with Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]).
2023-01-20T11:43:01.653403Z  INFO libp2p_bootstrap: Event: OutgoingConnectionError { peer_id: Some(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")), error: Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]) }
2023-01-20T11:43:01.653478Z TRACE libp2p_kad::behaviour: Query QueryId(0) finished.
2023-01-20T11:43:01.653652Z  INFO libp2p_bootstrap: Event: Behaviour(Discovery(OutboundQueryCompleted { id: QueryId(0), result: Bootstrap(Ok(BootstrapOk { peer: PeerId("1Afr3gty76WnJbDaBfr2bVTarcZaykF45qUxt4Tuxxo9Hw"), num_remaining: 2 })), stats: QueryStats { requests: 1, success: 0, failure: 1, start: Some(Instant { t: 11670595719617 }), end: Some(Instant { t: 11670595728778 }) } }))
2023-01-20T11:43:01.653718Z DEBUG libp2p_tcp: dialing 127.0.0.1:30002
2023-01-20T11:43:01.653771Z TRACE mio::poll: registering event source with poller: token=Token(83886081), interests=READABLE | WRITABLE
2023-01-20T11:43:01.653748Z  INFO libp2p_bootstrap: Event: Dialing(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"))
2023-01-20T11:43:01.653814Z TRACE mio::poll: deregistering event source from poller
2023-01-20T11:43:01.653861Z DEBUG libp2p_kad::behaviour: Last remaining address '/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' of peer '12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' is unreachable.
2023-01-20T11:43:01.653940Z DEBUG libp2p_swarm: Connection attempt to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") failed with Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]).
2023-01-20T11:43:01.654011Z  INFO libp2p_bootstrap: Event: OutgoingConnectionError { peer_id: Some(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")), error: Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]) }
2023-01-20T11:43:01.654087Z TRACE libp2p_kad::behaviour: Query QueryId(0) finished.
2023-01-20T11:43:01.654262Z  INFO libp2p_bootstrap: Event: Behaviour(Discovery(OutboundQueryCompleted { id: QueryId(0), result: Bootstrap(Ok(BootstrapOk { peer: PeerId("1Ah5HqYGRpGL8ZkpkgtB2DX2biWmCsatNQFYMxKkX63caf"), num_remaining: 1 })), stats: QueryStats { requests: 1, success: 0, failure: 1, start: Some(Instant { t: 11670595733970 }), end: Some(Instant { t: 11670595743402 }) } }))
2023-01-20T11:43:01.654328Z DEBUG libp2p_tcp: dialing 127.0.0.1:30002
2023-01-20T11:43:01.654375Z TRACE mio::poll: registering event source with poller: token=Token(100663297), interests=READABLE | WRITABLE
2023-01-20T11:43:01.654357Z  INFO libp2p_bootstrap: Event: Dialing(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X"))
2023-01-20T11:43:01.654424Z TRACE mio::poll: deregistering event source from poller
2023-01-20T11:43:01.654471Z DEBUG libp2p_kad::behaviour: Last remaining address '/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' of peer '12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X' is unreachable.
2023-01-20T11:43:01.654551Z DEBUG libp2p_swarm: Connection attempt to PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X") failed with Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]).
2023-01-20T11:43:01.654621Z  INFO libp2p_bootstrap: Event: OutgoingConnectionError { peer_id: Some(PeerId("12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X")), error: Transport([("/ip4/127.0.0.1/tcp/30002/p2p/12D3KooWPjceQrSwdWXPyLLeABRXmuqt69Rg3sBYbU1Nft9HyQ6X", Other(Custom { kind: Other, error: A(A(Os { code: 61, kind: ConnectionRefused, message: "Connection refused" })) }))]) }
2023-01-20T11:43:01.654698Z TRACE libp2p_kad::behaviour: Query QueryId(0) finished.
2023-01-20T11:43:01.654711Z  INFO libp2p_bootstrap: Event: Behaviour(Discovery(OutboundQueryCompleted { id: QueryId(0), result: Bootstrap(Ok(BootstrapOk { peer: PeerId("1AYec5b6t4Pg2YNEtUJyKTPAJhRJ4bs2BVjpJGqece1Eo3"), num_remaining: 0 })), stats: QueryStats { requests: 1, success: 0, failure: 1, start: Some(Instant { t: 11670595748598 }), end: Some(Instant { t: 11670595758062 }) } }))

What's disturbing me is that the BootstrapOk comes from peers that I don't know, the num_remaining is high and I don't known what it is. How can I investigate this?

[Freyskeyd]

After some digging, I realized that those peers were randomly generated. But I didn't expect to receive BootstrapOk from peer that I couldn't join. Maybe we can add some documentation regarding those events and what they're representing to avoid some confusion?