[Nfd-dev] Calling consumer from NFD code

Mohammad Alhowaidi malhowaidi at gmail.com
Thu Dec 8 13:14:58 PST 2016 

I run the NFD with TRACE option, I was mistaken, the interest will go but
the data is not received, here the log , I marked by red the last three
line which I think its the error (probably not). Any help?

my interest name is /ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02

1481230655.666152 TRACE: [LinkService]
[id=264,local=unix:///run/nfd.sock,remote=fd://29] receiveInterest
1481230655.666194 DEBUG: [Forwarder] onIncomingInterest face=264
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.666254 TRACE: [NameTree] lookup
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.666300 TRACE: [NameTreeHashtable] found / hash=0 bucket=0
1481230655.666356 TRACE: [NameTreeHashtable] found /ndn
hash=6181921638449679482 bucket=122
1481230655.666403 TRACE: [NameTreeHashtable] found /ndn/44
hash=7352304724033790321 bucket=369
1481230655.666438 TRACE: [NameTreeHashtable] found
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34 hash=10986399680431118961
bucket=625
1481230655.666523 TRACE: [NameTreeHashtable] insert
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
hash=6488493601744978189 bucket=269
1481230655.666566 DEBUG: [ContentStore] find
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02 L
1481230655.666652 DEBUG: [ContentStore]   no-match
1481230655.666733 DEBUG: [Forwarder] onContentStoreMiss
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.666799 TRACE: [Strategy] lookupFib noLinkObject found=/ndn
1481230655.666879 DEBUG: [Forwarder] onOutgoingInterest face=260
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.666927 TRACE: [LinkService] [id=260,local=udp4://
10.71.103.20:6363,remote=udp4://10.71.102.53:6363] sendInterest
1481230655.667007 TRACE: [UnicastUdpTransport] [id=260,local=udp4://
10.71.103.20:6363,remote=udp4://10.71.102.53:6363] doSend
1481230655.667114 DEBUG: [BestRouteStrategy2]
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02?ndn.MaxSuffixComponents=2&ndn.Nonce=1808191983
from=264 newPitEntry-to=260
1481230655.667186 TRACE: [LinkService]
[id=264,local=unix:///run/nfd.sock,remote=fd://29] receiveInterest
1481230655.667244 DEBUG: [Forwarder] onIncomingInterest face=264
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.667291 TRACE: [NameTree] lookup
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.667349 TRACE: [NameTreeHashtable] found / hash=0 bucket=0
1481230655.667399 TRACE: [NameTreeHashtable] found /ndn
hash=6181921638449679482 bucket=122
1481230655.667437 TRACE: [NameTreeHashtable] found /ndn/44
hash=7352304724033790321 bucket=369
1481230655.667489 TRACE: [NameTreeHashtable] found
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34 hash=10986399680431118961
bucket=625
1481230655.667535 TRACE: [NameTreeHashtable] found
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
hash=6488493601744978189 bucket=269
1481230655.667603 DEBUG: [Forwarder] onContentStoreMiss
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.667661 DEBUG: [BestRouteStrategy2]
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02?ndn.MaxSuffixComponents=2&ndn.Nonce=681845873
from=264 suppressed
1481230655.667726 TRACE: [LinkService]
[id=264,local=unix:///run/nfd.sock,remote=fd://29] receiveInterest
1481230655.667780 DEBUG: [Forwarder] onIncomingInterest face=264
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.667840 TRACE: [NameTree] lookup
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.667890 TRACE: [NameTreeHashtable] found / hash=0 bucket=0
1481230655.667927 TRACE: [NameTreeHashtable] found /ndn
hash=6181921638449679482 bucket=122
1481230655.667978 TRACE: [NameTreeHashtable] found /ndn/44
hash=7352304724033790321 bucket=369
1481230655.668024 TRACE: [NameTreeHashtable] found
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34 hash=10986399680431118961
bucket=625
1481230655.668095 TRACE: [NameTreeHashtable] found
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
hash=6488493601744978189 bucket=269
1481230655.668159 DEBUG: [Forwarder] onContentStoreMiss
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.668218 DEBUG: [BestRouteStrategy2]
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02?ndn.MaxSuffixComponents=2&ndn.Nonce=3180116241
from=264 suppressed
1481230655.668281 TRACE: [LinkService]
[id=264,local=unix:///run/nfd.sock,remote=fd://29] receiveInterest
1481230655.668337 DEBUG: [Forwarder] onIncomingInterest face=264
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.668383 TRACE: [NameTree] lookup
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.668423 TRACE: [NameTreeHashtable] found / hash=0 bucket=0
1481230655.668479 TRACE: [NameTreeHashtable] found /ndn
hash=6181921638449679482 bucket=122
1481230655.668530 TRACE: [NameTreeHashtable] found /ndn/44
hash=7352304724033790321 bucket=369
1481230655.668568 TRACE: [NameTreeHashtable] found
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34 hash=10986399680431118961
bucket=625
1481230655.668608 TRACE: [NameTreeHashtable] found
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
hash=6488493601744978189 bucket=269
1481230655.668662 DEBUG: [Forwarder] onContentStoreMiss
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02
1481230655.668730 DEBUG: [BestRouteStrategy2]
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%02?ndn.MaxSuffixComponents=2&ndn.Nonce=4013909888
from=264 suppressed
1481230655.668798 TRACE: [InternalForwarderTransport]
[id=1,local=internal://,remote=internal://] receiveFromLink
1481230655.668861 TRACE: [LinkService]
[id=1,local=internal://,remote=internal://] receiveData
1481230655.668911 DEBUG: [Forwarder] onIncomingData face=1
data=/localhost/nfd/faces/events/%FE%0A
1481230655.668973 TRACE: [NameTreeHashtable] not-found
/localhost/nfd/faces/events/%FE%0A hash=9958661621874513940 bucket=20
1481230655.669026 TRACE: [NameTreeHashtable] found
/localhost/nfd/faces/events hash=1233637898983554347 bucket=299
1481230655.669085 TRACE: [NameTreeIterator] initialized
entry=/localhost/nfd/faces/events ref=/localhost/nfd/faces/events state=0
1481230655.669131 TRACE: [NameTreeIterator] advanced end
1481230655.669172 DEBUG: [ContentStore] insert
/localhost/nfd/faces/events/%FE%0A
1481230655.669230 DEBUG: [Forwarder] onIncomingData
matching=/localhost/nfd/faces/events
1481230655.669290 DEBUG: [Strategy] beforeSatisfyInterest
pitEntry=/localhost/nfd/faces/events inFace=1
data=/localhost/nfd/faces/events/%FE%0A
1481230655.669371 DEBUG: [Forwarder] onOutgoingData face=258
data=/localhost/nfd/faces/events/%FE%0A
1481230655.669424 TRACE: [LinkService]
[id=258,local=unix:///run/nfd.sock,remote=fd://25] sendData
1481230655.669493 TRACE: [UnixStreamTransport]
[id=258,local=unix:///run/nfd.sock,remote=fd://25] doSend
1481230655.669631 TRACE: [RibManager] onNotification:
FaceEventNotification(Kind: created, FaceID: 264, RemoteUri: fd://29,
LocalUri: unix:///run/nfd.sock, FaceScope: local, FacePersistency:
on-demand, LinkType: point-to-point, Flags: 0)
1481230655.669766 TRACE: [UnicastUdpTransport] [id=263,local=udp4://
10.71.103.20:6363,remote=udp4://10.71.103.58:6363] Successfully sent: 4797
bytes
1481230655.669809 TRACE: [UnicastUdpTransport] [id=263,local=udp4://
10.71.103.20:6363,remote=udp4://10.71.103.58:6363] Received: 67 bytes
1481230655.669884 TRACE: [LinkService] [id=263,local=udp4://
10.71.103.20:6363,remote=udp4://10.71.103.58:6363] receiveInterest
1481230655.669934 DEBUG: [Forwarder] onIncomingInterest face=263
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34
1481230655.669990 TRACE: [NameTree] lookup
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34
1481230655.670044 TRACE: [NameTreeHashtable] found / hash=0 bucket=0
1481230655.670106 TRACE: [NameTreeHashtable] found /ndn
hash=6181921638449679482 bucket=122
1481230655.670157 TRACE: [NameTreeHashtable] found /ndn/44
hash=7352304724033790321 bucket=369
1481230655.670206 TRACE: [NameTreeHashtable] found
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34 hash=10986399680431118961
bucket=625
1481230655.670265 DEBUG: [ContentStore] find
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34 L
1481230655.670315 DEBUG: [ContentStore]   matching
/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%00
1481230655.670378 DEBUG: [Forwarder] onContentStoreHit
interest=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34
1481230655.670421 DEBUG: [Forwarder] onOutgoingData face=263
data=/ndn/44/d83ef13781d9c0f6d6a825f1000ebe38752d34/%00%00
1481230655.670472 TRACE: [LinkService] [id=263,local=udp4://
10.71.103.20:6363,remote=udp4://10.71.103.58:6363] sendData
1481230655.670525 TRACE: [UnicastUdpTransport] [id=263,local=udp4://
10.71.103.20:6363,remote=udp4://10.71.103.58:6363] doSend
1481230655.670613 TRACE: [DeadNonceList] adjustCapacity DOWN capacity=8
1481230655.670665 TRACE: [DeadNonceList] mark nMarks=8
1481230655.670719 TRACE: [UnicastUdpTransport] [id=260,local=udp4://
10.71.103.20:6363,remote=udp4://10.71.102.53:6363] Successfully sent: 68
bytes
1481230655.670770 TRACE: [UnixStreamTransport]
[id=264,local=unix:///run/nfd.sock,remote=fd://29] processErrorCode
1481230655.670809 INFO: [Transport]
[id=264,local=unix:///run/nfd.sock,remote=fd://29] setState UP -> FAILED
1481230655.670874 TRACE: [UnixStreamTransport]
[id=264,local=unix:///run/nfd.sock,remote=fd://29] doClose



On Thu, Dec 8, 2016 at 12:00 PM, Mohammad Alhowaidi <malhowaidi at gmail.com>
wrote:

> I am not using ndnSIM, I am running my experiments on VMs.
>
> Thanks,
> Mohammad
>
> On Thu, Dec 8, 2016 at 11:50 AM, Spyridon (Spyros) Mastorakis <
> mastorakis at cs.ucla.edu> wrote:
>
>> Hi,
>>
>> Are you using ndnSIM?
>>
>> In ndnSIM, you can enable the nfd.Forwarder logging component and see
>> what is going with your Interest in the Forwarder module.
>>
>> Thanks,
>>
>> Spyridon (Spyros) Mastorakis
>> Personal Website: http://cs.ucla.edu/~mastorakis/
>> Internet Research Laboratory
>> Computer Science Department
>> UCLA
>>
>> On Dec 8, 2016, at 9:47 AM, Mohammad Alhowaidi <malhowaidi at gmail.com>
>> wrote:
>>
>> Hello,
>>
>> I am trying to send an interest when a data arrive the router. I do that
>> in the function Forwarder::onIncomingData in the NFD code,(so the consumer
>> is called for NFD code), but it seems the interest never go and always
>> reach timeout and doesn't reach the producer.
>>
>> Is there something preventing the consumer from sending the interest?
>>
>> Thanks,
>> Mohammad
>>
>>
>>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.lists.cs.ucla.edu/pipermail/nfd-dev/attachments/20161208/422a51b5/attachment-0001.html>

More information about the Nfd-dev mailing list