[Kea-users] DHCP-DDNS server repeatedly logging "Resource temporarily unavailable"
Derek Lambert
dlambert at dereklambert.com
Thu Feb 4 23:26:45 UTC 2016
If it helps here's a strace of the server without optimizations, it just
waits in the last call to epoll_wait. I gave it 5 minutes before killing it:
0.000417 fstat(1</dev/pts/0>, {st_mode=S_IFCHR|0620,
st_rdev=makedev(136, 0), ...}) = 0
0.000516 mmap(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f6d8d8bd000
0.000480 write(1</dev/pts/0>, "2016-02-04 17:18:01.054 INFO [k"...,
1272016-02-04 17:18:01.054 INFO [kea-dhcp-ddns.dhcpddns/14261]
DHCP_DDNS_STARTING DHCP-DDNS starting, pid: 14261, version: 1.0.0
) = 127
0.000636 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_UNLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000634 socket(PF_NETLINK, SOCK_RAW, NETLINK_ROUTE) =
4<NETLINK:[370455]>
0.000658 fcntl(4<NETLINK:[370455]>, F_SETFD, FD_CLOEXEC) = 0
0.000289 setsockopt(4<NETLINK:[370455]>, SOL_SOCKET, SO_SNDBUF,
"\x00\x80\x00\x00\x00\x00\x00\x00", 8) = 0
0.000344 setsockopt(4<NETLINK:[370455]>, SOL_SOCKET, SO_RCVBUF,
"\x00\x80\x00\x00\x00\x00\x00\x00", 8) = 0
0.000422 bind(4<NETLINK:[370455]>, {sa_family=AF_NETLINK, pid=0,
groups=00000000}, 12) = 0
0.000498 getsockname(4<NETLINK:[370455]>, {sa_family=AF_NETLINK,
pid=14261, groups=00000000}, [12]) = 0
0.000360 sendto(4<NETLINK:[370455]>,
"\x14\x00\x00\x00\x12\x00\x01\x03\x01\x00\x00\x00\x00\x00\x00\x00\x11\x00\x00\x00",
20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
0.000681 recvmsg(4<NETLINK:[370455]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x9c\x04\x00\x00\x10\x00\x02\x00\x01\x00\x00\x00\xb5\x37\x00\x00\x00\x00\x04\x03\x01\x00\x00\x00\x49\x00\x01\x00\x00\x00\x00\x00"...,
32768}], msg_controllen=0, msg_flags=0}, 0) = 2364
0.000440 recvmsg(4<NETLINK:[370455]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x14\x00\x00\x00\x03\x00\x02\x00\x01\x00\x00\x00\xb5\x37\x00\x00\x00\x00\x00\x00",
32768}], msg_controllen=0, msg_flags=0}, 0) = 20
0.000452 sendto(4<NETLINK:[370455]>,
"\x14\x00\x00\x00\x16\x00\x01\x03\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00",
20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
0.000810 recvmsg(4<NETLINK:[370455]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x4c\x00\x00\x00\x14\x00\x02\x00\x02\x00\x00\x00\xb5\x37\x00\x00\x02\x08\x80\xfe\x01\x00\x00\x00\x08\x00\x01\x00\x7f\x00\x00\x01"...,
32768}], msg_controllen=0, msg_flags=0}, 0) = 168
0.000803 recvmsg(4<NETLINK:[370455]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x48\x00\x00\x00\x14\x00\x02\x00\x02\x00\x00\x00\xb5\x37\x00\x00\x0a\x80\x80\xfe\x01\x00\x00\x00\x14\x00\x01\x00\x00\x00\x00\x00"...,
32768}], msg_controllen=0, msg_flags=0}, 0) = 144
0.000370 recvmsg(4<NETLINK:[370455]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x14\x00\x00\x00\x03\x00\x02\x00\x02\x00\x00\x00\xb5\x37\x00\x00\x00\x00\x00\x00",
32768}], msg_controllen=0, msg_flags=0}, 0) = 20
0.000542 close(4<NETLINK:[370455]>) = 0
0.000436 open("/etc/kea/kea.conf", O_RDONLY) = 4</etc/kea/kea.conf>
0.000361 read(4</etc/kea/kea.conf>, "{\n\n\"Dhcp4\":\n{\n
\"interfaces-conf"..., 8191) = 8191
0.000623 read(4</etc/kea/kea.conf>, "/kea-dhcp4.log\"\n }\n
"..., 8191) = 528
0.000362 read(4</etc/kea/kea.conf>, "", 8191) = 0
0.002174 brk(NULL) = 0x55bc46e73000
0.000332 brk(0x55bc46e94000) = 0x55bc46e94000
0.000320 brk(NULL) = 0x55bc46e94000
0.000274 brk(NULL) = 0x55bc46e94000
0.000320 brk(0x55bc46e93000) = 0x55bc46e93000
0.000307 brk(NULL) = 0x55bc46e93000
0.001099 close(4</etc/kea/kea.conf>) = 0
0.001167 futex(0x7f6d88bb1190, FUTEX_WAKE_PRIVATE, 2147483647) = 0
0.001664 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_WRLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000366 write(1</dev/pts/0>, "2016-02-04 17:18:01.077 INFO [k"...,
1642016-02-04 17:18:01.077 INFO [kea-dhcp-ddns.dhcpddns/14261]
DCTL_CONFIG_COMPLETE server has completed configuration: listening on
127.0.0.1, port 53001, using UDP
) = 164
0.000395 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_UNLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000740 open("/var/log/kea-dhcp4.log", O_WRONLY|O_CREAT|O_APPEND,
0666) = 4</var/log/kea-dhcp4.log>
0.000582 lseek(4</var/log/kea-dhcp4.log>, 0, SEEK_END) = 0
0.000510 open("/var/log/kea-dhcp6.log", O_WRONLY|O_CREAT|O_APPEND,
0666) = 5</var/log/kea-dhcp6.log>
0.000363 lseek(5</var/log/kea-dhcp6.log>, 0, SEEK_END) = 0
0.000398 open("/var/log/kea-ddns.log", O_WRONLY|O_CREAT|O_APPEND,
0666) = 6</var/log/kea-ddns.log>
0.000782 lseek(6</var/log/kea-ddns.log>, 0, SEEK_END) = 904
0.000593 rt_sigaction(SIGHUP, {0x7f6d89f92374, ~[RTMIN RT_1],
SA_RESTORER, 0x7f6d89b169f0}, NULL, 8) = 0
0.000439 rt_sigaction(SIGINT, {0x7f6d89f92374, ~[RTMIN RT_1],
SA_RESTORER, 0x7f6d89b169f0}, NULL, 8) = 0
0.000323 rt_sigaction(SIGTERM, {0x7f6d89f92374, ~[RTMIN RT_1],
SA_RESTORER, 0x7f6d89b169f0}, NULL, 8) = 0
0.000367 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_WRLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000355 write(6</var/log/kea-ddns.log>, "2016-02-04 17:18:01.083 INFO
[k"..., 122) = 122
0.000329 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_UNLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000344 eventfd2(0, EFD_CLOEXEC|EFD_NONBLOCK) =
7<anon_inode:[eventfd]>
0.000316 epoll_create1(EPOLL_CLOEXEC) = 8<anon_inode:[eventpoll]>
0.000268 timerfd_create(CLOCK_MONOTONIC, TFD_CLOEXEC) =
9<anon_inode:[timerfd]>
0.000285 epoll_ctl(8<anon_inode:[eventpoll]>, EPOLL_CTL_ADD,
7<anon_inode:[eventfd]>, {EPOLLIN|EPOLLERR|EPOLLET, {u32=1189499604,
u64=94267131712212}}) = 0
0.000342 write(7<anon_inode:[eventfd]>,
"\x01\x00\x00\x00\x00\x00\x00\x00", 8) = 8
0.000333 epoll_ctl(8<anon_inode:[eventpoll]>, EPOLL_CTL_ADD,
9<anon_inode:[timerfd]>, {EPOLLIN|EPOLLERR, {u32=1189499616,
u64=94267131712224}}) = 0
0.000413 socket(PF_INET, SOCK_DGRAM, IPPROTO_UDP) = 10<UDP:[370456]>
0.000527 epoll_ctl(8<anon_inode:[eventpoll]>, EPOLL_CTL_ADD,
10<UDP:[370456]>, {EPOLLIN|EPOLLPRI|EPOLLERR|EPOLLHUP|EPOLLET,
{u32=1189566944, u64=94267131779552}}) = 0
0.000373 setsockopt(10<UDP:[370456]>, SOL_SOCKET, SO_REUSEADDR, [1],
4) = 0
0.000387 bind(10<UDP:[370456]>, {sa_family=AF_INET,
sin_port=htons(53001), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
0.000418 ioctl(10<UDP:[127.0.0.1:53001]>, FIONBIO, [1]) = 0
0.000415 recvmsg(10<UDP:[127.0.0.1:53001]>, 0x7ffd6a144a50, 0) = -1
EAGAIN (Resource temporarily unavailable)
0.000453 epoll_wait(8<anon_inode:[eventpoll]>, [{EPOLLIN,
{u32=1189499604, u64=94267131712212}}], 128, 0) = 1
0.000411 epoll_wait(8<anon_inode:[eventpoll]>,
The optimized version only calls epoll_wait once then loops. Maybe the
compiler is optimizing the 2nd call away?
0.000115 fstat(1</dev/pts/0>, {st_mode=S_IFCHR|0620,
st_rdev=makedev(136, 0), ...}) = 0
0.000089 mmap(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2bac962000
0.000102 write(1</dev/pts/0>, "2016-02-04 17:11:41.860 INFO [k"...,
1252016-02-04 17:11:41.860 INFO [kea-dhcp-ddns.dhcpddns/2026]
DHCP_DDNS_STARTING DHCP-DDNS starting, pid: 2026, version: 1.0.0
) = 125
0.000103 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_UNLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000568 socket(PF_NETLINK, SOCK_RAW, NETLINK_ROUTE) =
4<NETLINK:[31622]>
0.001403 fcntl(4<NETLINK:[31622]>, F_SETFD, FD_CLOEXEC) = 0
0.000105 setsockopt(4<NETLINK:[31622]>, SOL_SOCKET, SO_SNDBUF,
"\x00\x80\x00\x00\x00\x00\x00\x00", 8) = 0
0.000139 setsockopt(4<NETLINK:[31622]>, SOL_SOCKET, SO_RCVBUF,
"\x00\x80\x00\x00\x00\x00\x00\x00", 8) = 0
0.000109 bind(4<NETLINK:[31622]>, {sa_family=AF_NETLINK, pid=0,
groups=00000000}, 12) = 0
0.000114 getsockname(4<NETLINK:[31622]>, {sa_family=AF_NETLINK,
pid=2026, groups=00000000}, [12]) = 0
0.000098 sendto(4<NETLINK:[31622]>,
"\x14\x00\x00\x00\x12\x00\x01\x03\x01\x00\x00\x00\x00\x00\x00\x00\x11\x00\x00\x00",
20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
0.000576 recvmsg(4<NETLINK:[31622]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x88\x04\x00\x00\x10\x00\x02\x00\x01\x00\x00\x00\xea\x07\x00\x00\x00\x00\x04\x03\x01\x00\x00\x00\x49\x00\x01\x00\x00\x00\x00\x00"...,
32768}], msg_controllen=0, msg_flags=0}, 0) = 2324
0.000178 recvmsg(4<NETLINK:[31622]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x14\x00\x00\x00\x03\x00\x02\x00\x01\x00\x00\x00\xea\x07\x00\x00\x00\x00\x00\x00",
32768}], msg_controllen=0, msg_flags=0}, 0) = 20
0.000136 sendto(4<NETLINK:[31622]>,
"\x14\x00\x00\x00\x16\x00\x01\x03\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00",
20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
0.000113 recvmsg(4<NETLINK:[31622]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x4c\x00\x00\x00\x14\x00\x02\x00\x02\x00\x00\x00\xea\x07\x00\x00\x02\x08\x80\xfe\x01\x00\x00\x00\x08\x00\x01\x00\x7f\x00\x00\x01"...,
32768}], msg_controllen=0, msg_flags=0}, 0) = 168
0.000132 recvmsg(4<NETLINK:[31622]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x48\x00\x00\x00\x14\x00\x02\x00\x02\x00\x00\x00\xea\x07\x00\x00\x0a\x80\x80\xfe\x01\x00\x00\x00\x14\x00\x01\x00\x00\x00\x00\x00"...,
32768}], msg_controllen=0, msg_flags=0}, 0) = 144
0.000126 recvmsg(4<NETLINK:[31622]>,
{msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\x14\x00\x00\x00\x03\x00\x02\x00\x02\x00\x00\x00\xea\x07\x00\x00\x00\x00\x00\x00",
32768}], msg_controllen=0, msg_flags=0}, 0) = 20
0.000187 close(4<NETLINK:[31622]>) = 0
0.000314 open("/etc/kea/kea.conf", O_RDONLY) = 4</etc/kea/kea.conf>
0.000288 read(4</etc/kea/kea.conf>, "{\n\n\"Dhcp4\":\n{\n
\"interfaces-conf"..., 8191) = 8191
0.000210 read(4</etc/kea/kea.conf>, "/kea-dhcp4.log\"\n }\n
"..., 8191) = 527
0.000116 read(4</etc/kea/kea.conf>, "", 8191) = 0
0.001214 brk(NULL) = 0x5608111c6000
0.000092 brk(0x5608111e7000) = 0x5608111e7000
0.000074 brk(NULL) = 0x5608111e7000
0.000054 brk(NULL) = 0x5608111e7000
0.000054 brk(0x5608111e6000) = 0x5608111e6000
0.000064 brk(NULL) = 0x5608111e6000
0.000509 close(4</etc/kea/kea.conf>) = 0
0.000590 futex(0x7f2ba8315190, FUTEX_WAKE_PRIVATE, 2147483647) = 0
0.000710 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_WRLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000384 write(1</dev/pts/0>, "2016-02-04 17:11:41.870 INFO [k"...,
1632016-02-04 17:11:41.870 INFO [kea-dhcp-ddns.dhcpddns/2026]
DCTL_CONFIG_COMPLETE server has completed configuration: listening on
127.0.0.1, port 53001, using UDP
) = 163
0.000085 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_UNLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000155 open("/var/log/kea-dhcp4.log", O_WRONLY|O_CREAT|O_APPEND,
0666) = 4</var/log/kea-dhcp4.log>
0.000387 lseek(4</var/log/kea-dhcp4.log>, 0, SEEK_END) = 14569
0.000117 open("/var/log/kea-dhcp6.log", O_WRONLY|O_CREAT|O_APPEND,
0666) = 5</var/log/kea-dhcp6.log>
0.000106 lseek(5</var/log/kea-dhcp6.log>, 0, SEEK_END) = 0
0.000103 open("/var/log/kea-ddns.log", O_WRONLY|O_CREAT|O_APPEND,
0666) = 6</var/log/kea-ddns.log>
0.000454 lseek(6</var/log/kea-ddns.log>, 0, SEEK_END) = 53009192
0.000240 rt_sigaction(SIGHUP, {0x7f2ba96b2630, ~[RTMIN RT_1],
SA_RESTORER, 0x7f2ba92799f0}, NULL, 8) = 0
0.000097 rt_sigaction(SIGINT, {0x7f2ba96b2630, ~[RTMIN RT_1],
SA_RESTORER, 0x7f2ba92799f0}, NULL, 8) = 0
0.000064 rt_sigaction(SIGTERM, {0x7f2ba96b2630, ~[RTMIN RT_1],
SA_RESTORER, 0x7f2ba92799f0}, NULL, 8) = 0
0.000076 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_WRLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000108 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:41.872 INFO
[k"..., 121) = 121
0.089260 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_UNLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000348 eventfd2(0, EFD_CLOEXEC|EFD_NONBLOCK) =
7<anon_inode:[eventfd]>
0.000120 epoll_create1(EPOLL_CLOEXEC) = 8<anon_inode:[eventpoll]>
0.000084 timerfd_create(CLOCK_MONOTONIC, TFD_CLOEXEC) =
9<anon_inode:[timerfd]>
0.000122 epoll_ctl(8<anon_inode:[eventpoll]>, EPOLL_CTL_ADD,
7<anon_inode:[eventfd]>, {EPOLLIN|EPOLLERR|EPOLLET, {u32=287018820,
u64=94592646745924}}) = 0
0.000096 write(7<anon_inode:[eventfd]>,
"\x01\x00\x00\x00\x00\x00\x00\x00", 8) = 8
0.000083 epoll_ctl(8<anon_inode:[eventpoll]>, EPOLL_CTL_ADD,
9<anon_inode:[timerfd]>, {EPOLLIN|EPOLLERR, {u32=287018832,
u64=94592646745936}}) = 0
0.000094 socket(PF_INET, SOCK_DGRAM, IPPROTO_UDP) = 10<UDP:[31642]>
0.226548 epoll_ctl(8<anon_inode:[eventpoll]>, EPOLL_CTL_ADD,
10<UDP:[31642]>, {EPOLLIN|EPOLLPRI|EPOLLERR|EPOLLHUP|EPOLLET,
{u32=287086192, u64=94592646813296}}) = 0
0.000527 setsockopt(10<UDP:[31642]>, SOL_SOCKET, SO_REUSEADDR, [1], 4)
= 0
0.000129 bind(10<UDP:[31642]>, {sa_family=AF_INET,
sin_port=htons(53001), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
0.000248 ioctl(10<UDP:[127.0.0.1:53001]>, FIONBIO, [1]) = 0
0.000221 recvmsg(10<UDP:[127.0.0.1:53001]>, 0x7ffd4e9030e0, 0) = -1
EAGAIN (Resource temporarily unavailable)
0.000146 epoll_wait(8<anon_inode:[eventpoll]>, [{EPOLLIN,
{u32=287018820, u64=94592646745924}}], 128, 0) = 1
0.000133 umask(0111) = 022
0.000043 open("/var/run/kea/logger_lockfile", O_RDWR|O_CREAT, 0660) =
11</run/kea/logger_lockfile>
0.000109 umask(022) = 0111
0.000037 fcntl(11</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_WRLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000098 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:42.191
ERROR [k"..., 192) = 192
0.000101 fcntl(11</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000073 umask(0111) = 022
0.000033 open("/var/run/kea/logger_lockfile", O_RDWR|O_CREAT, 0660) =
12</run/kea/logger_lockfile>
0.000143 umask(022) = 0111
0.000040 fcntl(12</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_WRLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000069 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:42.191
ERROR [k"..., 178) = 178
0.000070 fcntl(12</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000064 close(10<UDP:[127.0.0.1:53001]>) = 0
0.000129 epoll_wait(8<anon_inode:[eventpoll]>, [], 128, 0) = 0
0.000060 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_WRLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000077 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:42.192 INFO
[k"..., 158) = 158
0.000080 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_UNLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000098 socket(PF_INET, SOCK_DGRAM, IPPROTO_UDP) = 10<UDP:[31657]>
0.000132 epoll_ctl(8<anon_inode:[eventpoll]>, EPOLL_CTL_ADD,
10<UDP:[31657]>, {EPOLLIN|EPOLLPRI|EPOLLERR|EPOLLHUP|EPOLLET,
{u32=287086192, u64=94592646813296}}) = 0
0.000110 setsockopt(10<UDP:[31657]>, SOL_SOCKET, SO_REUSEADDR, [1], 4)
= 0
0.000089 bind(10<UDP:[31657]>, {sa_family=AF_INET,
sin_port=htons(53001), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
0.000099 ioctl(10<UDP:[127.0.0.1:53001]>, FIONBIO, [1]) = 0
0.000091 recvmsg(10<UDP:[127.0.0.1:53001]>, 0x7ffd4e9030e0, 0) = -1
EAGAIN (Resource temporarily unavailable)
0.000108 epoll_wait(8<anon_inode:[eventpoll]>, [], 128, 0) = 0
0.000073 fcntl(11</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_WRLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000080 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:42.193
ERROR [k"..., 192) = 192
0.000070 fcntl(11</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000072 fcntl(12</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_WRLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000074 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:42.193
ERROR [k"..., 178) = 178
0.000125 fcntl(12</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000061 close(10<UDP:[127.0.0.1:53001]>) = 0
0.000169 epoll_wait(8<anon_inode:[eventpoll]>, [], 128, 0) = 0
0.000064 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_WRLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000069 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:42.193 INFO
[k"..., 158) = 158
0.000082 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_UNLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000085 socket(PF_INET, SOCK_DGRAM, IPPROTO_UDP) = 10<UDP:[31665]>
0.000121 epoll_ctl(8<anon_inode:[eventpoll]>, EPOLL_CTL_ADD,
10<UDP:[31665]>, {EPOLLIN|EPOLLPRI|EPOLLERR|EPOLLHUP|EPOLLET,
{u32=287086192, u64=94592646813296}}) = 0
0.000126 setsockopt(10<UDP:[31665]>, SOL_SOCKET, SO_REUSEADDR, [1], 4)
= 0
0.000106 bind(10<UDP:[31665]>, {sa_family=AF_INET,
sin_port=htons(53001), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
0.000126 ioctl(10<UDP:[127.0.0.1:53001]>, FIONBIO, [1]) = 0
0.000114 recvmsg(10<UDP:[127.0.0.1:53001]>, 0x7ffd4e9030e0, 0) = -1
EAGAIN (Resource temporarily unavailable)
0.000110 epoll_wait(8<anon_inode:[eventpoll]>, [], 128, 0) = 0
0.000088 fcntl(11</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_WRLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000092 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:42.194
ERROR [k"..., 192) = 192
0.000131 fcntl(11</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000079 fcntl(12</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_WRLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000077 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:42.195
ERROR [k"..., 178) = 178
0.000070 fcntl(12</run/kea/logger_lockfile>, F_SETLKW,
{l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000076 close(10<UDP:[127.0.0.1:53001]>) = 0
0.000136 epoll_wait(8<anon_inode:[eventpoll]>, [], 128, 0) = 0
0.000066 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_WRLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000075 write(6</var/log/kea-ddns.log>, "2016-02-04 17:11:42.195 INFO
[k"..., 158) = 158
0.000111 fcntl(3</run/kea/logger_lockfile>, F_SETLKW, {l_type=F_UNLCK,
l_whence=SEEK_SET, l_start=0, l_len=1}) = 0
0.000091 socket(PF_INET, SOCK_DGRAM, IPPROTO_UDP) = 10<UDP:[31673]>
0.000167 epoll_ctl(8<anon_inode:[eventpoll]>, EPOLL_CTL_ADD,
10<UDP:[31673]>, {EPOLLIN|EPOLLPRI|EPOLLERR|EPOLLHUP|EPOLLET,
{u32=287086192, u64=94592646813296}}) = 0
0.000118 setsockopt(10<UDP:[31673]>, SOL_SOCKET, SO_REUSEADDR, [1], 4)
= 0
0.000108 bind(10<UDP:[31673]>, {sa_family=AF_INET,
sin_port=htons(53001), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
0.000108 ioctl(10<UDP:[127.0.0.1:53001]>, FIONBIO, [1]) = 0
0.000134 recvmsg(10<UDP:[127.0.0.1:53001]>, 0x7ffd4e9030e0, 0) = -1
EAGAIN (Resource temporarily unavailable)
On Thu, Feb 4, 2016 at 3:53 PM, Tomek Mrugalski <tomasz at isc.org> wrote:
> On 04/02/16 20:44, Derek Lambert wrote:
> > Reported config installed from Fedora repo:
> >
> > C++ Compiler:
> > CXX: g++
> > CXX_VERSION: g++ (GCC) 5.3.1 20151207 (Red Hat 5.3.1-2)
> >
> > I rebuilt the package disabling optimizations (also had to remove the
> > D_FORTIFY_SOURCE define) and the DDNS server starts up without issue
> > now. Haven't tested functionality but the process is listening on
> > udp/53001 and CPU usage is normal.
> >
> > Thanks for the tip! This will at least allow me to move forward with my
> > testing.
> Thanks for sharing the results. Ok, so it seems this issue occurring
> when Kea is built with gcc 5 and optimizations are enabled. Hopefully
> that piece of information will be useful in debugging.
>
> Tomek
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.isc.org/pipermail/kea-users/attachments/20160204/d7ac79b3/attachment.htm>
More information about the Kea-users
mailing list