[ndnSIM] Unexpected output for 3 nodes configuration using wifi

[Otávio Augusto]

Hello everyone.

I'm testing a multi-node with wifi connection in ndnSIM, the code is
attached (ndn_wifi_3_nodes.cc). In addition, I'm using ndnSIM from Windows
OS with WSL 2, Ubuntu 20.04  (I don't know if this is important).

Using the following command I get some log information about the consumer:


*NS_LOG=ndn.Consumer ./waf --run=ndn_wifi_3_nodes*
The output is in the out.txt attachment.
I expected the communication to go through the intermediary. However, as
you can see, many messages timed out and the data packets that reached the
consumer contain hop count equal to 1, which probably means that the
consumer and producer are communicating directly, without using the
intermediary node. Why did this happen?

Note 1: in the 'ndn-simple-wifi' example the best-route strategy is used,
but for me it resulted in NACK, I don't know why. Because of this, I used
the multicast strategy.
Note 2: How can I see the ndn route log (intermediate node, node 1)? I
know how to check the consumer and producer logs, but I don't know what
name I use to check the ndn router log with *NS_LOG*.
--
Otávio da Cruz
Doctor student
Electrical Engineering at the Graduate Program in Electrical Engineering of
the Federal University of Rio Grande do Sul (UFRGS) - Brazil
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.lists.cs.ucla.edu/pipermail/ndnsim/attachments/20250115/1ea8e6e7/attachment-0001.htm>
-------------- next part --------------
set position for nodes
+0.000000000s 0 ndn.Consumer:Consumer()
+0.000000000s 0 ndn.Consumer:StartApplication()
+0.000000000s 0 ndn.Consumer:SendPacket()
+0.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
+0.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with +0ns. already 0 items
+1.000000000s 0 ndn.Consumer:SendPacket()
+1.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
+1.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with +1e+09ns. already 1 items
+1.000000000s 0 ndn.Consumer:OnTimeout(0)
+2.000000000s 0 ndn.Consumer:SendPacket()
+2.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
+2.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with +2e+09ns. already 1 items
+2.000568734s 0 ndn.Consumer:OnData(0x5604fa5a2de0, 0x5604fa6c2550)
+2.000568734s 0 ndn.Consumer:OnData(): [INFO ] < DATA for 0
+2.000568734s 0 ndn.Consumer:OnData(): [DEBUG] Hop count: 1
+3.000000000s 0 ndn.Consumer:SendPacket()
+3.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
+3.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with +3e+09ns. already 1 items
+3.000000000s 0 ndn.Consumer:OnTimeout(1)
+4.000000000s 0 ndn.Consumer:SendPacket()
+4.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
+4.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with +4e+09ns. already 1 items
+5.000000000s 0 ndn.Consumer:SendPacket()
+5.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 3
+5.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 3 with +5e+09ns. already 2 items
+6.000000000s 0 ndn.Consumer:SendPacket()
+6.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 4
+6.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 4 with +6e+09ns. already 3 items
+6.000568734s 0 ndn.Consumer:OnData(0x5604fa5a2de0, 0x5604fa6c2550)
+6.000568734s 0 ndn.Consumer:OnData(): [INFO ] < DATA for 4
+6.000568734s 0 ndn.Consumer:OnData(): [DEBUG] Hop count: 1
+6.050000000s 0 ndn.Consumer:OnTimeout(2)
+6.050000000s 0 ndn.Consumer:OnTimeout(1)
+6.050000000s 0 ndn.Consumer:OnTimeout(3)
+7.000000000s 0 ndn.Consumer:SendPacket()
+7.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
+7.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with +7e+09ns. already 0 items
+8.000000000s 0 ndn.Consumer:SendPacket()
+8.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
+8.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with +8e+09ns. already 1 items
+8.000568734s 0 ndn.Consumer:OnData(0x5604fa5a2de0, 0x5604fa6c2550)
+8.000568734s 0 ndn.Consumer:OnData(): [INFO ] < DATA for 2
+8.000568734s 0 ndn.Consumer:OnData(): [DEBUG] Hop count: 1
+8.600000000s 0 ndn.Consumer:OnTimeout(1)
+9.000000000s 0 ndn.Consumer:SendPacket()
+9.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
+9.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with +9e+09ns. already 0 items
+10.000000000s 0 ndn.Consumer:SendPacket()
+10.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 3
+10.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 3 with +1e+10ns. already 1 items
+11.000000000s 0 ndn.Consumer:SendPacket()
+11.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 5
+11.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 5 with +1.1e+10ns. already 2 items
+12.000000000s 0 ndn.Consumer:SendPacket()
+12.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 6
+12.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 6 with +1.2e+10ns. already 3 items
+12.200000000s 0 ndn.Consumer:OnTimeout(1)
+13.000000000s 0 ndn.Consumer:SendPacket()
+13.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
+13.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with +1.3e+10ns. already 3 items
+14.000000000s 0 ndn.Consumer:SendPacket()
+14.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 7
+14.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 7 with +1.4e+10ns. already 4 items
+15.000000000s 0 ndn.Consumer:SendPacket()
+15.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 8
+15.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 8 with +1.5e+10ns. already 5 items
+16.000000000s 0 ndn.Consumer:SendPacket()
+16.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 9
+16.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 9 with +1.6e+10ns. already 6 items
+16.400000000s 0 ndn.Consumer:OnTimeout(3)
+17.000000000s 0 ndn.Consumer:SendPacket()
+17.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 3
+17.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 3 with +1.7e+10ns. already 6 items
+18.000000000s 0 ndn.Consumer:SendPacket()
+18.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 10
+18.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 10 with +1.8e+10ns. already 7 items
+19.000000000s 0 ndn.Consumer:SendPacket()
+19.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 11
+19.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 11 with +1.9e+10ns. already 8 items
+20.000000000s 0 ndn.Consumer:SendPacket()
+20.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 12
+20.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 12 with +2e+10ns. already 9 items
+21.000000000s 0 ndn.Consumer:SendPacket()
+21.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 13
+21.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 13 with +2.1e+10ns. already 10 items
+22.000000000s 0 ndn.Consumer:SendPacket()
+22.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 14
+22.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 14 with +2.2e+10ns. already 11 items
+22.000568734s 0 ndn.Consumer:OnData(0x5604fa5a2de0, 0x5604fa6c2550)
+22.000568734s 0 ndn.Consumer:OnData(): [INFO ] < DATA for 14
+22.000568734s 0 ndn.Consumer:OnData(): [DEBUG] Hop count: 1
+22.050000000s 0 ndn.Consumer:OnTimeout(5)
+22.050000000s 0 ndn.Consumer:OnTimeout(6)
+22.050000000s 0 ndn.Consumer:OnTimeout(1)
+22.050000000s 0 ndn.Consumer:OnTimeout(7)
+22.050000000s 0 ndn.Consumer:OnTimeout(8)
+22.050000000s 0 ndn.Consumer:OnTimeout(9)
+22.050000000s 0 ndn.Consumer:OnTimeout(3)
+22.050000000s 0 ndn.Consumer:OnTimeout(10)
+22.050000000s 0 ndn.Consumer:OnTimeout(11)
+22.050000000s 0 ndn.Consumer:OnTimeout(12)
+22.050000000s 0 ndn.Consumer:OnTimeout(13)
+23.000000000s 0 ndn.Consumer:SendPacket()
+23.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
+23.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with +2.3e+10ns. already 0 items
+24.000000000s 0 ndn.Consumer:SendPacket()
+24.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 3
+24.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 3 with +2.4e+10ns. already 1 items
+25.000000000s 0 ndn.Consumer:SendPacket()
+25.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 5
+25.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 5 with +2.5e+10ns. already 2 items
+25.000568734s 0 ndn.Consumer:OnData(0x5604fa5a2de0, 0x5604fa6c2550)
+25.000568734s 0 ndn.Consumer:OnData(): [INFO ] < DATA for 5
+25.000568734s 0 ndn.Consumer:OnData(): [DEBUG] Hop count: 1
+26.000000000s 0 ndn.Consumer:SendPacket()
+26.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 6
+26.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 6 with +2.6e+10ns. already 2 items
+27.000000000s 0 ndn.Consumer:SendPacket()
+27.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 7
+27.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 7 with +2.7e+10ns. already 3 items
+28.000000000s 0 ndn.Consumer:SendPacket()
+28.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 8
+28.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 8 with +2.8e+10ns. already 4 items
+29.000000000s 0 ndn.Consumer:SendPacket()
+29.000000000s 0 ndn.Consumer:SendPacket(): [INFO ] > Interest for 9
+29.000000000s 0 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 9 with +2.9e+10ns. already 5 items
-------------- next part --------------
A non-text attachment was scrubbed...
Name: ndn_wifi_3_nodes.cc
Type: application/octet-stream
Size: 4363 bytes
Desc: not available
URL: <http://www.lists.cs.ucla.edu/pipermail/ndnsim/attachments/20250115/1ea8e6e7/attachment-0001.obj>