1.280 dhcp bug

[Bill Moss <bmoss CLEMSON EDU>]

Here is a description of the bug which is revealed in the syslog output 
below.

Boot, NM makes a wireless connection at 15:59:09 with lease time of a 
half hour and gets ip address 130.127.36.205

Wire inserted at 16:01:32, NM makes a wired connection at 16:01:39 and 
gets ip address 130.127.112.189

At 16:14:09 NM makes a DHCP request for 130.127.136.205. NM is trying to 
renew the wireless address at the T1 time of 15 minutes. Trouble is, the 
wired interface is currently active. The wireless interface no longer 
has this address according to ifconfig. NM should not be trying to renew 
the wireless ip address.

Feb 15 15:59:09 localhost NetworkManager: Broadcasting DHCP_REQUEST for 
130.127.136.205
Feb 15 15:59:09 localhost NetworkManager: DHCP: Starting request loop, 
overall start_time = {1108501149s, 294234us}
Feb 15 15:59:09 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...
Feb 15 15:59:09 localhost NetworkManager: DHCP: Got some data of length 328.
Feb 15 15:59:09 localhost NetworkManager: Server replied with 10 DHCP 
options:
Feb 15 15:59:09 localhost NetworkManager:       Subnet Mask (1):        
255.255.255.0
Feb 15 15:59:09 localhost NetworkManager:       Router (3):     
130.127.136.1
Feb 15 15:59:09 localhost NetworkManager:       Domain Server (6):      
130.127.8.8
Feb 15 15:59:09 localhost NetworkManager:       Domain Server (6):      
130.127.28.14
Feb 15 15:59:09 localhost NetworkManager:       Domain Name (15):       
"clemson.edu"
Feb 15 15:59:09 localhost NetworkManager:       Broadcast Address (28): 
130.127.136.255
Feb 15 15:59:09 localhost NetworkManager:       Address Time (51):      1800
Feb 15 15:59:09 localhost NetworkManager:       DHCP Msg Type (53):     5
Feb 15 15:59:09 localhost NetworkManager:       DHCP Server Id (54):    
192.168.8.15
Feb 15 15:59:09 localhost NetworkManager:       Renewal Time (58):      0
Feb 15 15:59:09 localhost NetworkManager:       Rebinding Time (59):    0
Feb 15 15:59:09 localhost NetworkManager:       Your IP Address:        
130.127.136.205
Feb 15 15:59:09 localhost NetworkManager:       DHCP Server Address:    
192.168.8.15 (HW=00:D0:00:8C:9B:FC)
Feb 15 15:59:09 localhost NetworkManager:       Gateway Address:        
130.127.132.1
Feb 15 15:59:09 localhost NetworkManager: DHCP_ACK received from  
(192.168.8.15)
Feb 15 15:59:09 localhost NetworkManager: Your IP address = 130.127.136.205
Feb 15 15:59:09 localhost NetworkManager: : Adding nameserver: 130.127.8.8
Feb 15 15:59:09 localhost NetworkManager: : Adding nameserver: 130.127.28.14
Feb 15 15:59:09 localhost NetworkManager: : Adding domain search: 
clemson.edu
Feb 15 15:59:09 localhost NetworkManager: Activation (eth1) successful, 
device activated.
Feb 15 15:59:46 localhost su(pam_unix)[4582]: session opened for user 
root by bmoss(uid=500)
Feb 15 16:01:01 localhost crond(pam_unix)[4633]: session opened for user 
root by (uid=0)
Feb 15 16:01:01 localhost crond(pam_unix)[4633]: session closed for user 
root
Feb 15 16:01:32 localhost kernel: e1000: eth0: e1000_watchdog: NIC Link 
is Up 100 Mbps Full Duplex
Feb 15 16:01:32 localhost NetworkManager:     SWITCH: best device changed
Feb 15 16:01:32 localhost NetworkManager: Activation (eth0) started...
Feb 15 16:01:32 localhost NetworkManager: dhcp_interface_init: MAC 
address = 00:0d:60:b2:19:9d
Feb 15 16:01:32 localhost NetworkManager: Broadcasting DHCP_DISCOVER
Feb 15 16:01:32 localhost NetworkManager: DHCP: Starting request loop, 
overall start_time = {1108501292s, 727724us}
Feb 15 16:01:32 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...
Feb 15 16:01:35 localhost NetworkManager: DHCP: Got some data of length 229.
Feb 15 16:01:35 localhost NetworkManager: DHCP: Reply message's source 
port (138) was not the DHCP server port number (67), won't use it.
Feb 15 16:01:38 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...
Feb 15 16:01:38 localhost NetworkManager: DHCP: Got some data of length 48.
Feb 15 16:01:38 localhost NetworkManager: DHCP: Reply message was not 
UDP (ip_hdr->protocol = 1, IPPROTO_UDP = 17), won't use it.
Feb 15 16:01:39 localhost NetworkManager: DHCP: Got some data of length 328.
Feb 15 16:01:39 localhost NetworkManager: Server replied with 7 DHCP 
options:
Feb 15 16:01:39 localhost NetworkManager:       Subnet Mask (1):        
255.255.255.0
Feb 15 16:01:39 localhost NetworkManager:       Router (3):     
130.127.112.2
Feb 15 16:01:39 localhost NetworkManager:       Domain Server (6):      
130.127.8.8
Feb 15 16:01:39 localhost NetworkManager:       Domain Server (6):      
130.127.200.15
Feb 15 16:01:39 localhost NetworkManager:       Domain Name (15):       
"ces.clemson.edu"
Feb 15 16:01:39 localhost NetworkManager:       Address Time (51):      
14400
Feb 15 16:01:39 localhost NetworkManager:       DHCP Msg Type (53):     2
Feb 15 16:01:39 localhost NetworkManager:       DHCP Server Id (54):    
192.168.8.15
Feb 15 16:01:39 localhost NetworkManager:       Your IP Address:        
130.127.112.189
Feb 15 16:01:39 localhost NetworkManager:       DHCP Server Address:    
192.168.8.15 (HW=00:05:01:A8:23:FC)
Feb 15 16:01:39 localhost NetworkManager:       Gateway Address:        
130.127.112.2
Feb 15 16:01:39 localhost NetworkManager: DHCP_OFFER received from  
(192.168.8.15)
Feb 15 16:01:39 localhost NetworkManager: Broadcasting DHCP_REQUEST for 
130.127.112.189
Feb 15 16:01:39 localhost NetworkManager: DHCP: Starting request loop, 
overall start_time = {1108501299s, 367234us}
Feb 15 16:01:39 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...
Feb 15 16:01:39 localhost NetworkManager: DHCP: Got some data of length 328.
Feb 15 16:01:39 localhost NetworkManager: Server replied with 10 DHCP 
options:
Feb 15 16:01:39 localhost NetworkManager:       Subnet Mask (1):        
255.255.255.0
Feb 15 16:01:39 localhost NetworkManager:       Router (3):     
130.127.112.2
Feb 15 16:01:39 localhost NetworkManager:       Domain Server (6):      
130.127.8.8
Feb 15 16:01:39 localhost NetworkManager:       Domain Server (6):      
130.127.200.15
Feb 15 16:01:39 localhost NetworkManager:       Domain Name (15):       
"ces.clemson.edu"
Feb 15 16:01:39 localhost NetworkManager:       Broadcast Address (28): 
130.127.112.255
Feb 15 16:01:39 localhost NetworkManager:       Address Time (51):      
14400
Feb 15 16:01:39 localhost NetworkManager:       DHCP Msg Type (53):     5
Feb 15 16:01:39 localhost NetworkManager:       DHCP Server Id (54):    
192.168.8.15
Feb 15 16:01:39 localhost NetworkManager:       Renewal Time (58):      0
Feb 15 16:01:39 localhost NetworkManager:       Rebinding Time (59):    0
Feb 15 16:01:39 localhost NetworkManager:       Your IP Address:        
130.127.112.189
Feb 15 16:01:39 localhost NetworkManager:       DHCP Server Address:    
192.168.8.15 (HW=00:05:01:A8:23:FC)
Feb 15 16:01:39 localhost NetworkManager:       Gateway Address:        
130.127.112.2
Feb 15 16:01:39 localhost NetworkManager: DHCP_ACK received from  
(192.168.8.15)
Feb 15 16:01:39 localhost NetworkManager: Your IP address = 130.127.112.189
Feb 15 16:01:39 localhost NetworkManager: : Adding nameserver: 130.127.8.8
Feb 15 16:01:39 localhost NetworkManager: : Adding nameserver: 
130.127.200.15
Feb 15 16:01:39 localhost NetworkManager: : Adding domain search: 
ces.clemson.edu
Feb 15 16:01:39 localhost NetworkManager: Activation (eth0) successful, 
device activated.
Feb 15 16:14:09 localhost NetworkManager: Sending DHCP_REQUEST for 
130.127.136.205 to 192.168.8.15
Feb 15 16:14:09 localhost NetworkManager: DHCP: Starting request loop, 
overall start_time = {1108502049s, 452620us}
Feb 15 16:14:09 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...
Feb 15 16:14:13 localhost NetworkManager: DHCP: Got some data of length 328.
Feb 15 16:14:13 localhost NetworkManager: DHCP: Reply message's source 
port (68) was not the DHCP server port number (67), won't use it.
Feb 15 16:14:14 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...
Feb 15 16:14:21 localhost NetworkManager: DHCP: Got some data of length 78.
Feb 15 16:14:21 localhost NetworkManager: DHCP: Reply message's source 
port (137) was not the DHCP server port number (67), won't use it.
Feb 15 16:14:21 localhost NetworkManager: DHCP: Got some data of length 78.
Feb 15 16:14:21 localhost NetworkManager: DHCP: Reply message's source 
port (137) was not the DHCP server port number (67), won't use it.
Feb 15 16:14:22 localhost NetworkManager: DHCP: Got some data of length 78.
Feb 15 16:14:22 localhost NetworkManager: DHCP: Reply message's source 
port (137) was not the DHCP server port number (67), won't use it.
Feb 15 16:14:25 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...
Feb 15 16:25:24 localhost NetworkManager: Broadcasting DHCP_REQUEST for 
130.127.136.205
Feb 15 16:25:24 localhost NetworkManager: DHCP: Starting request loop, 
overall start_time = {1108502724s, 453924us}
Feb 15 16:25:24 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...
Feb 15 16:25:30 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...
Feb 15 16:25:41 localhost NetworkManager: DHCP: Got some data of length 46.
Feb 15 16:25:41 localhost NetworkManager: DHCP: Reply message was not 
UDP (ip_hdr->protocol = 2, IPPROTO_UDP = 17), won't use it.
Feb 15 16:25:41 localhost NetworkManager: DHCP: Request sent, waiting 
for reply...

--
Bill Moss
Professor, Mathematical Sciences
Clemson University