Hi All,

As [Renewing] begins and during the renewing cycle (as I view configuration with dhcpleasectl -l fxp0) about halfway through the ISP'one hour dhcp lease, the external interface seems to become "stalled".

"Stalled" is a term that describes the experience of using a host on the lan, as one's video is fine, then stops, then starts up again after some period of time.

This on a dual homed i386 GENERIC installation planned to be a router and run unbound and dhcp for the lan. Subject to my understanding, I'm informed by https://www.mail-archive.com/misc@openbsd.org/msg180064.html and I imagine there is some interplay with the dhcp rebinding/renewing timer(s) and name resolution.

hostname.fxp0 has:
lladdr [fxp0 mac address]
inet autoconf
up

during the "stall":
from a local host I can ping the "stalled" interface
from the stalled interface I can ping the adjacent fxp1 interface (and vice versa)
from the stalled interface I can ping a local lan host
from the stalled interface I cannot ping the dhcp server
ping -I 64.203.147.252 64.4.147.142 (dhcp server) and ping says "No route to host"

Another dhcp server: 64.4.117.66 has provided an address and all is good until the next Renewing cycle.

For completeness I'll mention that during the "stall" or Renewing time, when the client does the unicast request, ISP dhcp server is the fourth hop out from fxp0.

Please find below logs of tcpdump on port 67 and 68  along with a record of dhcpleased's actions.

Again, all is subject to my understanding of things, and if further direction is needed, I'm willing.

Thank you very much,
Pete

tcpdump on ports 67 and 67 (overnight):

21:37:37.794212 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0xbf9abf5 [|bootp] [tos 0x10] 21:37:37.820800 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0xbf9abf5 Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 22:07:37.921663 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.142.67:  xid:0xe8045d16 [|bootp] 22:18:53.031792 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.142.67:  xid:0x7565c229 [|bootp] 22:30:42.560940 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0xb5045322 [|bootp] [tos 0x10] 22:30:42.585848 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0xb5045322 Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 22:30:42.588890 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.142.67:  xid:0x94f64521 [|bootp] 23:20:23.003559 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0xd82511a3 [|bootp] [tos 0x10] 23:20:23.028535 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0xd82511a3 Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 23:50:23.130648 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.142.67:  xid:0xb72f620e [|bootp] 00:13:27.769937 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0xaf233fee [|bootp] [tos 0x10] 00:13:27.794085 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0xaf233fee Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 00:43:27.898039 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.66.67:  xid:0x5218fa3b [|bootp] 01:06:32.537328 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0x4eb78fc9 [|bootp] [tos 0x10] 01:06:32.562361 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0x4eb78fc9 Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 01:36:32.664554 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.66.67:  xid:0xbd0c7e36 [|bootp] 01:59:37.303896 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0x5ad6e610 [|bootp] [tos 0x10] 01:59:37.328723 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0x5ad6e610 Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 02:29:37.431019 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.142.67:  xid:0x880c6233 [|bootp] 02:51:41.962153 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0xe92a28b6 [|bootp] [tos 0x10] 02:51:41.986640 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0xe92a28b6 Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 03:21:42.089271 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.66.67:  xid:0xa0ed2e00 [|bootp] 03:44:46.728577 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0xba32aa0b [|bootp] [tos 0x10] 03:44:46.753055 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0xba32aa0b Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 03:44:46.756051 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.66.67:  xid:0xc1773ed5 [|bootp] 04:14:46.855843 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0x6e65631b [|bootp] [tos 0x10] 04:14:46.882266 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0x6e65631b Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 04:44:46.982930 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.66.67:  xid:0x6adc69a0 [|bootp] 05:04:27.298275 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0x43534f7d [|bootp] [tos 0x10] 05:04:27.324149 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0x43534f7d Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 05:34:27.425401 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.142.67:  xid:0x72a3ccf3 [|bootp] 05:57:32.064718 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0xfa325b3f [|bootp] [tos 0x10] 05:57:32.090314 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0xfa325b3f Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 06:27:32.191893 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.142.67:  xid:0x2cd9fbea [|bootp] 06:50:36.831239 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0x5f9b590e [|bootp] [tos 0x10] 06:50:36.854166 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0x5f9b590e Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 07:20:36.960157 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.142.67:  xid:0x3d0164b1 [|bootp] 07:41:41.383057 00:01:29:17:24:01 ff:ff:ff:ff:ff:ff 0800 342: 0.0.0.0.68 > 255.255.255.255.67:  xid:0x8ea64ae8 [|bootp] [tos 0x10] 07:41:41.406379 a4:7b:2c:3d:03:74 00:01:29:17:24:01 0800 590: 64.203.147.2.67 > 64.203.147.251.68:  xid:0x8ea64ae8 Y:64.203.147.251 G:10.245.0.3 [|bootp] [tos 0xc0] 08:11:41.510143 00:01:29:17:24:01 00:00:5e:00:01:09 0800 342: 64.203.147.251.68 > 64.4.117.142.67:  xid:0x5582143c [|bootp]

