On Thu, Oct 24 2019 11:06:04 +0200, Martin Pieuchot wrote:
> Could you repeat the steps to make that issue arise while capturing
> routing socket messages with "# route monitor" on another terminal?
>
> If you do that before and after the commit it might help figuring out
> what's different.
Certainly. Though this is bsd.rd, so there is no another terminal :) I
did the following instead on the same terminal:
# route monitor >/tmp/route-monitor.good &
# dhclient vio0
# ping 1.1.1.1
^C
# ifconfig vio0 -soii
# ping 1.1.1.1
^C
# fg
^C
and similarly for route-monitor.bad. Respectively they are tested on
bsd.rd's built from the following commits:
route-monitor.good, the commit preceding the first bad commit:
9262e070b4250387ec24125a9d5446cebbe12ec8 aka. lib/libcrypto/evp/e_aes.c
r1.38
route-monitor.bad, the first bad commit:
a571f6fc23a13c4832f0066f64a7d855785533f4 aka. sbin/dhclient/dhclient.c
r1.634
both inlined below. I can send them as attachments instead if you
prefer.
$ cat route-monitor.bad
got message of size 168 on Thu Oct 24 12:03:21 2019
RTM_IFINFO: iface status change: len 168, if# 1, name: vio0, link: active, mtu:
1500, flags:<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST>
got message of size 168 on Thu Oct 24 12:03:21 2019
RTM_IFINFO: iface status change: len 168, if# 1, name: vio0, link: active, mtu:
1500, flags:<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST>
got message of size 168 on Thu Oct 24 12:03:21 2019
RTM_IFINFO: iface status change: len 168, if# 1, name: vio0, link: active, mtu:
1500, flags:<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST>
got message of size 96 on Thu Oct 24 12:03:21 2019
RTM_PROPOSAL: config proposal: len 96, source dhcp table 0, ifidx 1, pid:
49913, seq -2116425604, errno 0
flags:<UP,DONE,PROTO3>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
Static Routes:
Domain search:
Domain Name Servers:
got message of size 104 on Thu Oct 24 12:03:21 2019
RTM_NEWADDR: address being added to iface: len 104, metric 0, flags:
sockaddrs: <NETMASK,IFP,IFA,BRD>
255.255.255.255 96:00:00:34:06:8e 95.217.7.62 0.0.0.0
got message of size 192 on Thu Oct 24 12:03:21 2019
RTM_ADD: Add Route: len 192, priority 1, table 0, ifidx 1, pid: 0, seq 0, errno 0
flags:<UP,HOST,DONE,LLINFO,LOCAL>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,IFP,IFA>
95.217.7.62 96:00:00:34:06:8e 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:21 2019
RTM_ADD: Add Route: len 192, priority 4, table 0, ifidx 1, pid: 0, seq 0, errno 0
flags:<UP,DONE,CLONING,CONNECTED>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
95.217.7.62 95.217.7.62 255.255.255.255 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:21 2019
RTM_ADD: Add Route: len 192, priority 8, table 0, ifidx 1, pid: 49799, seq 0,
errno 0
flags:<UP,DONE,CLONING,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
172.31.1.1 95.217.7.62 255.255.255.255 96:00:00:34:06:8e 95.217.7.62
got message of size 144 on Thu Oct 24 12:03:21 2019
RTM_ADD: Add Route: len 144, priority 0, table 0, ifidx 1, pid: 49799, seq 0,
errno 17
flags:<CLONING,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK>
172.31.1.1 95.217.7.62 255.255.255.255
got message of size 192 on Thu Oct 24 12:03:21 2019
RTM_ADD: Add Route: len 192, priority 7, table 0, ifidx 1, pid: 0, seq 0, errno 0
flags:<UP,HOST,DONE,LLINFO,CLONED>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,IFP,IFA>
172.31.1.1 link#1 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:21 2019
RTM_ADD: Add Route: len 192, priority 8, table 0, ifidx 1, pid: 49799, seq 0,
errno 0
flags:<UP,GATEWAY,DONE,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:21 2019
RTM_RESOLVE: Route created by cloning: len 192, priority 7, table 0, ifidx 1,
pid: 0, seq 0, errno 0
flags:<UP,HOST,DONE,LLINFO,CLONED,CACHED>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,IFP,IFA>
172.31.1.1 d2:74:7f:6e:37:e3 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:21 2019
RTM_GET: Report Metrics: len 192, priority 8, table 0, ifidx 1, pid: 49799, seq
-1176973181, errno 0
flags:<UP,GATEWAY,DONE,STATIC>
fmask:
use: 4 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:21 2019
RTM_GET: Report Metrics: len 192, priority 8, table 0, ifidx 1, pid: 49799, seq
-1500177916, errno 0
flags:<UP,GATEWAY,DONE,STATIC>
fmask:
use: 4 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 96:00:00:34:06:8e 95.217.7.62
got message of size 168 on Thu Oct 24 12:03:35 2019
RTM_IFINFO: iface status change: len 168, if# 1, name: vio0, link: active, mtu:
1500, flags:<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST,INET6_NOSOII>
got message of size 192 on Thu Oct 24 12:03:35 2019
RTM_GET: Report Metrics: len 192, priority 8, table 0, ifidx 1, pid: 49799, seq
1811745801, errno 0
flags:<UP,GATEWAY,DONE,STATIC>
fmask:
use: 26 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 96:00:00:34:06:8e 95.217.7.62
got message of size 208 on Thu Oct 24 12:03:35 2019
RTM_DELETE: Delete Route: len 208, priority 7, table 0, ifidx 1, pid: 49799,
seq 0, errno 0
flags:<UP,HOST,DONE,LLINFO,CLONED,CACHED>
fmask:
use: 3 mtu: 0 expire: 1191
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
172.31.1.1 link#1 255.255.255.255 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:35 2019
RTM_DELETE: Delete Route: len 192, priority 7, table 0, ifidx 1, pid: 0, seq 0,
errno 0
flags:<HOST,DONE,CLONED,CACHED>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,IFP,IFA>
172.31.1.1 link#1 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:35 2019
RTM_DELETE: Delete Route: len 192, priority 8, table 0, ifidx 1, pid: 49799,
seq 1, errno 0
flags:<DONE,CLONING,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
172.31.1.1 95.217.7.62 255.255.255.255 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:35 2019
RTM_ADD: Add Route: len 192, priority 8, table 0, ifidx 1, pid: 49799, seq 0,
errno 0
flags:<UP,DONE,CLONING,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
172.31.1.1 95.217.7.62 255.255.255.255 96:00:00:34:06:8e 95.217.7.62
got message of size 144 on Thu Oct 24 12:03:35 2019
RTM_ADD: Add Route: len 144, priority 0, table 0, ifidx 1, pid: 49799, seq 0,
errno 17
flags:<CLONING,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK>
172.31.1.1 95.217.7.62 255.255.255.255
got message of size 192 on Thu Oct 24 12:03:35 2019
RTM_ADD: Add Route: len 192, priority 7, table 0, ifidx 1, pid: 0, seq 0, errno 0
flags:<UP,HOST,DONE,LLINFO,CLONED>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,IFP,IFA>
172.31.1.1 link#1 96:00:00:34:06:8e 95.217.7.62
got message of size 160 on Thu Oct 24 12:03:35 2019
RTM_ADD: Add Route: len 160, priority 0, table 0, ifidx 1, pid: 49799, seq 0,
errno 17
flags:<GATEWAY,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 95.217.7.62
got message of size 192 on Thu Oct 24 12:03:35 2019
RTM_GET: Report Metrics: len 192, priority 8, table 0, ifidx 1, pid: 49799, seq
1025377889, errno 0
flags:<UP,GATEWAY,DONE,STATIC>
fmask:
use: 26 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 96:00:00:34:06:8e 95.217.7.62
$ cat route-monitor.good
got message of size 168 on Thu Oct 24 12:09:06 2019
RTM_IFINFO: iface status change: len 168, if# 1, name: vio0, link: active, mtu:
1500, flags:<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST>
got message of size 168 on Thu Oct 24 12:09:06 2019
RTM_IFINFO: iface status change: len 168, if# 1, name: vio0, link: active, mtu:
1500, flags:<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST>
got message of size 168 on Thu Oct 24 12:09:06 2019
RTM_IFINFO: iface status change: len 168, if# 1, name: vio0, link: active, mtu:
1500, flags:<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST>
got message of size 96 on Thu Oct 24 12:09:06 2019
RTM_PROPOSAL: config proposal: len 96, source dhcp table 0, ifidx 1, pid:
96606, seq 328606457, errno 0
flags:<UP,DONE,PROTO3>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
Static Routes:
Domain search:
Domain Name Servers:
got message of size 104 on Thu Oct 24 12:09:06 2019
RTM_NEWADDR: address being added to iface: len 104, metric 0, flags:
sockaddrs: <NETMASK,IFP,IFA,BRD>
255.255.255.255 96:00:00:34:06:8e 95.217.7.62 0.0.0.0
got message of size 192 on Thu Oct 24 12:09:06 2019
RTM_ADD: Add Route: len 192, priority 1, table 0, ifidx 1, pid: 0, seq 0, errno 0
flags:<UP,HOST,DONE,LLINFO,LOCAL>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,IFP,IFA>
95.217.7.62 96:00:00:34:06:8e 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:09:06 2019
RTM_ADD: Add Route: len 192, priority 4, table 0, ifidx 1, pid: 0, seq 0, errno 0
flags:<UP,DONE,CLONING,CONNECTED>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
95.217.7.62 95.217.7.62 255.255.255.255 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:09:06 2019
RTM_ADD: Add Route: len 192, priority 8, table 0, ifidx 1, pid: 45467, seq 0,
errno 0
flags:<UP,DONE,CLONING,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
172.31.1.1 95.217.7.62 255.255.255.255 96:00:00:34:06:8e 95.217.7.62
got message of size 144 on Thu Oct 24 12:09:06 2019
RTM_ADD: Add Route: len 144, priority 0, table 0, ifidx 1, pid: 45467, seq 0,
errno 17
flags:<CLONING,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK>
172.31.1.1 95.217.7.62 255.255.255.255
got message of size 192 on Thu Oct 24 12:09:06 2019
RTM_ADD: Add Route: len 192, priority 7, table 0, ifidx 1, pid: 0, seq 0, errno 0
flags:<UP,HOST,DONE,LLINFO,CLONED>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,IFP,IFA>
172.31.1.1 link#1 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:09:06 2019
RTM_ADD: Add Route: len 192, priority 8, table 0, ifidx 1, pid: 45467, seq 0,
errno 0
flags:<UP,GATEWAY,DONE,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:09:06 2019
RTM_GET: Report Metrics: len 192, priority 8, table 0, ifidx 1, pid: 45467, seq
-888130080, errno 0
flags:<UP,GATEWAY,DONE,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:09:06 2019
RTM_GET: Report Metrics: len 192, priority 8, table 0, ifidx 1, pid: 45467, seq
-1967703289, errno 0
flags:<UP,GATEWAY,DONE,STATIC>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 96:00:00:34:06:8e 95.217.7.62
got message of size 192 on Thu Oct 24 12:09:07 2019
RTM_RESOLVE: Route created by cloning: len 192, priority 7, table 0, ifidx 1,
pid: 0, seq 0, errno 0
flags:<UP,HOST,DONE,LLINFO,CLONED,CACHED>
fmask:
use: 0 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,IFP,IFA>
172.31.1.1 d2:74:7f:6e:37:e3 96:00:00:34:06:8e 95.217.7.62
got message of size 168 on Thu Oct 24 12:09:17 2019
RTM_IFINFO: iface status change: len 168, if# 1, name: vio0, link: active, mtu:
1500, flags:<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST,INET6_NOSOII>
got message of size 192 on Thu Oct 24 12:09:17 2019
RTM_GET: Report Metrics: len 192, priority 8, table 0, ifidx 1, pid: 45467, seq
1695513400, errno 0
flags:<UP,GATEWAY,DONE,STATIC>
fmask:
use: 18 mtu: 0 expire: 0
locks: inits:
sockaddrs: <DST,GATEWAY,NETMASK,IFP,IFA>
0.0.0.0 172.31.1.1 0.0.0.0 96:00:00:34:06:8e 95.217.7.62
--
Lauri Tirkkonen | lotheac @ IRCnet
