[ndnSIM] Interest packets are not forwarded

[Amir Ibrahim]

Hi Members,
   I modified one of the examples to simulate the mobility impact on
caching.
My scenario is:
1. a grid of routers (5*5)
2. 8 WiFi access points
3. 15 mobile wifi consumers
4. Only one producer (router at grid(4,4) positions)

- both routers and APs are cache enabled, WiFi consumers are unable to
cache.
- scenario code is accessed from here
<https://gist.github.com/amiribrahim/47b31eb1d3918e21fa6717c7f5098af2> (
https://gist.github.com/amiribrahim/47b31eb1d3918e21fa6717c7f5098af2 )

When I run the scenario, I get lot of NACKs with no route reason (when I
use Best-route forwarding), and re-transmission trials when I use
(multicast) forwarding strategy.

Any clue why nodes aren't forwarding interest packets

as below:


ns3 at ubuntu:~/ndnSIM/ns-3$ NS_LOG=ndn.Consumer:ndn.Producer ./waf --run
scratch/project-simulation --vis
Waf: Entering directory `/home/ns3/ndnSIM/ns-3/build'
Waf: Leaving directory `/home/ns3/ndnSIM/ns-3/build'
Build commands will be stored in build/compile_commands.json
'build' finished successfully (3.637s)
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Consumer:Consumer()
0s -1 ndn.Producer:Producer()
0s 24 ndn.Producer:StartApplication()
0s 33 ndn.Consumer:StartApplication()
0s 34 ndn.Consumer:StartApplication()
0s 35 ndn.Consumer:StartApplication()
0s 36 ndn.Consumer:StartApplication()
0s 37 ndn.Consumer:StartApplication()
0s 38 ndn.Consumer:StartApplication()
0s 39 ndn.Consumer:StartApplication()
0s 40 ndn.Consumer:StartApplication()
0s 41 ndn.Consumer:StartApplication()
0s 42 ndn.Consumer:StartApplication()
0s 43 ndn.Consumer:StartApplication()
0s 44 ndn.Consumer:StartApplication()
0s 45 ndn.Consumer:StartApplication()
0s 46 ndn.Consumer:StartApplication()
0s 47 ndn.Consumer:StartApplication()
0s 33 ndn.Consumer:SendPacket()
0s 33 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 33 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 34 ndn.Consumer:SendPacket()
0s 34 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 34 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 35 ndn.Consumer:SendPacket()
0s 35 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 35 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 36 ndn.Consumer:SendPacket()
0s 36 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 36 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 37 ndn.Consumer:SendPacket()
0s 37 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 37 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 38 ndn.Consumer:SendPacket()
0s 38 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 38 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 39 ndn.Consumer:SendPacket()
0s 39 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 39 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 40 ndn.Consumer:SendPacket()
0s 40 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 40 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 41 ndn.Consumer:SendPacket()
0s 41 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 41 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 42 ndn.Consumer:SendPacket()
0s 42 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 42 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 43 ndn.Consumer:SendPacket()
0s 43 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 43 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 44 ndn.Consumer:SendPacket()
0s 44 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 44 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 45 ndn.Consumer:SendPacket()
0s 45 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 45 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 46 ndn.Consumer:SendPacket()
0s 46 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 46 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0s 47 ndn.Consumer:SendPacket()
0s 47 ndn.Consumer:SendPacket(): [INFO ] > Interest for 0
0s 47 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 0 with
+0.0ns. already 0 items
0.5s 33 ndn.Consumer:SendPacket()
0.5s 33 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 33 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 34 ndn.Consumer:SendPacket()
0.5s 34 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 34 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 35 ndn.Consumer:SendPacket()
0.5s 35 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 35 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 36 ndn.Consumer:SendPacket()
0.5s 36 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 36 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 37 ndn.Consumer:SendPacket()
0.5s 37 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 37 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 38 ndn.Consumer:SendPacket()
0.5s 38 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 38 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 39 ndn.Consumer:SendPacket()
0.5s 39 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 39 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 40 ndn.Consumer:SendPacket()
0.5s 40 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 40 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 41 ndn.Consumer:SendPacket()
0.5s 41 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 41 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 42 ndn.Consumer:SendPacket()
0.5s 42 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 42 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 43 ndn.Consumer:SendPacket()
0.5s 43 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 43 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 44 ndn.Consumer:SendPacket()
0.5s 44 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 44 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 45 ndn.Consumer:SendPacket()
0.5s 45 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 45 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 46 ndn.Consumer:SendPacket()
0.5s 46 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 46 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.5s 47 ndn.Consumer:SendPacket()
0.5s 47 ndn.Consumer:SendPacket(): [INFO ] > Interest for 1
0.5s 47 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 1 with
+500000000.0ns. already 1 items
0.500489s 37 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%01, reason: NoRoute
0.500498s 43 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%01, reason: NoRoute
0.501802s 39 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%01, reason: NoRoute
0.502403s 41 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%01, reason: NoRoute
0.502652s 38 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%01, reason: NoRoute
0.503541s 35 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%01, reason: NoRoute
1s 33 ndn.Consumer:SendPacket()
1s 33 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 33 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 34 ndn.Consumer:SendPacket()
1s 34 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 34 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 35 ndn.Consumer:SendPacket()
1s 35 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 35 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 36 ndn.Consumer:SendPacket()
1s 36 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 36 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 37 ndn.Consumer:SendPacket()
1s 37 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 37 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 38 ndn.Consumer:SendPacket()
1s 38 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 38 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 39 ndn.Consumer:SendPacket()
1s 39 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 39 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 40 ndn.Consumer:SendPacket()
1s 40 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 40 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 41 ndn.Consumer:SendPacket()
1s 41 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 41 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 42 ndn.Consumer:SendPacket()
1s 42 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 42 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 43 ndn.Consumer:SendPacket()
1s 43 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 43 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 44 ndn.Consumer:SendPacket()
1s 44 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 44 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 45 ndn.Consumer:SendPacket()
1s 45 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 45 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 46 ndn.Consumer:SendPacket()
1s 46 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 46 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s 47 ndn.Consumer:SendPacket()
1s 47 ndn.Consumer:SendPacket(): [INFO ] > Interest for 2
1s 47 ndn.Consumer:WillSendOutInterest(): [DEBUG] Trying to add 2 with
+1000000000.0ns. already 2 items
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
1s -1 ndn.Consumer:OnTimeout(0)
*1.0005s 37 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%02, reason: NoRoute*
*1.00056s 43 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%02, reason: NoRoute*
*1.00178s 41 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%02, reason: NoRoute*
*1.00196s 38 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%02, reason: NoRoute*
*1.00229s 39 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%02, reason: NoRoute*
*1.00395s 35 ndn.Consumer:OnNack(): [INFO ] NACK received for:
/prefix/%FE%02, reason: NoRoute*
*1.5s 33 ndn.Consumer:SendPacket()*

-- 

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.lists.cs.ucla.edu/pipermail/ndnsim/attachments/20170420/4783d1ce/attachment-0001.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: project-simulation.cc
Type: application/octet-stream
Size: 10636 bytes
Desc: not available
URL: <http://www.lists.cs.ucla.edu/pipermail/ndnsim/attachments/20170420/4783d1ce/attachment-0001.obj>