dhcpleasd, through a few cycles:

state_transition[fxp0] Down -> Rebooting, timo: 1
DHCPREQUEST on fxp0
iface_timeout[1]: Rebooting
state_transition[fxp0] Rebooting -> Rebooting, timo: 2
DHCPREQUEST on fxp0
parse_dhcp, from: a4:7b:2c:3d:03:74, to: 00:01:29:17:24:01
parse_dhcp: 64.203.147.2:67 -> 64.203.147.251:68
dhcp_hdr op: Boot Reply (2)
dhcp_hdr htype: Ethernet (1)
dhcp_hdr hlen: 6
dhcp_hdr hops: 0
dhcp_hdr xid: 0x12406ca3
dhcp_hdr secs: 0
dhcp_hdr flags: 0x0
dhcp_hdr ciaddr: 0.0.0.0
dhcp_hdr yiaddr: 64.203.147.251
dhcp_hdr siaddr: 0.0.0.0
dhcp_hdr giaddr: 10.245.0.3
dhcp_hdr chaddr: 00:01:29:17:24:01 (00000000000000000000)
DHO_SUBNET_MASK: 255.255.255.0
DHO_ROUTER: 64.203.147.1
DHO_DOMAIN_NAME_SERVERS: 216.12.78.10 (1/2)
DHO_DOMAIN_NAME_SERVERS: 216.12.78.20 (2/2)
DHO_DOMAIN_NAME: lumos.net
DHO_28, len: 4
DHO_DHCP_LEASE_TIME 3600s
DHO_DHCP_MESSAGE_TYPE: DHCPACK
DHO_DHCP_SERVER_IDENTIFIER: 64.4.117.142
DHO_END
DHCPACK on fxp0 from a4:7b:2c:3d:03:74/64.203.147.2 to 00:01:29:17:24:01/64.203.147.251
adding 64.203.147.251 to fxp0 (lease from 64.4.117.142)
adding nameservers 216.12.78.10 216.12.78.20 (lease from 64.4.117.142 on fxp0)
state_transition[fxp0] Rebooting -> Bound, timo: 1800
configure_interface fxp0
iface_timeout[1]: Bound
state_transition[fxp0] Bound -> Renewing, timo: 675
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 2475, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 337
DHCPREQUEST on fxp0
sendto: No route to host
parse_dhcp, from: a4:7b:2c:3d:03:74, to: 00:01:29:17:24:01
parse_dhcp: 64.203.147.2:67 -> 64.203.147.251:68
dhcp_hdr op: Boot Reply (2)
dhcp_hdr htype: Ethernet (1)
dhcp_hdr hlen: 6
dhcp_hdr hops: 0
dhcp_hdr xid: 0xa8c29909
dhcp_hdr secs: 0
dhcp_hdr flags: 0x0
dhcp_hdr ciaddr: 0.0.0.0
dhcp_hdr yiaddr: 64.203.147.251
dhcp_hdr siaddr: 0.0.0.0
dhcp_hdr giaddr: 10.245.0.3
dhcp_hdr chaddr: 00:01:29:17:24:01 (00000000000000000000)
DHO_SUBNET_MASK: 255.255.255.0
DHO_ROUTER: 64.203.147.1
DHO_DOMAIN_NAME_SERVERS: 216.12.78.10 (1/2)
DHO_DOMAIN_NAME_SERVERS: 216.12.78.20 (2/2)
DHO_DOMAIN_NAME: lumos.net
DHO_28, len: 4
DHO_DHCP_LEASE_TIME 3600s
DHO_DHCP_MESSAGE_TYPE: DHCPACK
DHO_DHCP_SERVER_IDENTIFIER: 64.4.117.142
DHO_END
DHCPACK on fxp0 from a4:7b:2c:3d:03:74/64.203.147.2 to 00:01:29:17:24:01/64.203.147.251
state_transition[fxp0] Renewing -> Bound, timo: 1800
iface_timeout[1]: Bound
state_transition[fxp0] Bound -> Renewing, timo: 675
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 2475, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 337
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 2812, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 168
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 2980, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 84
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 3064, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 60
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 3124, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 60
DHCPREQUEST on fxp0
sendto: No route to host
parse_dhcp, from: a4:7b:2c:3d:03:74, to: 00:01:29:17:24:01
parse_dhcp: 64.203.147.2:67 -> 64.203.147.251:68
dhcp_hdr op: Boot Reply (2)
dhcp_hdr htype: Ethernet (1)
dhcp_hdr hlen: 6
dhcp_hdr hops: 0
dhcp_hdr xid: 0x60a83005
dhcp_hdr secs: 0
dhcp_hdr flags: 0x0
dhcp_hdr ciaddr: 0.0.0.0
dhcp_hdr yiaddr: 64.203.147.251
dhcp_hdr siaddr: 0.0.0.0
dhcp_hdr giaddr: 10.245.0.3
dhcp_hdr chaddr: 00:01:29:17:24:01 (00000000000000000000)
DHO_SUBNET_MASK: 255.255.255.0
DHO_ROUTER: 64.203.147.1
DHO_DOMAIN_NAME_SERVERS: 216.12.78.10 (1/2)
DHO_DOMAIN_NAME_SERVERS: 216.12.78.20 (2/2)
DHO_DOMAIN_NAME: lumos.net
DHO_28, len: 4
DHO_DHCP_LEASE_TIME 3600s
DHO_DHCP_MESSAGE_TYPE: DHCPACK
DHO_DHCP_SERVER_IDENTIFIER: 64.4.117.66
DHO_END
DHCPACK on fxp0 from a4:7b:2c:3d:03:74/64.203.147.2 to 00:01:29:17:24:01/64.203.147.251
state_transition[fxp0] Renewing -> Bound, timo: 1800
iface_timeout[1]: Bound
state_transition[fxp0] Bound -> Renewing, timo: 675
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 2475, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 337
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 2812, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 168
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 2980, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 84
DHCPREQUEST on fxp0
iface_timeout[1]: Renewing
iface_timeout: res.tv_sec: 3064, rebinding_time: 3150
state_transition[fxp0] Renewing -> Renewing, timo: 60
DHCPREQUEST on fxp0
sendto: No route to host
parse_dhcp, from: a4:7b:2c:3d:03:74, to: 00:01:29:17:24:01
parse_dhcp: 64.203.147.2:67 -> 64.203.147.251:68
dhcp_hdr op: Boot Reply (2)
dhcp_hdr htype: Ethernet (1)
dhcp_hdr hlen: 6
dhcp_hdr hops: 0
dhcp_hdr xid: 0x5b1985d4
dhcp_hdr secs: 0
dhcp_hdr flags: 0x0
dhcp_hdr ciaddr: 0.0.0.0
dhcp_hdr yiaddr: 64.203.147.251
dhcp_hdr siaddr: 0.0.0.0
dhcp_hdr giaddr: 10.245.0.3
dhcp_hdr chaddr: 00:01:29:17:24:01 (00000000000000000000)
DHO_SUBNET_MASK: 255.255.255.0
DHO_ROUTER: 64.203.147.1
DHO_DOMAIN_NAME_SERVERS: 216.12.78.10 (1/2)
DHO_DOMAIN_NAME_SERVERS: 216.12.78.20 (2/2)
DHO_DOMAIN_NAME: lumos.net
DHO_28, len: 4
DHO_DHCP_LEASE_TIME 3600s
DHO_DHCP_MESSAGE_TYPE: DHCPACK
DHO_DHCP_SERVER_IDENTIFIER: 64.4.117.66
DHO_END
DHCPACK on fxp0 from a4:7b:2c:3d:03:74/64.203.147.2 to 00:01:29:17:24:01/64.203.147.251
state_transition[fxp0] Renewing -> Bound, timo: 1800
waiting for children to terminate
frontend exiting
engine exiting
terminating





Reply via email to