dispatcher script ran before DHCP response

[Loïc Yhuel <loic yhuel gmail com>]

Hi,

I'm trying to create a NetworkManager dispatcher script to change
proxy settings depending on the current network.

Usually, I get the "up" event after the DHCP configuration, so I can
determine the network from DHCP4_NETWORK_NUMBER for example.
But when the DHCP server is slow to respond, the script is called with
only the IPv6 (link-local) configured, and I don't get a
"dhcp4-change" event later.

Is it a bug, or do I need to do something else to be notified after
the DHCP response ?

Version : NetworkManager-0.9.8.2-8.git20130709.fc19.x86_64

In the following logs, you can see my "proxydispatcher" script being
only called once, before the DHCP response.

NetworkManager[436]: <info> Auto-activating connection 'Ethernet'.
NetworkManager[436]: <info> Activation (p3p1) starting connection 'Ethernet'
NetworkManager[436]: <info> (p3p1): device state change: disconnected
-> prepare (reason 'none') [30 40 0]
NetworkManager[436]: <info> Activation (p3p1) Stage 1 of 5 (Device
Prepare) scheduled...
NetworkManager[436]: <info> Activation (p3p1) Stage 1 of 5 (Device
Prepare) started...
NetworkManager[436]: <info> Activation (p3p1) Stage 2 of 5 (Device
Configure) scheduled...
NetworkManager[436]: <info> Activation (p3p1) Stage 1 of 5 (Device
Prepare) complete.
NetworkManager[436]: <info> Activation (p3p1) Stage 2 of 5 (Device
Configure) starting...
NetworkManager[436]: <info> (p3p1): device state change: prepare ->
config (reason 'none') [40 50 0]
NetworkManager[436]: <info> Activation (p3p1) Stage 2 of 5 (Device
Configure) successful.
NetworkManager[436]: <info> Activation (p3p1) Stage 3 of 5 (IP
Configure Start) scheduled.
NetworkManager[436]: <info> Activation (p3p1) Stage 2 of 5 (Device
Configure) complete.
NetworkManager[436]: <info> Activation (p3p1) Stage 3 of 5 (IP
Configure Start) started...
NetworkManager[436]: <info> (p3p1): device state change: config ->
ip-config (reason 'none') [50 70 0]
NetworkManager[436]: <info> Activation (p3p1) Beginning DHCPv4
transaction (timeout in 45 seconds)
NetworkManager[436]: <info> dhclient started with pid 28299
NetworkManager[436]: <info> Activation (p3p1) Beginning IP6 addrconf.
NetworkManager[436]: <info> Activation (p3p1) Stage 3 of 5 (IP
Configure Start) complete.
NetworkManager[436]: <info> Activation (p3p1) Stage 5 of 5 (IPv6
Commit) scheduled...
NetworkManager[436]: <info> Activation (p3p1) Stage 5 of 5 (IPv6
Commit) started...
dhclient[28299]: Internet Systems Consortium DHCP Client 4.2.5
dhclient[28299]: Copyright 2004-2013 Internet Systems Consortium.
dhclient[28299]: All rights reserved.
dhclient[28299]: For info, please visit https://www.isc.org/software/dhcp/
dhclient[28299]:
dhclient[28299]: Listening on LPF/p3p1/b8:88:e3:3c:9f:aa
dhclient[28299]: Sending on   LPF/p3p1/b8:88:e3:3c:9f:aa
dhclient[28299]: Sending on   Socket/fallback
dhclient[28299]: DHCPREQUEST on p3p1 to 255.255.255.255 port 67 (xid=0x74f6969e)
NetworkManager[436]: <info> (p3p1): device state change: ip-config ->
secondaries (reason 'none') [70 90 0]
NetworkManager[436]: <info> Activation (p3p1) Stage 5 of 5 (IPv6
Commit) complete.
NetworkManager[436]: <info> (p3p1): DHCPv4 state changed nbi -> preinit
NetworkManager[436]: <info> (p3p1): device state change: secondaries
-> activated (reason 'none') [90 100 0]
NetworkManager[436]: <info> Activation (p3p1) successful, device activated.
proxydispatcher[28320]: script called: p3p1 up
dhclient[28299]: DHCPREQUEST on p3p1 to 255.255.255.255 port 67 (xid=0x74f6969e)
dhclient[28299]: DHCPDISCOVER on p3p1 to 255.255.255.255 port 67
interval 5 (xid=0x5157f204)
dhclient[28299]: DHCPREQUEST on p3p1 to 255.255.255.255 port 67 (xid=0x5157f204)
...
dhclient[28299]: DHCPOFFER from 192.168.0.254
dhclient[28299]: DHCPACK from 192.168.0.254 (xid=0x5157f204)
dhclient[28299]: bound to 192.168.0.12 -- renewal in 381622 seconds.
NetworkManager[436]: <info> (p3p1): DHCPv4 state changed preinit -> bound
NetworkManager[436]: <info>   address 192.168.0.12
NetworkManager[436]: <info>   prefix 24 (255.255.255.0)
NetworkManager[436]: <info>   gateway 192.168.0.254
NetworkManager[436]: <info>   nameserver '212.27.40.240'
NetworkManager[436]: <info>   nameserver '212.27.40.241'
NetworkManager[436]: <info> Activation (p3p1) Stage 5 of 5 (IPv4
Configure Commit) scheduled...
NetworkManager[436]: <info> Activation (p3p1) Stage 5 of 5 (IPv4
Commit) started...
NetworkManager[436]: <info> Policy set 'Ethernet' (p3p1) as default
for IPv4 routing and DNS.
NetworkManager[436]: <info> Activation (p3p1) Stage 5 of 5 (IPv4
Commit) complete.