Skip to content

Instantly share code, notes, and snippets.

@Folaht
Last active December 31, 2021 00:10
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save Folaht/70ae0329b5acd176cc3ea84b920c1576 to your computer and use it in GitHub Desktop.
Save Folaht/70ae0329b5acd176cc3ea84b920c1576 to your computer and use it in GitHub Desktop.
Random port issue
INFO 2021-12-31T00:00:41.722446Z [sn/src/routing/routing_api/mod.rs:L152]:
➤ 859220.. Bootstrapping a new node.
TRACE 2021-12-31T00:00:41.738907Z [sn/src/routing/core/comm.rs:L464]:
➤ bootstrap {bootstrap_nodes=[192.168.178.29:12000]}
➤ handle_incoming_connections
➤ handle_incoming_messages {connection=Connection { id: 1023325744, remote_address: 192.168.178.29:57402, .. }}
➤ ConnectionOpened connection_id=1023325744 src=192.168.178.29:57402
TRACE 2021-12-31T00:00:41.738969Z [sn/src/routing/core/comm.rs:L485]:
➤ bootstrap {bootstrap_nodes=[192.168.178.29:12000]}
➤ handle_incoming_connections
➤ handle_incoming_messages {connection=Connection { id: 1023325744, remote_address: 192.168.178.29:57402, .. }}
➤ ConnectionClosed connection_id=1023325744 src=192.168.178.29:57402
TRACE 2021-12-31T00:00:41.739020Z [sn/src/routing/core/comm.rs:L464]:
➤ bootstrap {bootstrap_nodes=[192.168.178.29:12000]}
➤ handle_incoming_messages {connection=Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }}
➤ ConnectionOpened connection_id=1022504624 src=192.168.178.29:12000
INFO 2021-12-31T00:00:41.739542Z [sn/src/routing/routing_api/mod.rs:L166]:
➤ 859220.. Joining as a new node (PID: 216653) our socket: 192.168.178.29:12001, bootstrapper was: 192.168.178.29:12000, network's genesis key: PublicKey(0d39..ae26)
INFO 2021-12-31T00:00:41.740029Z [sn/src/routing/core/bootstrap/join.rs:L505]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ send_join_requests {join_request=JoinRequest { section_key: PublicKey(0d39..ae26), resource_proof_response: None, aggregated: None } recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] section_key=PublicKey(0d39..ae26) should_backoff=false}
➤ Sending JoinRequest { section_key: PublicKey(0d39..ae26), resource_proof_response: None, aggregated: None } to [Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]
TRACE 2021-12-31T00:00:41.740452Z [sn/src/routing/core/comm.rs:L214]:
➤ bootstrap
➤ send {recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(f19b..cf73), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8592..9705), signature: Signature(4a57..860f) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Sending message (msg_id: MessageId(f19b..cf73)) to 1 of [Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]
TRACE 2021-12-31T00:00:41.740543Z [sn/src/routing/core/comm.rs:L326]:
➤ bootstrap
➤ send {recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(f19b..cf73), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8592..9705), signature: Signature(4a57..860f) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Sending message (568 bytes, msg_id: MessageId(f19b..cf73)) to 859220.. at 192.168.178.29:12000 (connected)
TRACE 2021-12-31T00:00:41.740662Z [sn/src/routing/core/comm.rs:L362]:
➤ bootstrap
➤ send {recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(f19b..cf73), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8592..9705), signature: Signature(4a57..860f) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ ConnectionReused connection_id=1022504624 src=192.168.178.29:12000
TRACE 2021-12-31T00:00:41.763875Z [sn/src/routing/core/bootstrap/join.rs:L303]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ Joining node Prefix() - 859220(10000101)../5 received a Retry from c9a8fa.. at 192.168.178.29:12000 (connected) with SAP SectionAuthorityProvider { prefix: Prefix(), public_key_set: PublicKeySet { public_key: PublicKey(0d39..ae26), threshold: 0 }, elders: {Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: None }} }, expected_age: 98, our age: 5
TRACE 2021-12-31T00:00:41.763943Z [sn/src/prefix_map/mod.rs:L149]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ Attempting to update prefixmap for Prefix()
TRACE 2021-12-31T00:00:41.763964Z [sn/src/prefix_map/mod.rs:L153]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ No key found for prefix: Prefix()
TRACE 2021-12-31T00:00:41.771635Z [sn/src/prefix_map/mod.rs:L263]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ Known prefix: Prefix()
TRACE 2021-12-31T00:00:41.771674Z [sn/src/prefix_map/mod.rs:L166]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ Known prefix after update: Prefix()
TRACE 2021-12-31T00:00:41.771685Z [sn/src/routing/core/bootstrap/join.rs:L347]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ Re-generating name due to mis-matched age, current 5 vs. expected 98
TRACE 2021-12-31T00:00:41.771694Z [sn/src/routing/core/bootstrap/join.rs:L361]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ Name shall have the prefix of Prefix(100011)
INFO 2021-12-31T00:00:50.566406Z [sn/src/routing/core/bootstrap/join.rs:L367]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ Setting Node name to 8ffdf7.. (age 98)
INFO 2021-12-31T00:00:50.566454Z [sn/src/routing/core/bootstrap/join.rs:L374]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ Newer Join response for us 8ffdf7(10001111).., SAP SectionAuthorityProvider { prefix: Prefix(), public_key_set: PublicKeySet { public_key: PublicKey(0d39..ae26), threshold: 0 }, elders: {Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: None }} } from Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }
TRACE 2021-12-31T00:00:50.566631Z [sn/src/routing/core/comm.rs:L297]:
➤ bootstrap
➤ send {recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(f19b..cf73), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8592..9705), signature: Signature(4a57..860f) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Finished sending message WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(f19b..cf73), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8592..9705), signature: Signature(4a57..860f) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } } to 1/1 recipients (failed: [])
INFO 2021-12-31T00:00:50.898981Z [sn/src/routing/core/bootstrap/join.rs:L505]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ send_join_requests {join_request=JoinRequest { section_key: PublicKey(0d39..ae26), resource_proof_response: None, aggregated: None } recipients=[Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] section_key=PublicKey(0d39..ae26) should_backoff=true}
➤ Sending JoinRequest { section_key: PublicKey(0d39..ae26), resource_proof_response: None, aggregated: None } to [Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]
TRACE 2021-12-31T00:00:50.899258Z [sn/src/routing/core/comm.rs:L214]:
➤ bootstrap
➤ send {recipients=[Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(bb51..7345), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8ffd..8062), signature: Signature(ac24..d407) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Sending message (msg_id: MessageId(bb51..7345)) to 1 of [Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]
TRACE 2021-12-31T00:00:50.899309Z [sn/src/routing/core/comm.rs:L326]:
➤ bootstrap
➤ send {recipients=[Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(bb51..7345), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8ffd..8062), signature: Signature(ac24..d407) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Sending message (572 bytes, msg_id: MessageId(bb51..7345)) to c9a8fa.. at 192.168.178.29:12000 (connected)
TRACE 2021-12-31T00:00:50.899325Z [sn/src/routing/core/comm.rs:L362]:
➤ bootstrap
➤ send {recipients=[Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(bb51..7345), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8ffd..8062), signature: Signature(ac24..d407) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ ConnectionReused connection_id=1022504624 src=192.168.178.29:12000
TRACE 2021-12-31T00:00:50.900459Z [sn/src/routing/core/comm.rs:L297]:
➤ bootstrap
➤ send {recipients=[Peer { name: c9a8fa(11001001).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(bb51..7345), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8ffd..8062), signature: Signature(ac24..d407) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Finished sending message WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(bb51..7345), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8ffd..8062), signature: Signature(ac24..d407) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } } to 1/1 recipients (failed: [])
TRACE 2021-12-31T00:01:50.902406Z [sn/src/routing/core/comm.rs:L485]:
➤ bootstrap {bootstrap_nodes=[192.168.178.29:12000]}
➤ handle_incoming_messages {connection=Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }}
➤ ConnectionClosed connection_id=1022504624 src=192.168.178.29:12000
TRACE 2021-12-31T00:01:50.914764Z [sn/src/routing/core/comm.rs:L464]:
➤ bootstrap {bootstrap_nodes=[192.168.178.29:12000]}
➤ handle_incoming_connections
➤ handle_incoming_messages {connection=Connection { id: 1023283216, remote_address: 192.168.178.29:12000, .. }}
➤ ConnectionOpened connection_id=1023283216 src=192.168.178.29:12000
ERROR 2021-12-31T00:01:50.918735Z [sn/src/routing/core/bootstrap/join.rs:L164]:
➤ bootstrap
➤ join {network_genesis_key=PublicKey(0d39..ae26) target_section_key=PublicKey(0d39..ae26) recipients=[Peer { name: 859220(10000101).., addr: 192.168.178.29:12000, connection: Some(Connection { id: 1022504624, remote_address: 192.168.178.29:12000, .. }) }]}
➤ Node cannot join the network since it is not externally reachable: 10.0.2.100:37675
TRACE 2021-12-31T00:00:02.076087Z [sn/src/node/logging/mod.rs:L71]:
➤ log {system=System { global CPU usage: 25.013546, load average: LoadAvg { one: 1.0, five: 1.0, fifteen: 1.0 }, total memory: 3990802, free memory: 1294704, total swap: 5986201, free swap: 5986201, nb CPUs: 4, nb network interfaces: 2, nb processes: 1, nb disks: 2, nb components: 1 } print_resources_usage=false}
➤ Node resource usage: Process { memory: 36968, virtual_memory: 125906944, cpu_usage: 0.004167535, disk_usage: DiskUsage { total_written_bytes: 114688, written_bytes: 4096, total_read_bytes: 0, read_bytes: 0 } } prefix="000000.."
TRACE 2021-12-31T00:00:41.730074Z [sn/src/routing/core/comm.rs:L464]:
➤ new
➤ handle_incoming_connections
➤ handle_incoming_messages {connection=Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }}
➤ ConnectionOpened connection_id=848182192 src=10.0.2.100:37675
TRACE 2021-12-31T00:00:41.741395Z [sn/src/routing/routing_api/mod.rs:L418]:
➤ New message (568 bytes) received from: UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }
TRACE 2021-12-31T00:00:41.744618Z [sn/src/routing/routing_api/mod.rs:L439]:
➤ handle_message {name=c9a8fa.. sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } msg_id=MessageId(f19b..cf73)}
➤ DispatchHandleMsgCmd from UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } length 568
TRACE 2021-12-31T00:00:41.744829Z [sn/src/routing/routing_api/dispatcher.rs:L71]:
➤ handle_message {name=c9a8fa.. sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } msg_id=MessageId(f19b..cf73)}
➤ CommandHandleSpawned HandleMessage MessageId(f19b..cf73) cmd_id=56846047
TRACE 2021-12-31T00:00:41.744967Z [sn/src/routing/routing_api/dispatcher.rs:L148]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(f19b..cf73)}
➤ CommandHandleStart
TRACE 2021-12-31T00:00:41.745035Z [sn/src/routing/core/msg_handling/mod.rs:L60]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(f19b..cf73)}
➤ handle_message {sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(f19b..cf73), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8592..9705), signature: Signature(4a57..860f) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ handling msg
TRACE 2021-12-31T00:00:41.747456Z [sn/src/routing/core/msg_handling/mod.rs:L113]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(f19b..cf73)}
➤ handle_message {sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(f19b..cf73), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8592..9705), signature: Signature(4a57..860f) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Trusted msg authority in message (MessageId(f19b..cf73)) from UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }: JoinRequest(JoinRequest { section_key: PublicKey(0d39..ae26), resource_proof_response: None, aggregated: None })
TRACE 2021-12-31T00:00:41.747502Z [sn/src/routing/core/msg_handling/mod.rs:L139]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(f19b..cf73)}
➤ handle_message {sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(f19b..cf73), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8592..9705), signature: Signature(4a57..860f) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Entropy check skipped for MessageId(f19b..cf73), handling message directly
TRACE 2021-12-31T00:00:41.747530Z [sn/src/routing/routing_api/dispatcher.rs:L153]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(f19b..cf73)}
➤ CommandHandleEnd HandleMessage MessageId(f19b..cf73)
TRACE 2021-12-31T00:00:41.747632Z [sn/src/routing/routing_api/dispatcher.rs:L71]:
➤ CommandHandleSpawned HandleSystemMessage MessageId(f19b..cf73) cmd_id=56846047.0
TRACE 2021-12-31T00:00:41.747735Z [sn/src/routing/routing_api/dispatcher.rs:L148]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ CommandHandleStart
TRACE 2021-12-31T00:00:41.747752Z [sn/src/routing/core/msg_handling/mod.rs:L260]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ SystemMsgToBeHandled
TRACE 2021-12-31T00:00:41.747787Z [sn/src/routing/core/msg_handling/mod.rs:L296]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ Handling non blocking message
TRACE 2021-12-31T00:00:41.747794Z [sn/src/routing/core/msg_handling/mod.rs:L567]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ Handling msg: JoinRequest from 859220.. at 10.0.2.100:37675 (connected)
DEBUG 2021-12-31T00:00:41.747808Z [sn/src/routing/core/msg_handling/join.rs:L51]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ Received JoinRequest { section_key: PublicKey(0d39..ae26), resource_proof_response: None, aggregated: None } from 859220.. at 10.0.2.100:37675 (connected)
TRACE 2021-12-31T00:00:41.747855Z [sn/src/routing/core/msg_handling/join.rs:L184]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ our_prefix Prefix() section_members 1 expected_age 98 is_age_invalid true
TRACE 2021-12-31T00:00:41.747865Z [sn/src/routing/core/msg_handling/join.rs:L202]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ SendJoinRetryAgeIssue
TRACE 2021-12-31T00:00:41.747870Z [sn/src/routing/core/msg_handling/join.rs:L203]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ JoinRequest from 859220.. at 10.0.2.100:37675 (connected) (with age 5) doesn't have the expected: 98
TRACE 2021-12-31T00:00:41.747887Z [sn/src/routing/core/msg_handling/join.rs:L224]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ Sending JoinResponse(Retry { section_auth: SectionAuthorityProvider { prefix: Prefix(), public_key_set: PublicKeySet { public_key: PublicKey(0d39..ae26), threshold: 0 }, elders: {c9a8fa(11001001)..: 192.168.178.29:12000} }, section_signed: KeyedSig { public_key: PublicKey(0d39..ae26), signature: Signature(0a11..a30d) }, proof_chain: PublicKey(0d39..ae26), expected_age: 98 }) to 859220.. at 10.0.2.100:37675 (connected)
TRACE 2021-12-31T00:00:41.748362Z [sn/src/routing/core/messaging.rs:L568]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ SendDirect
TRACE 2021-12-31T00:00:41.748395Z [sn/src/routing/routing_api/dispatcher.rs:L153]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(f19b..cf73)}
➤ CommandHandleEnd HandleSystemMessage MessageId(f19b..cf73)
TRACE 2021-12-31T00:00:41.748493Z [sn/src/routing/routing_api/dispatcher.rs:L71]:
➤ CommandHandleSpawned SendMessage MessageId(3f7d..64da) cmd_id=56846047.0.0
TRACE 2021-12-31T00:00:41.748577Z [sn/src/routing/routing_api/dispatcher.rs:L148]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(3f7d..64da)}
➤ CommandHandleStart
TRACE 2021-12-31T00:00:41.748684Z [sn/src/routing/core/comm.rs:L214]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(3f7d..64da)}
➤ send {recipients=[Peer { name: 859220(10000101).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(3f7d..64da), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(fd65..9f01) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Sending message (msg_id: MessageId(3f7d..64da)) to 1 of [Peer { name: 859220(10000101).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }]
TRACE 2021-12-31T00:00:41.748799Z [sn/src/routing/core/comm.rs:L326]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(3f7d..64da)}
➤ send {recipients=[Peer { name: 859220(10000101).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(3f7d..64da), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(fd65..9f01) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Sending message (1076 bytes, msg_id: MessageId(3f7d..64da)) to 859220.. at 10.0.2.100:37675 (connected)
TRACE 2021-12-31T00:00:41.748823Z [sn/src/routing/core/comm.rs:L362]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(3f7d..64da)}
➤ send {recipients=[Peer { name: 859220(10000101).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(3f7d..64da), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(fd65..9f01) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ ConnectionReused connection_id=848182192 src=10.0.2.100:37675
TRACE 2021-12-31T00:00:41.750098Z [sn/src/routing/core/comm.rs:L297]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(3f7d..64da)}
➤ send {recipients=[Peer { name: 859220(10000101).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(3f7d..64da), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(fd65..9f01) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Finished sending message WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(3f7d..64da), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(fd65..9f01) }), dst_location: Section { name: 859220(10000101).., section_pk: PublicKey(0d39..ae26) } } } } to 1/1 recipients (failed: [])
TRACE 2021-12-31T00:00:41.750264Z [sn/src/routing/routing_api/dispatcher.rs:L153]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=56846047.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(3f7d..64da)}
➤ CommandHandleEnd SendMessage MessageId(3f7d..64da)
TRACE 2021-12-31T00:00:50.900023Z [sn/src/routing/routing_api/mod.rs:L418]:
➤ New message (572 bytes) received from: UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }
TRACE 2021-12-31T00:00:50.903246Z [sn/src/routing/routing_api/mod.rs:L439]:
➤ handle_message {name=c9a8fa.. sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } msg_id=MessageId(bb51..7345)}
➤ DispatchHandleMsgCmd from UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } length 572
TRACE 2021-12-31T00:00:50.903488Z [sn/src/routing/routing_api/dispatcher.rs:L71]:
➤ handle_message {name=c9a8fa.. sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } msg_id=MessageId(bb51..7345)}
➤ CommandHandleSpawned HandleMessage MessageId(bb51..7345) cmd_id=2935419944
TRACE 2021-12-31T00:00:50.903615Z [sn/src/routing/routing_api/dispatcher.rs:L148]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(bb51..7345)}
➤ CommandHandleStart
TRACE 2021-12-31T00:00:50.903680Z [sn/src/routing/core/msg_handling/mod.rs:L60]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(bb51..7345)}
➤ handle_message {sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(bb51..7345), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8ffd..8062), signature: Signature(ac24..d407) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ handling msg
TRACE 2021-12-31T00:00:50.905731Z [sn/src/routing/core/msg_handling/mod.rs:L113]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(bb51..7345)}
➤ handle_message {sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(bb51..7345), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8ffd..8062), signature: Signature(ac24..d407) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Trusted msg authority in message (MessageId(bb51..7345)) from UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }: JoinRequest(JoinRequest { section_key: PublicKey(0d39..ae26), resource_proof_response: None, aggregated: None })
TRACE 2021-12-31T00:00:50.905783Z [sn/src/routing/core/msg_handling/mod.rs:L139]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(bb51..7345)}
➤ handle_message {sender=UnnamedPeer { addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) } wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(bb51..7345), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(8ffd..8062), signature: Signature(ac24..d407) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Entropy check skipped for MessageId(bb51..7345), handling message directly
TRACE 2021-12-31T00:00:50.905811Z [sn/src/routing/routing_api/dispatcher.rs:L153]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944 section_key=PublicKey(0d39..ae26) command=HandleMessage MessageId(bb51..7345)}
➤ CommandHandleEnd HandleMessage MessageId(bb51..7345)
TRACE 2021-12-31T00:00:50.905929Z [sn/src/routing/routing_api/dispatcher.rs:L71]:
➤ CommandHandleSpawned HandleSystemMessage MessageId(bb51..7345) cmd_id=2935419944.0
TRACE 2021-12-31T00:00:50.906034Z [sn/src/routing/routing_api/dispatcher.rs:L148]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ CommandHandleStart
TRACE 2021-12-31T00:00:50.906049Z [sn/src/routing/core/msg_handling/mod.rs:L260]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ SystemMsgToBeHandled
TRACE 2021-12-31T00:00:50.906087Z [sn/src/routing/core/msg_handling/mod.rs:L296]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ Handling non blocking message
TRACE 2021-12-31T00:00:50.906094Z [sn/src/routing/core/msg_handling/mod.rs:L567]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ Handling msg: JoinRequest from 8ffdf7.. at 10.0.2.100:37675 (connected)
DEBUG 2021-12-31T00:00:50.906108Z [sn/src/routing/core/msg_handling/join.rs:L51]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ Received JoinRequest { section_key: PublicKey(0d39..ae26), resource_proof_response: None, aggregated: None } from 8ffdf7.. at 10.0.2.100:37675 (connected)
TRACE 2021-12-31T00:00:50.906158Z [sn/src/routing/core/msg_handling/join.rs:L184]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ our_prefix Prefix() section_members 1 expected_age 98 is_age_invalid false
TRACE 2021-12-31T00:01:02.075422Z [sn/src/node/logging/mod.rs:L71]:
➤ log {system=System { global CPU usage: 31.321886, load average: LoadAvg { one: 1.18, five: 1.05, fifteen: 1.01 }, total memory: 3990802, free memory: 1287737, total swap: 5986201, free swap: 5986201, nb CPUs: 4, nb network interfaces: 2, nb processes: 1, nb disks: 2, nb components: 1 } print_resources_usage=false}
➤ Node resource usage: Process { memory: 36968, virtual_memory: 125947904, cpu_usage: 0.020836806, disk_usage: DiskUsage { total_written_bytes: 139264, written_bytes: 24576, total_read_bytes: 0, read_bytes: 0 } } prefix="000000.."
TRACE 2021-12-31T00:01:50.902457Z [sn/src/routing/core/comm.rs:L485]:
➤ new
➤ handle_incoming_connections
➤ handle_incoming_messages {connection=Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }}
➤ ConnectionClosed connection_id=848182192 src=10.0.2.100:37675
INFO 2021-12-31T00:01:50.908759Z [sn/src/routing/core/comm.rs:L183]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ Peer 10.0.2.100:37675 is NOT externally reachable: Send(ConnectionLost(TimedOut))
TRACE 2021-12-31T00:01:50.908806Z [sn/src/routing/core/msg_handling/join.rs:L237]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ SendJoinRejected
TRACE 2021-12-31T00:01:50.908814Z [sn/src/routing/core/msg_handling/join.rs:L239]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ Sending JoinResponse(Rejected(NodeNotReachable(10.0.2.100:37675))) to 8ffdf7.. at 10.0.2.100:37675 (connected)
TRACE 2021-12-31T00:01:50.909026Z [sn/src/routing/core/messaging.rs:L568]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ SendDirect
TRACE 2021-12-31T00:01:50.909061Z [sn/src/routing/routing_api/dispatcher.rs:L153]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0 section_key=PublicKey(0d39..ae26) command=HandleSystemMessage MessageId(bb51..7345)}
➤ CommandHandleEnd HandleSystemMessage MessageId(bb51..7345)
TRACE 2021-12-31T00:01:50.909260Z [sn/src/routing/routing_api/dispatcher.rs:L71]:
➤ CommandHandleSpawned SendMessage MessageId(e717..5355) cmd_id=2935419944.0.0
TRACE 2021-12-31T00:01:50.909382Z [sn/src/routing/routing_api/dispatcher.rs:L148]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(e717..5355)}
➤ CommandHandleStart
TRACE 2021-12-31T00:01:50.909507Z [sn/src/routing/core/comm.rs:L214]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(e717..5355)}
➤ send {recipients=[Peer { name: 8ffdf7(10001111).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(e717..5355), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(f6ae..4905) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Sending message (msg_id: MessageId(e717..5355)) to 1 of [Peer { name: 8ffdf7(10001111).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }]
TRACE 2021-12-31T00:01:50.909633Z [sn/src/routing/core/comm.rs:L326]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(e717..5355)}
➤ send {recipients=[Peer { name: 8ffdf7(10001111).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(e717..5355), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(f6ae..4905) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Sending message (459 bytes, msg_id: MessageId(e717..5355)) to 8ffdf7.. at 10.0.2.100:37675 (connected)
TRACE 2021-12-31T00:01:50.909660Z [sn/src/routing/core/comm.rs:L362]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(e717..5355)}
➤ send {recipients=[Peer { name: 8ffdf7(10001111).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(e717..5355), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(f6ae..4905) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ ConnectionReused connection_id=848182192 src=10.0.2.100:37675
TRACE 2021-12-31T00:01:50.909689Z [sn/src/routing/core/comm.rs:L326]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(e717..5355)}
➤ send {recipients=[Peer { name: 8ffdf7(10001111).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(e717..5355), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(f6ae..4905) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Sending message (459 bytes, msg_id: MessageId(e717..5355)) to 8ffdf7.. at 10.0.2.100:37675 (connected)
TRACE 2021-12-31T00:01:50.914142Z [sn/src/routing/core/comm.rs:L464]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(e717..5355)}
➤ send {recipients=[Peer { name: 8ffdf7(10001111).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(e717..5355), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(f6ae..4905) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ handle_incoming_messages {connection=Connection { id: 848103120, remote_address: 10.0.2.100:37675, .. }}
➤ ConnectionOpened connection_id=848103120 src=10.0.2.100:37675
TRACE 2021-12-31T00:01:50.915634Z [sn/src/routing/core/comm.rs:L297]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(e717..5355)}
➤ send {recipients=[Peer { name: 8ffdf7(10001111).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(e717..5355), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(f6ae..4905) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ Finished sending message WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(e717..5355), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(f6ae..4905) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } } to 1/1 recipients (failed: [])
TRACE 2021-12-31T00:01:50.916285Z [sn/src/routing/routing_api/dispatcher.rs:L153]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(e717..5355)}
➤ CommandHandleEnd SendMessage MessageId(e717..5355)
TRACE 2021-12-31T00:01:50.921000Z [sn/src/routing/core/comm.rs:L485]:
➤ handle_command {name=c9a8fa.. prefix=() age=255 elder=true cmd_id=2935419944.0.0 section_key=PublicKey(0d39..ae26) command=SendMessage MessageId(e717..5355)}
➤ send {recipients=[Peer { name: 8ffdf7(10001111).., addr: 10.0.2.100:37675, connection: Some(Connection { id: 848182192, remote_address: 10.0.2.100:37675, .. }) }] delivery_group_size=1 wire_msg=WireMsg { header: WireMsgHeader { version: 1, msg_envelope: MsgEnvelope { msg_id: MessageId(e717..5355), msg_kind: NodeAuthMsg(NodeAuth { section_pk: PublicKey(0d39..ae26), public_key: PublicKey(c9a8..eeff), signature: Signature(f6ae..4905) }), dst_location: Section { name: 8ffdf7(10001111).., section_pk: PublicKey(0d39..ae26) } } } }}
➤ handle_incoming_messages {connection=Connection { id: 848103120, remote_address: 10.0.2.100:37675, .. }}
➤ ConnectionClosed connection_id=848103120 src=10.0.2.100:37675
TRACE 2021-12-31T00:02:02.075760Z [sn/src/node/logging/mod.rs:L71]:
➤ log {system=System { global CPU usage: 25.023964, load average: LoadAvg { one: 1.28, five: 1.1, fifteen: 1.03 }, total memory: 3990802, free memory: 1293737, total swap: 5986201, free swap: 5986201, nb CPUs: 4, nb network interfaces: 2, nb processes: 1, nb disks: 2, nb components: 1 } print_resources_usage=false}
➤ Node resource usage: Process { memory: 36968, virtual_memory: 125947904, cpu_usage: 0.0083357645, disk_usage: DiskUsage { total_written_bytes: 155648, written_bytes: 16384, total_read_bytes: 0, read_bytes: 0 } } prefix="000000.."
TRACE 2021-12-31T00:03:02.075995Z [sn/src/node/logging/mod.rs:L71]:
➤ log {system=System { global CPU usage: 25.02396, load average: LoadAvg { one: 1.1, five: 1.08, fifteen: 1.02 }, total memory: 3990802, free memory: 1293705, total swap: 5986201, free swap: 5986201, nb CPUs: 4, nb network interfaces: 2, nb processes: 1, nb disks: 2, nb components: 1 } print_resources_usage=false}
➤ Node resource usage: Process { memory: 36968, virtual_memory: 125947904, cpu_usage: 0.0041671875, disk_usage: DiskUsage { total_written_bytes: 155648, written_bytes: 0, total_read_bytes: 0, read_bytes: 0 } } prefix="000000.."
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment