[Nfd-dev] NFD: Error code:1, message:Timeout

Mon Apr 18 01:33:03 PDT 2016

Hi all,

Could somebody help me to found the reason of next error:

[root at Producer1 ndn]# nfd-status
1460967791.879474 INFO: [FaceTable] Removed face id=267 remote=fd://40
local=unix:///run/nfd.sock
Error code:1, message:Timeout
Error code:1, message:Timeout
Error code:1, message:Timeout
1460967803.531449 FATAL: [NFD] std::bad_alloc
1460967803.839138 FATAL: [NFD] std::bad_alloc
ERROR: error while receiving data from socket (Connection reset by peer)

As it show above error appear after I trying to check nfd status.
Previously I had installed new OS version (Fedora 23) and newest version of
NFD platform (0.4.1). And from that moment I wasn't able to check NFD
status without getting this error.

I have another machine with same OS and NFD versions and I didn't get such
error over there.

This error also goes together with strong freezes of OS, because RAM
consumption rise to 100% at the time when nfd trying to produce output for
nfd-status.

Here also debug log: while I tried to get nfd-status several times:

[root at Producer1 ndn]# nfd-start
1460967621.445331 INFO: [StrategyChoice] setDefaultStrategy
/localhost/nfd/strategy/best-route/%FD%04
1460967621.445839 INFO: [InternalForwarderTransport]
[id=0,local=internal://,remote=internal://] Creating transport
1460967621.445913 INFO: [FaceTable] Added face id=1 remote=internal://
local=internal://
1460967621.447226 WARNING: [CommandValidator] Wildcard identity is intended
for demo purpose only and SHOULD NOT be used in production environment
1460967621.447244 INFO: [CommandValidator] Giving privilege "faces" to
identity wildcard
1460967621.447254 INFO: [CommandValidator] Giving privilege "fib" to
identity wildcard
1460967621.447266 INFO: [CommandValidator] Giving privilege
"strategy-choice" to identity wildcard
1460967621.447603 INFO: [StrategyChoice] changeStrategy(/ndn/broadcast)
from /localhost/nfd/strategy/best-route/%FD%04 to
/localhost/nfd/strategy/multicast/%FD%01
1460967621.447797 INFO: [StrategyChoice] changeStrategy(/localhost) from
/localhost/nfd/strategy/best-route/%FD%04 to
/localhost/nfd/strategy/multicast/%FD%01
1460967621.448039 INFO: [StrategyChoice] changeStrategy(/localhost/nfd)
from /localhost/nfd/strategy/multicast/%FD%01 to
/localhost/nfd/strategy/best-route/%FD%04
1460967621.448273 INFO: [TablesConfigSection] Setting CS max packets to
65536
1460967621.451005 INFO: [MulticastUdpTransport] [id=0,local=udp4://
192.168.1.1:56363,remote=udp4://224.0.23.170:56363] Creating transport
1460967621.451070 INFO: [FaceTable] Added face id=256 remote=udp4://
224.0.23.170:56363 local=udp4://192.168.1.1:56363
1460967621.451219 INFO: [MulticastUdpTransport] [id=0,local=udp4://
203.253.235.164:56363,remote=udp4://224.0.23.170:56363] Creating transport
1460967621.451274 INFO: [FaceTable] Added face id=257 remote=udp4://
224.0.23.170:56363 local=udp4://203.253.235.164:56363
1460967621.451370 INFO: [EthernetTransport]
[id=0,local=dev://enp4s0f1,remote=ether://[01:00:5e:00:17:aa]] Creating
transport
1460967621.459711 INFO: [FaceTable] Added face id=258
remote=ether://[01:00:5e:00:17:aa] local=dev://enp4s0f1
1460967621.459768 INFO: [EthernetTransport]
[id=0,local=dev://enp4s0f0,remote=ether://[01:00:5e:00:17:aa]] Creating
transport
1460967621.468604 INFO: [FaceTable] Added face id=259
remote=ether://[01:00:5e:00:17:aa] local=dev://enp4s0f0
1460967621.468665 INFO: [EthernetTransport]
[id=0,local=dev://eno4,remote=ether://[01:00:5e:00:17:aa]] Creating
transport
1460967621.477496 INFO: [FaceTable] Added face id=260
remote=ether://[01:00:5e:00:17:aa] local=dev://eno4
1460967621.477564 INFO: [EthernetTransport]
[id=0,local=dev://eno1,remote=ether://[01:00:5e:00:17:aa]] Creating
transport
1460967621.486329 INFO: [FaceTable] Added face id=261
remote=ether://[01:00:5e:00:17:aa] local=dev://eno1
1460967621.486394 INFO: [EthernetTransport]
[id=0,local=dev://eno2,remote=ether://[01:00:5e:00:17:aa]] Creating
transport
1460967621.498327 INFO: [FaceTable] Added face id=262
remote=ether://[01:00:5e:00:17:aa] local=dev://eno2
1460967621.498389 INFO: [EthernetTransport]
[id=0,local=dev://eno3,remote=ether://[01:00:5e:00:17:aa]] Creating
transport
1460967621.532467 INFO: [FaceTable] Added face id=263
remote=ether://[01:00:5e:00:17:aa] local=dev://eno3
1460967621.532865 WARNING: [CommandValidator] Wildcard identity is intended
for demo purpose only and SHOULD NOT be used in production environment
1460967621.532885 INFO: [CommandValidator] Giving privilege "faces" to
identity wildcard
1460967621.533031 INFO: [CommandValidator] Giving privilege "fib" to
identity wildcard
1460967621.533078 INFO: [CommandValidator] Giving privilege
"strategy-choice" to identity wildcard
1460967621.533364 INFO: [InternalForwarderTransport]
[id=0,local=null://,remote=null://] Creating transport
1460967621.533396 INFO: [FaceTable] Added face id=255 remote=null://
local=null://
1460967622.145354 INFO: [InternalForwarderTransport]
[id=0,local=contentstore://,remote=contentstore://] Creating transport
1460967622.145392 INFO: [FaceTable] Added face id=254
remote=contentstore:// local=contentstore://
1460967622.716610 INFO: [PrivilegeHelper] dropped to effective uid=0 gid=0
1460967622.718703 INFO: [AutoPrefixPropagator] Load auto_prefix_propagate
section in rib section
1460967622.718990 INFO: [AutoPrefixPropagator] Load auto_prefix_propagate
section in rib section
1460967622.719025 INFO: [RibManager] Listening on: /localhost/nfd/rib
1460967622.726480 INFO: [RibManager] Start monitoring face create/destroy
events
1460967622.731414 INFO: [UnixStreamTransport]
[id=0,local=unix:///run/nfd.sock,remote=fd://38] Creating transport
1460967622.731446 INFO: [FaceTable] Added face id=264 remote=fd://38
local=unix:///run/nfd.sock
[root at Producer1 ndn]# 1460967623.935280 INFO: [AutoPrefixPropagator] local
registration only for /localhost/nfd/rib
1460967646.743111 INFO: [UnixStreamTransport]
[id=0,local=unix:///run/nfd.sock,remote=fd://39] Creating transport
1460967646.743156 INFO: [FaceTable] Added face id=265 remote=fd://39
local=unix:///run/nfd.sock
1460967651.527112 WARNING: [TcpChannel] [0.0.0.0:6363] Connect failed: No
route to host
1460967652.165977 INFO: [Transport]
[id=265,local=unix:///run/nfd.sock,remote=fd://39] setState UP -> FAILED
1460967652.166041 INFO: [Transport]
[id=265,local=unix:///run/nfd.sock,remote=fd://39] setState FAILED -> CLOSED
1460967652.735913 INFO: [FaceTable] Removed face id=265 remote=fd://39
local=unix:///run/nfd.sock
1460967654.257621 INFO: [UnixStreamTransport]
[id=0,local=unix:///run/nfd.sock,remote=fd://39] Creating transport
1460967654.257666 INFO: [FaceTable] Added face id=266 remote=fd://39
local=unix:///run/nfd.sock
1460967719.151450 INFO: [Transport]
[id=266,local=unix:///run/nfd.sock,remote=fd://39] setState UP -> FAILED
1460967719.151601 INFO: [UnixStreamTransport]
[id=0,local=unix:///run/nfd.sock,remote=fd://40] Creating transport
1460967719.151646 INFO: [FaceTable] Added face id=267 remote=fd://40
local=unix:///run/nfd.sock
1460967719.760576 INFO: [Transport]
[id=266,local=unix:///run/nfd.sock,remote=fd://39] setState FAILED -> CLOSED
1460967720.339229 INFO: [FaceTable] Removed face id=266 remote=fd://39
local=unix:///run/nfd.sock
1460967720.339489 INFO: [UnixStreamTransport]
[id=0,local=unix:///run/nfd.sock,remote=fd://39] Creating transport
1460967720.339515 INFO: [FaceTable] Added face id=268 remote=fd://39
local=unix:///run/nfd.sock
1460967772.911580 INFO: [Transport]
[id=267,local=unix:///run/nfd.sock,remote=fd://40] setState UP -> FAILED
1460967773.155180 INFO: [Transport]
[id=267,local=unix:///run/nfd.sock,remote=fd://40] setState FAILED -> CLOSED

Best Regards,
Alexander Ni
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.lists.cs.ucla.edu/pipermail/nfd-dev/attachments/20160418/17dfe82e/attachment.html>