> On Oct 24, 2019, at 8:25 AM, Lauri Tirkkonen <[email protected]> wrote:
>
> 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
Can you add a "ifconfig vio0" at this point,
> ^C
> # ifconfig vio0 -soii
> # ping 1.1.1.1
and here.
I am puzzled that the AUTOCONF4 flag appears to be missing from IFINFO messages
after dhclient has configured the interface. Just doing -soii doesn't remove
the flag here.
The fact that AUTOCONF4 has been 'lost' triggers getting a new IPv4 lease. No
idea why this would cause the problem being reported.
....Ken
> ^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
>
