search

Joystream / substrate-node-joystream

Joystream Full Node
https://www.joystream.org
GNU General Public License v3.0
15 stars 16 forks source link

Windows network connectivity issues #61

Closed mnaamani closed 4 years ago

mnaamani commented 5 years ago 
$ ./target/release/joystream-node.exe --log sub-libp2p
2019-04-15 06:38:33.566 main INFO joystream_node::cli  Joystream Node
2019-04-15 06:38:33.566 main INFO joystream_node::cli    version 1.0.0-9ea3cfa-x86_64-windows-msvc
2019-04-15 06:38:33.566 main INFO joystream_node::cli    by Joystream, 2019
2019-04-15 06:38:33.566 main INFO joystream_node::cli  Chain specification: Joystream Testnet v2
2019-04-15 06:38:33.566 main INFO joystream_node::cli  Node name: didactic-dock-4262
2019-04-15 06:38:33.566 main INFO joystream_node::cli  Roles: FULL
2019-04-15 06:38:33.738 main INFO substrate_service  Best block: #128
2019-04-15 06:38:33.800 main INFO sub-libp2p  Local node address is: /ip4/0.0.0.0/tcp/30333/p2p/QmU4FV3RSrAV8JHYNysiQVYFHAANY731rjPk1AP5GnWdg1
2019-04-15 06:38:33.800 main WARN jsonrpc_http_server  Multi-threaded server is not available on Windows. Falling back to single thread.
2019-04-15 06:38:33.800 libp2p-0 DEBUG sub-libp2p  PSM => Connect(PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM")): Starting to connect
2019-04-15 06:38:33.800 libp2p-0 DEBUG sub-libp2p  Libp2p <= Dial PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM")
2019-04-15 06:38:33.800 libp2p-0 DEBUG sub-libp2p  PSM => Connect(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")): Starting to connect
2019-04-15 06:38:33.800 libp2p-0 DEBUG sub-libp2p  Libp2p <= Dial PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")
2019-04-15 06:38:33.800 libp2p-0 TRACE sub-libp2p  Addresses of PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM") are ["/dns4/bootnode1.joystream.org/tcp/30333", "/dns4/bootnode1.joystream.org/tcp/30333"]
2019-04-15 06:38:33.816 libp2p-0 TRACE sub-libp2p  Addresses of PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3") are ["/dns4/bootnode2.joystream.org/tcp/30333", "/dns4/bootnode2.joystream.org/tcp/30333"]
2019-04-15 06:38:33.816 libp2p-0 DEBUG sub-libp2p  PSM <= Discovered(PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM"))
2019-04-15 06:38:33.816 libp2p-0 DEBUG sub-libp2p  PSM <= Discovered(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3"))
2019-04-15 06:38:33.816 libp2p-0 DEBUG sub-libp2p  Libp2p <= Starting random Kademlia request for PeerId("QmSdkqGzT3KFYB2HXSCe2dW9AMgpJPUbr1gUYg4y5qbo1x")
2019-04-15 06:38:33.816 libp2p-0 TRACE sub-libp2p  Addresses of PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM") are ["/dns4/bootnode1.joystream.org/tcp/30333", "/dns4/bootnode1.joystream.org/tcp/30333"]
2019-04-15 06:38:33.816 libp2p-0 TRACE sub-libp2p  Addresses of PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3") are ["/dns4/bootnode2.joystream.org/tcp/30333", "/dns4/bootnode2.joystream.org/tcp/30333"]
2019-04-15 06:38:33.816 main INFO parity_ws  Listening for new connections on 127.0.0.1:9944.
2019-04-15 06:38:34.629 libp2p-2 DEBUG sub-libp2p  Libp2p => Connected(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")): Connection requested by PSM (through Dialer { address: "/dns4/bootnode2.joystream.org/tcp/30333" })
2019-04-15 06:38:34.629 libp2p-2 DEBUG sub-libp2p  Handler(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")) <= Enable
2019-04-15 06:38:34.801 libp2p-0 DEBUG sub-libp2p  Handler(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")) => Open: version 2
2019-04-15 06:38:34.801 libp2p-0 DEBUG sub-libp2p  External API <= Open(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3"))
2019-04-15 06:38:34.801 libp2p-0 TRACE sub-libp2p  Handler(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")) => Message
2019-04-15 06:38:34.801 libp2p-0 TRACE sub-libp2p  External API <= Message(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3"))
2019-04-15 06:38:34.801 libp2p-0 TRACE sub-libp2p  Identified PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3") => IdentifyInfo { public_key: Secp256k1(PublicKey(PublicKey(8a425a69b6b490269228e618a37415768f1d33db38198d76ae49a976c844571e21a79a6f031b990ead01e1e1f3b5b325effc0d62932e519ce4faacb3aa5ceceb))), protocol_version: "/substrate/1.0", agent_version: "joystream-node/v1.0.0-9ea3cfa-x86_64-linux-gnu (unknown)", listen_addrs: ["/ip4/139.162.154.213/tcp/30333", "/ip4/0.0.0.0/tcp/30333"], protocols: ["/ipfs/ping/1.0.0", "/substrate/sup/2", "/substrate/sup/multi/2", "/ipfs/kad/1.0.0", "/ipfs/id/1.0.0"] }
2019-04-15 06:38:34.801 libp2p-0 DEBUG sub-libp2p  PSM <= Discovered(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3"))
2019-04-15 06:38:34.801 libp2p-0 TRACE sub-libp2p  External API => Packet for PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")
2019-04-15 06:38:34.801 libp2p-0 TRACE sub-libp2p  Handler(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")) <= Packet
2019-04-15 06:38:34.801 libp2p-0 TRACE sub-libp2p  External API => Packet for PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")
2019-04-15 06:38:34.801 libp2p-0 TRACE sub-libp2p  Handler(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")) <= Packet
2019-04-15 06:38:34.816 libp2p-0 DEBUG sub-libp2p  Libp2p <= Starting random Kademlia request for PeerId("QmPL63CPWr5wtJjrnSM9FfpJtvrj5Mfwdj1P9RkDtwdvdp")
2019-04-15 06:38:34.941 libp2p-2 TRACE sub-libp2p  Ping time with PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3"): 146.1598ms
2019-04-15 06:38:34.941 libp2p-2 TRACE sub-libp2p  Handler(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")) => Message
2019-04-15 06:38:34.941 libp2p-2 TRACE sub-libp2p  External API <= Message(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3"))
2019-04-15 06:38:34.941 libp2p-2 TRACE sub-libp2p  Addresses of PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM") are ["/dns4/bootnode1.joystream.org/tcp/30333", "/dns4/bootnode1.joystream.org/tcp/30333"]
2019-04-15 06:38:34.941 libp2p-1 TRACE sub-libp2p  Addresses of PeerId("QmQXcSkSyXF9rbSKZdeFheSJ8pxxV5PhDFdYhDQJHV4uaU") are ["/ip4/0.0.0.0/tcp/30333"]
2019-04-15 06:38:34.941 libp2p-1 TRACE sub-libp2p  Addresses of PeerId("QmR8J233eKMafWXBsvCcAySkTMQwdmDSsADtQSW7c2GjR2") are ["/ip4/0.0.0.0/tcp/30333"]
2019-04-15 06:38:34.941 libp2p-1 TRACE sub-libp2p  Addresses of PeerId("QmPzpqYPHe9QYD8D9xoinwZ4TgqqmckV91xN9Jp6AoXLd5") are ["/ip4/95.179.157.108/tcp/30333", "/ip4/0.0.0.0/tcp/30333"]
2019-04-15 06:38:34.941 libp2p-2 TRACE sub-libp2p  Libp2p => Reach failure for Some(PeerId("QmQXcSkSyXF9rbSKZdeFheSJ8pxxV5PhDFdYhDQJHV4uaU")) through "/ip4/0.0.0.0/tcp/30333": Transport(Other(Custom { kind: Other, error: Other(A(Transport(Underlying(B(Kind(ConnectionRefused)))))) }))
2019-04-15 06:38:34.941 libp2p-2 TRACE sub-libp2p  Libp2p => Dial failure for PeerId("QmQXcSkSyXF9rbSKZdeFheSJ8pxxV5PhDFdYhDQJHV4uaU")
2019-04-15 06:38:34.941 libp2p-2 TRACE sub-libp2p  Libp2p => Reach failure for Some(PeerId("QmR8J233eKMafWXBsvCcAySkTMQwdmDSsADtQSW7c2GjR2")) through "/ip4/0.0.0.0/tcp/30333": Transport(Other(Custom { kind: Other, error: Other(A(Transport(Underlying(B(Kind(ConnectionRefused)))))) }))
2019-04-15 06:38:34.941 libp2p-2 TRACE sub-libp2p  Libp2p => Dial failure for PeerId("QmR8J233eKMafWXBsvCcAySkTMQwdmDSsADtQSW7c2GjR2")
2019-04-15 06:38:34.988 libp2p-0 TRACE sub-libp2p  Handler(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")) => Message
2019-04-15 06:38:34.988 libp2p-0 TRACE sub-libp2p  External API <= Message(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3"))
2019-04-15 06:38:35.269 libp2p-0 TRACE sub-libp2p  Addresses of PeerId("QmPzpqYPHe9QYD8D9xoinwZ4TgqqmckV91xN9Jp6AoXLd5") are ["/ip4/95.179.157.108/tcp/30333", "/ip4/0.0.0.0/tcp/30333", "/ip4/95.179.157.108/tcp/30333", "/ip4/0.0.0.0/tcp/30333"]
2019-04-15 06:38:35.269 libp2p-0 TRACE sub-libp2p  Addresses of PeerId("QmR8J233eKMafWXBsvCcAySkTMQwdmDSsADtQSW7c2GjR2") are ["/ip4/0.0.0.0/tcp/30333", "/ip4/0.0.0.0/tcp/30333"]
2019-04-15 06:38:35.269 libp2p-0 TRACE sub-libp2p  Addresses of PeerId("QmQXcSkSyXF9rbSKZdeFheSJ8pxxV5PhDFdYhDQJHV4uaU") are ["/ip4/0.0.0.0/tcp/30333", "/ip4/0.0.0.0/tcp/30333"]
2019-04-15 06:38:35.269 libp2p-0 TRACE sub-libp2p  Libp2p => Reach failure for Some(PeerId("QmR8J233eKMafWXBsvCcAySkTMQwdmDSsADtQSW7c2GjR2")) through "/ip4/0.0.0.0/tcp/30333": Transport(Other(Custom { kind: Other, error: Other(A(Transport(Underlying(B(Kind(ConnectionRefused)))))) }))
2019-04-15 06:38:35.269 libp2p-0 TRACE sub-libp2p  Libp2p => Reach failure for Some(PeerId("QmQXcSkSyXF9rbSKZdeFheSJ8pxxV5PhDFdYhDQJHV4uaU")) through "/ip4/0.0.0.0/tcp/30333": Transport(Other(Custom { kind: Other, error: Other(A(Transport(Underlying(B(Kind(ConnectionRefused)))))) }))
2019-04-15 06:38:35.269 libp2p-1 TRACE sub-libp2p  Libp2p => Reach failure for Some(PeerId("QmR8J233eKMafWXBsvCcAySkTMQwdmDSsADtQSW7c2GjR2")) through "/ip4/0.0.0.0/tcp/30333": Transport(Other(Custom { kind: Other, error: Other(A(Transport(Underlying(B(Kind(ConnectionRefused)))))) }))
2019-04-15 06:38:35.269 libp2p-1 TRACE sub-libp2p  Libp2p => Dial failure for PeerId("QmR8J233eKMafWXBsvCcAySkTMQwdmDSsADtQSW7c2GjR2")
2019-04-15 06:38:35.269 libp2p-1 TRACE sub-libp2p  Libp2p => Reach failure for Some(PeerId("QmQXcSkSyXF9rbSKZdeFheSJ8pxxV5PhDFdYhDQJHV4uaU")) through "/ip4/0.0.0.0/tcp/30333": Transport(Other(Custom { kind: Other, error: Other(A(Transport(Underlying(B(Kind(ConnectionRefused)))))) }))
2019-04-15 06:38:35.269 libp2p-1 TRACE sub-libp2p  Libp2p => Dial failure for PeerId("QmQXcSkSyXF9rbSKZdeFheSJ8pxxV5PhDFdYhDQJHV4uaU")
2019-04-15 06:38:35.504 libp2p-0 DEBUG sub-libp2p  Libp2p => Connected(PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM")): Connection requested by PSM (through Dialer { address: "/dns4/bootnode1.joystream.org/tcp/30333" })
2019-04-15 06:38:35.504 libp2p-0 DEBUG sub-libp2p  Handler(PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM")) <= Enable
2019-04-15 06:38:35.504 libp2p-0 TRACE sub-libp2p  Handler(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")) => Message
2019-04-15 06:38:35.504 libp2p-0 TRACE sub-libp2p  External API <= Message(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3"))
2019-04-15 06:38:35.925 libp2p-0 TRACE sub-libp2p  Libp2p => Reach failure for Some(PeerId("QmPzpqYPHe9QYD8D9xoinwZ4TgqqmckV91xN9Jp6AoXLd5")) through "/ip4/95.179.157.108/tcp/30333": PeerIdMismatch { obtained: PeerId("QmWanf8qABHMxYZ6ZxqW4r1pmAE4bFGDg2UxAt53tmdjoZ") }
2019-04-15 06:38:35.925 libp2p-0 TRACE sub-libp2p  Libp2p => Reach failure for Some(PeerId("QmPzpqYPHe9QYD8D9xoinwZ4TgqqmckV91xN9Jp6AoXLd5")) through "/ip4/0.0.0.0/tcp/30333": Transport(Other(Custom { kind: Other, error: Other(A(Transport(Underlying(B(Kind(ConnectionRefused)))))) }))
2019-04-15 06:38:36.144 libp2p-0 TRACE sub-libp2p  Handler(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3")) => Message
2019-04-15 06:38:36.144 libp2p-0 TRACE sub-libp2p  External API <= Message(PeerId("QmbjzmNMjzQUMHpzqcPHW5DnFeUjM3x4hbiDSMkYv1McD3"))
2019-04-15 06:38:36.519 libp2p-2 DEBUG sub-libp2p  Handler(PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM")) => Open: version 2
2019-04-15 06:38:36.519 libp2p-2 DEBUG sub-libp2p  External API <= Open(PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM"))
2019-04-15 06:39:18.205 libp2p-0 WARN sub-libp2p  Received extra substream after having already one open in backwards-compatibility mode with PeerId("QmeDa8jASqMRpTh4YCkeVEuHo6nbMcFDzD9pkUxTr3WxhM")
mnaamani commented 5 years ago

Building windows binary with rustc v1.34.0 seemed to be cause of the problem. Built with v1.33.0 resolved the problem. ... But why?

mnaamani commented 4 years ago

Rust 1.33 is too old now for this to be an issue, additionally we are using newer version of libp2p in latest release so closing. If there is a general networking issue again for windows with latest versions we can reopen.