Foreman » Smart Proxy

Requesting a free IP fails with:

Failed to fetch a free IP from proxy t-infra-dc-013.dev.local (http://10.3.28.13:8000): ERF12-8202 [ProxyAPI::ProxyException]: Unable to retrieve unused IP ([RestClient::Exceptions::ReadTimeout]: Timed out reading data from server) for proxy http://10.3.28.13:8000/dhcp

In the logs we can see the retrieval is really slow because of pingable IPs:

D, [2018-09-04T21:40:03.318863 1aa3c42b] DEBUG -- : Searching for free IP - pinging 10.3.28.232.
D, [2018-09-04T21:40:04.316198 1aa3c42b] DEBUG -- : Found a pingable IP(10.3.28.232) address which does not have a Proxy::DHCP record.
D, [2018-09-04T21:40:04.317179 1aa3c42b] DEBUG -- : Searching for free IP - pinging 10.3.28.182.
D, [2018-09-04T21:40:07.160048 1aa3c42b] DEBUG -- : Found free IP 10.3.28.242 out of a total of 119 free IPs.
D, [2018-09-04T21:40:25.344844 1aa3c42b] DEBUG -- : Found a pingable IP(10.3.28.182) address which does not have a Proxy::DHCP record.
D, [2018-09-04T21:40:25.344844 1aa3c42b] DEBUG -- : Searching for free IP - pinging 10.3.28.154.
D, [2018-09-04T21:40:46.375760 1aa3c42b] DEBUG -- : Found a pingable IP(10.3.28.154) address which does not have a Proxy::DHCP record.
D, [2018-09-04T21:40:46.376759 1aa3c42b] DEBUG -- : Searching for free IP - pinging 10.3.28.156.
D, [2018-09-04T21:40:47.374746 1aa3c42b] DEBUG -- : Found a pingable IP(10.3.28.156) address which does not have a Proxy::DHCP record.
D, [2018-09-04T21:40:47.375742 1aa3c42b] DEBUG -- : Searching for free IP - pinging 10.3.28.152.
D, [2018-09-04T21:40:48.412564 1aa3c42b] DEBUG -- : Found a pingable IP(10.3.28.152) address which does not have a Proxy::DHCP record.
D, [2018-09-04T21:40:48.412564 1aa3c42b] DEBUG -- : Searching for free IP - pinging 10.3.28.217.
D, [2018-09-04T21:41:10.158492 1aa3c42b] DEBUG -- : Found free IP 10.3.28.217 out of a total of 119 free IPs.

It takes 1 minute 07 seconds with 5 pingable IPs.

In the previous implementation (PR#546), it took about 1 second with 15 pingable IPs:

D, [2017-10-09T12:55:35.391212 ] DEBUG -- : Searching for free IP in subnet 10.3.28.0
D, [2017-10-09T12:55:35.438011 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.117
D, [2017-10-09T12:55:35.469210 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.118
D, [2017-10-09T12:55:35.500412 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.119
D, [2017-10-09T12:55:35.516017 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.120
D, [2017-10-09T12:55:35.562813 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.121
D, [2017-10-09T12:55:35.578416 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.122
D, [2017-10-09T12:55:35.609617 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.123
D, [2017-10-09T12:55:35.640813 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.124
D, [2017-10-09T12:55:35.672014 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.125
D, [2017-10-09T12:55:35.687616 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.126
D, [2017-10-09T12:55:35.718814 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.127
D, [2017-10-09T12:55:35.734413 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.128
D, [2017-10-09T12:55:35.734413 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.129
D, [2017-10-09T12:55:35.765619 ] DEBUG -- : Found a pingable IP address which does not have a DHCP record: 10.3.28.130
I, [2017-10-09T12:55:36.795268 ]  INFO -- : Found free IP 10.3.28.131