I see strange duplications in the log.
Maybe it should be like this, do not know:
Two log fragments
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.552831800+03:00 [src\node\event_mapping\mod.rs:43] Handling RoutingEvent: MessageReceived { content: "00a500..", src: Node(d79bd2(11010111)..), dst: Node(c8384f(11001000)..) }
[tokio-runtime-worker] DEBUG 2021-06-25T11:07:37.555831800+03:00 [src\node\event_mapping\node_msg.rs:26] Handling Node message received event with id c8568e0d..: NodeQuery { query: Chunks { query: Get(Public(c2beaf(11000010)..)), origin: EndUser { xorname: c95bbf(11001001).., socket_id: 495bbf(01001001).. } }, id: c8568e0d.. }
[sn_node] DEBUG 2021-06-25T11:07:37.555831800+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: ReadChunk
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.555831800+03:00 [src\node\event_mapping\mod.rs:43] Handling RoutingEvent: MessageReceived { content: "00a500..", src: Node(e2394f(11100010)..), dst: Node(c8384f(11001000)..) }
[tokio-runtime-worker] DEBUG 2021-06-25T11:07:37.556831800+03:00 [src\node\event_mapping\node_msg.rs:26] Handling Node message received event with id c8568e0d..: NodeQuery { query: Chunks { query: Get(Public(c2beaf(11000010)..)), origin: EndUser { xorname: c95bbf(11001001).., socket_id: 495bbf(01001001).. } }, id: c8568e0d.. }
[sn_node] DEBUG 2021-06-25T11:07:37.556831800+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: ReadChunk
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.556831800+03:00 [src\node\event_mapping\mod.rs:43] Handling RoutingEvent: MessageReceived { content: "00a500..", src: Node(db272e(11011011)..), dst: Node(c8384f(11001000)..) }
[tokio-runtime-worker] DEBUG 2021-06-25T11:07:37.557831800+03:00 [src\node\event_mapping\node_msg.rs:26] Handling Node message received event with id c8568e0d..: NodeQuery { query: Chunks { query: Get(Public(c2beaf(11000010)..)), origin: EndUser { xorname: c95bbf(11001001).., socket_id: 495bbf(01001001).. } }, id: c8568e0d.. }
[sn_node] DEBUG 2021-06-25T11:07:37.557831800+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: ReadChunk
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\chunks\mod.rs:74] Checking used storage
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\chunks\mod.rs:74] Checking used storage
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\data_store\mod.rs:153] Used space: 149158642
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\data_store\mod.rs:154] Total space: 50000000000
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\data_store\mod.rs:155] Used space ratio: 0.00298317284
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\chunks\mod.rs:74] Checking used storage
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\data_store\mod.rs:153] Used space: 149158642
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\data_store\mod.rs:154] Total space: 50000000000
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\data_store\mod.rs:155] Used space ratio: 0.00298317284
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\data_store\mod.rs:153] Used space: 149158642
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\data_store\mod.rs:154] Total space: 50000000000
[tokio-runtime-worker] INFO 2021-06-25T11:07:37.559831800+03:00 [src\node\data_store\mod.rs:155] Used space ratio: 0.00298317284
[sn_node] DEBUG 2021-06-25T11:07:37.559831800+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: Send [ msg: OutgoingMsg { msg: Node(NodeQueryResponse { response: Data(GetChunk(Ok(Public(PublicChunk c2beaf(11000010)..)))), id: e43baed7.., correlation_id: c8568e0d.. }), dst: Section(c2beaf(11000010)..), section_source: false, aggregation: None } ]
[sn_node] DEBUG 2021-06-25T11:07:37.559831800+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: Send [ msg: OutgoingMsg { msg: Node(NodeQueryResponse { response: Data(GetChunk(Ok(Public(PublicChunk c2beaf(11000010)..)))), id: e43baed7.., correlation_id: c8568e0d.. }), dst: Section(c2beaf(11000010)..), section_source: false, aggregation: None } ]
[sn_node] DEBUG 2021-06-25T11:07:37.559831800+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: Send [ msg: OutgoingMsg { msg: Node(NodeQueryResponse { response: Data(GetChunk(Ok(Public(PublicChunk c2beaf(11000010)..)))), id: e43baed7.., correlation_id: c8568e0d.. }), dst: Section(c2beaf(11000010)..), section_source: false, aggregation: None } ]
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.958326600+03:00 [src\node\event_mapping\mod.rs:43] Handling RoutingEvent: MessageReceived { content: "00a500..", src: Node(db272e(11011011)..), dst: Node(c8384f(11001000)..) }
[tokio-runtime-worker] DEBUG 2021-06-25T11:31:38.958326600+03:00 [src\node\event_mapping\node_msg.rs:26] Handling Node message received event with id c645531c..: NodeQuery { query: Chunks { query: Get(Public(c0189e(11000000)..)), origin: EndUser { xorname: ea74c0(11101010).., socket_id: 6a74c0(01101010).. } }, id: c645531c.. }
[sn_node] DEBUG 2021-06-25T11:31:38.958326600+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: ReadChunk
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.959326800+03:00 [src\node\event_mapping\mod.rs:43] Handling RoutingEvent: MessageReceived { content: "00a500..", src: Node(db272e(11011011)..), dst: Node(c8384f(11001000)..) }
[tokio-runtime-worker] DEBUG 2021-06-25T11:31:38.960327+03:00 [src\node\event_mapping\node_msg.rs:26] Handling Node message received event with id 76dcd5a4..: NodeQuery { query: Chunks { query: Get(Public(dd9e0b(11011101)..)), origin: EndUser { xorname: ea74c0(11101010).., socket_id: 6a74c0(01101010).. } }, id: 76dcd5a4.. }
[sn_node] DEBUG 2021-06-25T11:31:38.960327+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: ReadChunk
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.960327+03:00 [src\node\event_mapping\mod.rs:43] Handling RoutingEvent: MessageReceived { content: "00a500..", src: Node(fd2b98(11111101)..), dst: Node(c8384f(11001000)..) }
[tokio-runtime-worker] DEBUG 2021-06-25T11:31:38.961327200+03:00 [src\node\event_mapping\node_msg.rs:26] Handling Node message received event with id 76dcd5a4..: NodeQuery { query: Chunks { query: Get(Public(dd9e0b(11011101)..)), origin: EndUser { xorname: ea74c0(11101010).., socket_id: 6a74c0(01101010).. } }, id: 76dcd5a4.. }
[sn_node] DEBUG 2021-06-25T11:31:38.961327200+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: ReadChunk
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.961327200+03:00 [src\node\event_mapping\mod.rs:43] Handling RoutingEvent: MessageReceived { content: "00a500..", src: Node(e2394f(11100010)..), dst: Node(c8384f(11001000)..) }
[tokio-runtime-worker] DEBUG 2021-06-25T11:31:38.961327200+03:00 [src\node\event_mapping\node_msg.rs:26] Handling Node message received event with id c645531c..: NodeQuery { query: Chunks { query: Get(Public(c0189e(11000000)..)), origin: EndUser { xorname: ea74c0(11101010).., socket_id: 6a74c0(01101010).. } }, id: c645531c.. }
[sn_node] DEBUG 2021-06-25T11:31:38.961327200+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: ReadChunk
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\chunks\mod.rs:74] Checking used storage
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\chunks\mod.rs:74] Checking used storage
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\data_store\mod.rs:153] Used space: 152304454
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\data_store\mod.rs:153] Used space: 152304454
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\data_store\mod.rs:154] Total space: 50000000000
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\data_store\mod.rs:154] Total space: 50000000000
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\data_store\mod.rs:155] Used space ratio: 0.00304608908
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\data_store\mod.rs:155] Used space ratio: 0.00304608908
[sn_node] DEBUG 2021-06-25T11:31:38.966328200+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: Send [ msg: OutgoingMsg { msg: Node(NodeQueryResponse { response: Data(GetChunk(Ok(Public(PublicChunk c0189e(11000000)..)))), id: a8df1e2e.., correlation_id: c645531c.. }), dst: Section(c0189e(11000000)..), section_source: false, aggregation: None } ]
[sn_node] DEBUG 2021-06-25T11:31:38.966328200+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: Send [ msg: OutgoingMsg { msg: Node(NodeQueryResponse { response: Data(GetChunk(Ok(Public(PublicChunk c0189e(11000000)..)))), id: a8df1e2e.., correlation_id: c645531c.. }), dst: Section(c0189e(11000000)..), section_source: false, aggregation: None } ]
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\chunks\mod.rs:74] Checking used storage
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\data_store\mod.rs:153] Used space: 152304454
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\data_store\mod.rs:154] Total space: 50000000000
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.966328200+03:00 [src\node\data_store\mod.rs:155] Used space ratio: 0.00304608908
[sn_node] DEBUG 2021-06-25T11:31:38.967328400+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: Send [ msg: OutgoingMsg { msg: Node(NodeQueryResponse { response: Data(GetChunk(Ok(Public(PublicChunk dd9e0b(11011101)..)))), id: b2428aa1.., correlation_id: 76dcd5a4.. }), dst: Section(dd9e0b(11011101)..), section_source: false, aggregation: None } ]
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.967328400+03:00 [src\node\chunks\mod.rs:74] Checking used storage
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.967328400+03:00 [src\node\data_store\mod.rs:153] Used space: 152304454
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.967328400+03:00 [src\node\data_store\mod.rs:154] Total space: 50000000000
[tokio-runtime-worker] INFO 2021-06-25T11:31:38.967328400+03:00 [src\node\data_store\mod.rs:155] Used space ratio: 0.00304608908
[sn_node] DEBUG 2021-06-25T11:31:38.967328400+03:00 [src\node\node_api\handle.rs:51] Handling NodeDuty: Send [ msg: OutgoingMsg { msg: Node(NodeQueryResponse { response: Data(GetChunk(Ok(Public(PublicChunk dd9e0b(11011101)..)))), id: b2428aa1.., correlation_id: 76dcd5a4.. }), dst: Section(dd9e0b(11011101)..), section_source: false, aggregation: None } ]
So same request arrives via several nodes.
And then several responses are sent.
I doubt that requester needs 3 copies of the same chunk from the same node.