after core update 136, dhcpcd stops running on red0

General questions.
cfusco
Posts: 184
Joined: March 23rd, 2015, 4:19 pm

after core update 136, dhcpcd stops running on red0

Post by cfusco » October 12th, 2019, 8:35 pm

Since I updated to core 136 in apparently a random way, the dhcpd stops running on the red interface. It has happened so far about one hour after the update, then again about one hour after I restarted the machine and again during the night. Now it is the second night.
This is my normal ifconfig output:

Code: Select all

[root@ipfire ~]# ifconfig
blue0     Link encap:Ethernet  HWaddr 04:F0:21:27:1D:44  
          inet addr:10.1.3.1  Bcast:10.1.3.255  Mask:255.255.255.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:304 errors:0 dropped:0 overruns:0 frame:0
          TX packets:249 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:52344 (51.1 Kb)  TX bytes:79718 (77.8 Kb)

green0    Link encap:Ethernet  HWaddr 00:0D:B9:42:68:90  
          inet addr:10.1.1.1  Bcast:10.1.1.255  Mask:255.255.255.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:6321 errors:0 dropped:3 overruns:0 frame:0
          TX packets:6255 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:1229239 (1.1 Mb)  TX bytes:2705676 (2.5 Mb)
          Memory:f7900000-f791ffff 

lo        Link encap:Local Loopback  
          inet addr:127.0.0.1  Mask:255.0.0.0
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:2218 errors:0 dropped:0 overruns:0 frame:0
          TX packets:2218 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:138648 (135.3 Kb)  TX bytes:138648 (135.3 Kb)

orange0   Link encap:Ethernet  HWaddr 00:0D:B9:42:68:91  
          inet addr:10.1.2.1  Bcast:10.1.2.255  Mask:255.255.255.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:13188 errors:0 dropped:0 overruns:0 frame:0
          TX packets:35180 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:4537675 (4.3 Mb)  TX bytes:42996158 (41.0 Mb)
          Memory:f7a00000-f7a1ffff 

red0      Link encap:Ethernet  HWaddr 00:0D:B9:42:68:92  
          inet addr:80.253.88.254  Bcast:80.253.88.255  Mask:255.255.255.0
          UP BROADCAST RUNNING  MTU:1500  Metric:1
          RX packets:63915 errors:0 dropped:0 overruns:0 frame:0
          TX packets:15000 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:52314723 (49.8 Mb)  TX bytes:4608848 (4.3 Mb)
          Memory:f7b00000-f7b1ffff 
And this is after the red0 interface stops being properly configured (notice the absence of the ip address):

Code: Select all

[root@ipfire ~]# ifconfig
blue0     Link encap:Ethernet  HWaddr 04:F0:21:27:1D:44  
          inet addr:10.1.3.1  Bcast:10.1.3.255  Mask:255.255.255.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:221079 errors:0 dropped:0 overruns:0 frame:0
          TX packets:756524 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:41121711 (39.2 Mb)  TX bytes:1064130278 (1014.8 Mb)

green0    Link encap:Ethernet  HWaddr 00:0D:B9:42:68:90  
          inet addr:10.1.1.1  Bcast:10.1.1.255  Mask:255.255.255.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:553848 errors:0 dropped:2 overruns:0 frame:0
          TX packets:2922974 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:53626147 (51.1 Mb)  TX bytes:4307198001 (4107.6 Mb)
          Memory:f7900000-f791ffff 

lo        Link encap:Local Loopback  
          inet addr:127.0.0.1  Mask:255.0.0.0
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:5108 errors:0 dropped:0 overruns:0 frame:0
          TX packets:5108 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:533790 (521.2 Kb)  TX bytes:533790 (521.2 Kb)

orange0   Link encap:Ethernet  HWaddr 00:0D:B9:42:68:91  
          inet addr:10.1.2.1  Bcast:10.1.2.255  Mask:255.255.255.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:225834 errors:0 dropped:0 overruns:0 frame:0
          TX packets:250548 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:114599100 (109.2 Mb)  TX bytes:121318728 (115.6 Mb)
          Memory:f7a00000-f7a1ffff 

red0      Link encap:Ethernet  HWaddr 00:0D:B9:42:68:92  
          UP BROADCAST RUNNING  MTU:1500  Metric:1
          RX packets:4318829 errors:0 dropped:0 overruns:697 frame:0
          TX packets:463236 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:5479806118 (5225.9 Mb)  TX bytes:133897838 (127.6 Mb)
          Memory:f7b00000-f7b1ffff 

tun0      Link encap:UNSPEC  HWaddr 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00  
          inet addr:10.1.4.1  P-t-P:10.1.4.2  Mask:255.255.255.255
          UP POINTOPOINT RUNNING NOARP MULTICAST  MTU:1500  Metric:1
          RX packets:216962 errors:0 dropped:0 overruns:0 frame:0
          TX packets:752997 errors:0 dropped:1089 overruns:0 carrier:0
          collisions:0 txqueuelen:100 
          RX bytes:17528673 (16.7 Mb)  TX bytes:966382537 (921.6 Mb)
When I restart the network, you can see how dhcpd was not running

Code: Select all

[root@ipfire ~]# /etc/init.d/network restart
Removing IPv4 addresses from the green0 interface...                   [  OK  ]
Removing IPv4 addresses from the blue0 interface...                    [  OK  ]
Removing IPv4 addresses from the orange0 interface...                  [  OK  ]
Stopping dhcpcd on the red0 interface...    Not running.               [ WARN ]
Bringing up the green0 interface...
Adding IPv4 address 10.1.1.1 to the green0 interface...                [  OK  ]
Bringing up the blue0 interface...
Adding IPv4 address 10.1.3.1 to the blue0 interface...                 [  OK  ]
Bringing up the orange0 interface...
Adding IPv4 address 10.1.2.1 to the orange0 interface...               [  OK  ]
Bringing up the red0 interface...
Starting dhcpcd on the red0 interface...                               [  OK  ]
           DHCP Assigned Settings for red0:
           IP Address:      80.253.88.254
           Hostname:        ipfire
           Subnet Mask:     255.255.255.0
           Default Gateway: 80.253.88.1
           DNS Server:      77.109.128.2 213.144.129.20
Adding static routes...                                                [  OK  ]
Adding static routes...                                                [  OK  ]
These are the kernel logs on the red interface (at 22:23:43 is when I restarted the network):

Code: Select all

IPFire diagnostics
Section: red
Date: October 12, 2019

04:48:14 dhcpcd[15534] : red0: waiting for carrier
04:48:14 dhcpcd[15534] : red0: carrier acquired
04:48:15 dhcpcd[15534] : DUID 00:01:00:01:24:00:67:9a:00:0d:b9:42:68:90
04:48:15 dhcpcd[15534] : red0: IAID b9:42:68:92
04:48:15 dhcpcd[15534] : red0: adding address fe80::20d:b9ff:fe42:6892
04:48:15 dhcpcd[15534] : ipv6_addaddr1: Permission denied
04:48:15 dhcpcd[15534] : red0: soliciting a DHCP lease
04:48:15 dhcpcd[15534] : red0: soliciting an IPv6 router
04:48:16 dhcpcd[15534] : red0: offered 80.253.88.254 from 213.144.129.5 `carnica.init7.net'
04:48:16 dhcpcd[15534] : red0: probing address 80.253.88.254/24
04:48:20 dhcpcd[15534] : red0: leased 80.253.88.254 for 1800 seconds
04:48:20 dhcpcd[15534] : red0: adding route to 80.253.88.0/24
04:48:20 dhcpcd[15534] : red0: adding default route via 80.253.88.1
04:48:40 dhcpcd[15534] : forked to background, child pid 16657
04:56:18 dhcpcd[18860] : sending signal ALRM to pid 16657
04:56:18 dhcpcd[18860] : waiting for pid 16657 to exit
04:56:18 dhcpcd[16657] : received SIGALRM, releasing
04:56:18 dhcpcd[16657] : red0: removing interface
04:56:18 dhcpcd[16657] : red0: releasing lease of 80.253.88.254
04:56:18 dhcpcd[16657] : red0: deleting route to 80.253.88.0/24
04:56:18 dhcpcd[16657] : red0: deleting default route via 80.253.88.1
04:56:21 dhcpcd[16657] : dhcpcd exited
04:56:28 dhcpcd[19328] : red0: waiting for carrier
04:56:28 dhcpcd[19328] : red0: carrier acquired
04:56:28 dhcpcd[19328] : DUID 00:01:00:01:24:00:67:9a:00:0d:b9:42:68:90
04:56:28 dhcpcd[19328] : red0: IAID b9:42:68:92
04:56:28 dhcpcd[19328] : red0: adding address fe80::20d:b9ff:fe42:6892
04:56:28 dhcpcd[19328] : ipv6_addaddr1: Permission denied
04:56:28 dhcpcd[19328] : red0: soliciting a DHCP lease
04:56:28 dhcpcd[19328] : red0: soliciting an IPv6 router
04:56:29 dhcpcd[19328] : red0: offered 80.253.88.254 from 213.144.129.5 `carnica.init7.net'
04:56:29 dhcpcd[19328] : red0: probing address 80.253.88.254/24
04:56:34 dhcpcd[19328] : red0: leased 80.253.88.254 for 1800 seconds
04:56:34 dhcpcd[19328] : red0: adding route to 80.253.88.0/24
04:56:34 dhcpcd[19328] : red0: adding default route via 80.253.88.1
04:56:56 dhcpcd[19328] : forked to background, child pid 20586
05:00:02 dhcpcd[21374] : sending signal ALRM to pid 20586
05:00:02 dhcpcd[21374] : waiting for pid 20586 to exit
05:00:02 dhcpcd[20586] : received SIGALRM, releasing
05:00:02 dhcpcd[20586] : red0: removing interface
05:00:02 dhcpcd[20586] : red0: releasing lease of 80.253.88.254
05:00:02 dhcpcd[20586] : red0: deleting route to 80.253.88.0/24
05:00:02 dhcpcd[20586] : red0: deleting default route via 80.253.88.1
05:00:05 dhcpcd[20586] : dhcpcd exited
05:00:13 dhcpcd[21768] : red0: waiting for carrier
05:00:13 dhcpcd[21768] : red0: carrier acquired
05:00:13 dhcpcd[21768] : DUID 00:01:00:01:24:00:67:9a:00:0d:b9:42:68:90
05:00:13 dhcpcd[21768] : red0: IAID b9:42:68:92
05:00:13 dhcpcd[21768] : red0: adding address fe80::20d:b9ff:fe42:6892
05:00:13 dhcpcd[21768] : ipv6_addaddr1: Permission denied
05:00:14 dhcpcd[21768] : red0: soliciting an IPv6 router
05:00:14 dhcpcd[21768] : red0: soliciting a DHCP lease
05:00:15 dhcpcd[21768] : red0: offered 80.253.88.254 from 213.144.129.5 `carnica.init7.net'
05:00:15 dhcpcd[21768] : red0: probing address 80.253.88.254/24
05:00:20 dhcpcd[21768] : red0: leased 80.253.88.254 for 1800 seconds
05:00:20 dhcpcd[21768] : red0: adding route to 80.253.88.0/24
05:00:20 dhcpcd[21768] : red0: adding default route via 80.253.88.1
05:00:44 dhcpcd[21768] : forked to background, child pid 23039
05:06:44 dhcpcd[23039] : red0: carrier lost
05:06:47 dhcpcd[23039] : red0: deleting route to 80.253.88.0/24
05:06:47 dhcpcd[23039] : red0: deleting default route via 80.253.88.1
05:07:00 dhcpcd[23039] : red0: carrier acquired
05:07:00 dhcpcd[23039] : red0: IAID b9:42:68:92
05:07:00 dhcpcd[23039] : red0: rebinding lease of 80.253.88.254
05:07:00 dhcpcd[23039] : red0: probing address 80.253.88.254/24
05:07:01 dhcpcd[23039] : red0: soliciting an IPv6 router
05:07:05 dhcpcd[23039] : red0: leased 80.253.88.254 for 1395 seconds
05:07:05 dhcpcd[23039] : red0: adding route to 80.253.88.0/24
05:07:05 dhcpcd[23039] : red0: adding default route via 80.253.88.1
05:16:00 dhcpcd[27282] : sending signal ALRM to pid 23039
05:16:00 dhcpcd[27282] : waiting for pid 23039 to exit
05:16:00 dhcpcd[23039] : received SIGALRM, releasing
05:16:00 dhcpcd[23039] : red0: removing interface
05:16:00 dhcpcd[23039] : red0: releasing lease of 80.253.88.254
05:16:00 dhcpcd[23039] : red0: deleting route to 80.253.88.0/24
05:16:00 dhcpcd[23039] : red0: deleting default route via 80.253.88.1
05:16:03 dhcpcd[23039] : dhcpcd exited
05:16:10 dhcpcd[27771] : red0: waiting for carrier
05:16:10 dhcpcd[27771] : red0: carrier acquired
05:16:10 dhcpcd[27771] : DUID 00:01:00:01:24:00:67:9a:00:0d:b9:42:68:90
05:16:10 dhcpcd[27771] : red0: IAID b9:42:68:92
05:16:10 dhcpcd[27771] : red0: adding address fe80::20d:b9ff:fe42:6892
05:16:10 dhcpcd[27771] : ipv6_addaddr1: Permission denied
05:16:10 dhcpcd[27771] : red0: soliciting an IPv6 router
05:16:11 dhcpcd[27771] : red0: soliciting a DHCP lease
05:16:12 dhcpcd[27771] : red0: offered 80.253.88.254 from 213.144.129.5 `carnica.init7.net'
05:16:12 dhcpcd[27771] : red0: probing address 80.253.88.254/24
05:16:16 dhcpcd[27771] : red0: leased 80.253.88.254 for 1800 seconds
05:16:16 dhcpcd[27771] : red0: adding route to 80.253.88.0/24
05:16:16 dhcpcd[27771] : red0: adding default route via 80.253.88.1
05:16:41 dhcpcd[27771] : forked to background, child pid 29035
05:42:31 dhcpcd[29035] : red0: failed to renew DHCP, rebinding
06:06:20 dhcpcd[29035] : red0: failed to renew DHCP, rebinding
06:31:02 dhcpcd[29035] : red0: failed to renew DHCP, rebinding
06:39:45 dhcpcd[10531] : sending signal ALRM to pid 29035
06:39:45 dhcpcd[10531] : waiting for pid 29035 to exit
06:39:45 dhcpcd[29035] : received SIGALRM, releasing
06:39:45 dhcpcd[29035] : red0: removing interface
06:39:45 dhcpcd[29035] : red0: releasing lease of 80.253.88.254
06:39:45 dhcpcd[29035] : red0: deleting route to 80.253.88.0/24
06:39:45 dhcpcd[29035] : red0: deleting default route via 80.253.88.1
06:39:48 dhcpcd[29035] : dhcpcd exited
06:39:55 dhcpcd[10989] : red0: waiting for carrier
06:39:55 dhcpcd[10989] : red0: carrier acquired
06:39:55 dhcpcd[10989] : DUID 00:01:00:01:24:00:67:9a:00:0d:b9:42:68:90
06:39:55 dhcpcd[10989] : red0: IAID b9:42:68:92
06:39:55 dhcpcd[10989] : red0: adding address fe80::20d:b9ff:fe42:6892
06:39:55 dhcpcd[10989] : ipv6_addaddr1: Permission denied
06:39:55 dhcpcd[10989] : red0: soliciting a DHCP lease
06:39:56 dhcpcd[10989] : red0: soliciting an IPv6 router
06:39:56 dhcpcd[10989] : red0: offered 80.253.88.254 from 213.144.129.5 `carnica.init7.net'
06:39:56 dhcpcd[10989] : red0: probing address 80.253.88.254/24
06:40:01 dhcpcd[10989] : red0: leased 80.253.88.254 for 1800 seconds
06:40:01 dhcpcd[10989] : red0: adding route to 80.253.88.0/24
06:40:01 dhcpcd[10989] : red0: adding default route via 80.253.88.1
06:40:27 dhcpcd[10989] : forked to background, child pid 12241
07:06:16 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
07:30:04 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
07:54:50 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
08:19:15 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
08:43:44 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
09:08:14 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
09:32:41 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
09:57:09 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
10:21:36 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
10:46:03 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
11:10:31 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
11:34:59 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
11:59:30 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
12:23:56 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
12:48:25 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
13:12:49 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
13:37:20 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
14:01:46 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
14:26:15 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
14:50:42 dhcpcd[12241] : red0: failed to renew DHCP, rebinding
15:04:46 dhcpcd[1180] : sending signal ALRM to pid 12241
15:04:46 dhcpcd[1180] : waiting for pid 12241 to exit
15:04:46 dhcpcd[12241] : received SIGALRM, releasing
15:04:46 dhcpcd[12241] : red0: removing interface
15:04:46 dhcpcd[12241] : red0: releasing lease of 80.253.88.254
15:04:46 dhcpcd[12241] : red0: deleting route to 80.253.88.0/24
15:04:46 dhcpcd[12241] : red0: deleting default route via 80.253.88.1
15:04:48 dhcpcd[12241] : dhcpcd exited
15:07:14 dhcpcd[15653] : red0: waiting for carrier
15:07:14 dhcpcd[15653] : red0: carrier acquired
15:07:14 dhcpcd[15653] : DUID 00:01:00:01:24:00:67:9a:00:0d:b9:42:68:90
15:07:14 dhcpcd[15653] : red0: IAID b9:42:68:92
15:07:14 dhcpcd[15653] : red0: adding address fe80::20d:b9ff:fe42:6892
15:07:14 dhcpcd[15653] : ipv6_addaddr1: Permission denied
15:07:14 dhcpcd[15653] : red0: soliciting a DHCP lease
15:07:15 dhcpcd[15653] : red0: soliciting an IPv6 router
15:07:15 dhcpcd[15653] : red0: offered 80.253.88.254 from 213.144.129.5 `carnica.init7.net'
15:07:15 dhcpcd[15653] : red0: probing address 80.253.88.254/24
15:07:20 dhcpcd[15653] : red0: leased 80.253.88.254 for 1800 seconds
15:07:20 dhcpcd[15653] : red0: adding route to 80.253.88.0/24
15:07:20 dhcpcd[15653] : red0: adding default route via 80.253.88.1
15:07:38 dhcpcd[15653] : forked to background, child pid 16775
15:33:35 dhcpcd[16775] : red0: failed to renew DHCP, rebinding
15:48:28 dhcpcd[21068] : sending signal ALRM to pid 16775
15:48:28 dhcpcd[21068] : waiting for pid 16775 to exit
15:48:28 dhcpcd[16775] : received SIGALRM, releasing
15:48:28 dhcpcd[16775] : red0: removing interface
15:48:28 dhcpcd[16775] : red0: releasing lease of 80.253.88.254
15:48:28 dhcpcd[16775] : red0: deleting route to 80.253.88.0/24
15:48:28 dhcpcd[16775] : red0: deleting default route via 80.253.88.1
15:48:30 dhcpcd[16775] : dhcpcd exited
15:50:38 dhcpcd[15560] : red0: waiting for carrier
15:50:38 dhcpcd[15560] : red0: carrier acquired
15:50:39 dhcpcd[15560] : DUID 00:01:00:01:24:00:67:9a:00:0d:b9:42:68:90
15:50:39 dhcpcd[15560] : red0: IAID b9:42:68:92
15:50:39 dhcpcd[15560] : red0: adding address fe80::20d:b9ff:fe42:6892
15:50:39 dhcpcd[15560] : ipv6_addaddr1: Permission denied
15:50:39 dhcpcd[15560] : red0: soliciting an IPv6 router
15:50:39 dhcpcd[15560] : red0: soliciting a DHCP lease
15:50:40 dhcpcd[15560] : red0: offered 80.253.88.254 from 213.144.129.5 `carnica.init7.net'
15:50:40 dhcpcd[15560] : red0: probing address 80.253.88.254/24
15:50:45 dhcpcd[15560] : red0: leased 80.253.88.254 for 1800 seconds
15:50:45 dhcpcd[15560] : red0: adding route to 80.253.88.0/24
15:50:45 dhcpcd[15560] : red0: adding default route via 80.253.88.1
15:51:03 dhcpcd[15560] : forked to background, child pid 16698
16:17:00 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
16:40:49 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
17:05:32 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
17:29:55 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
17:54:22 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
18:18:52 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
18:43:22 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
19:07:51 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
19:32:21 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
19:56:48 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
20:21:18 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
20:45:46 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
21:10:16 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
21:34:43 dhcpcd[16698] : red0: failed to renew DHCP, rebinding
21:34:44 dhcpcd[16698] : red0: checksum failure from 155.229.143.20
21:34:44 dhcpcd[16698] : red0: truncated packet (116) from 10.1.3.102
21:34:44 dhcpcd[16698] : red0: checksum failure from 201.55.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 206.52.88.49
21:34:44 dhcpcd[16698] : red0: invalid UDP packet from 173.194.187.202: No buffer space available
21:34:44 dhcpcd[16698] : red0: checksum failure from 211.49.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 216.46.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 221.43.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 226.40.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 155.229.143.20
21:34:44 dhcpcd[16698] : red0: truncated packet (116) from 10.1.3.102
21:34:44 dhcpcd[16698] : red0: checksum failure from 231.37.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 236.34.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 241.31.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 246.28.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 251.25.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 0.22.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 5.19.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 10.16.88.49
21:34:44 dhcpcd[16698] : red0: checksum failure from 15.13.88.49
22:23:43 dhcpcd[420] : DUID 00:01:00:01:24:00:67:9a:00:0d:b9:42:68:90
22:23:43 dhcpcd[420] : red0: IAID b9:42:68:92
22:23:43 dhcpcd[420] : red0: adding address fe80::20d:b9ff:fe42:6892
22:23:43 dhcpcd[420] : ipv6_addaddr1: Permission denied
22:23:43 dhcpcd[420] : red0: soliciting an IPv6 router
22:23:44 dhcpcd[420] : red0: soliciting a DHCP lease
22:23:45 dhcpcd[420] : red0: offered 80.253.88.254 from 213.144.129.5 `carnica.init7.net'
22:23:45 dhcpcd[420] : red0: probing address 80.253.88.254/24
22:23:50 dhcpcd[420] : red0: leased 80.253.88.254 for 1800 seconds
22:23:50 dhcpcd[420] : red0: adding route to 80.253.88.0/24
22:23:50 dhcpcd[420] : red0: adding default route via 80.253.88.1
22:24:13 dhcpcd[420] : forked to background, child pid 1471
22:24:24 dhcpcd[1707] : sending signal ALRM to pid 1471
22:24:24 dhcpcd[1707] : waiting for pid 1471 to exit
22:24:24 dhcpcd[1471] : received SIGALRM, releasing
22:24:24 dhcpcd[1471] : red0: removing interface
22:24:24 dhcpcd[1471] : red0: releasing lease of 80.253.88.254
22:24:24 dhcpcd[1471] : red0: deleting route to 80.253.88.0/24
22:24:24 dhcpcd[1471] : red0: deleting default route via 80.253.88.1
22:24:27 dhcpcd[1471] : dhcpcd exited
22:24:36 dhcpcd[2180] : red0: waiting for carrier
22:24:36 dhcpcd[2180] : red0: carrier acquired
22:24:36 dhcpcd[2180] : DUID 00:01:00:01:24:00:67:9a:00:0d:b9:42:68:90
22:24:36 dhcpcd[2180] : red0: IAID b9:42:68:92
22:24:36 dhcpcd[2180] : red0: adding address fe80::20d:b9ff:fe42:6892
22:24:36 dhcpcd[2180] : ipv6_addaddr1: Permission denied
22:24:36 dhcpcd[2180] : red0: soliciting a DHCP lease
22:24:36 dhcpcd[2180] : red0: offered 80.253.88.254 from 213.144.129.5 `carnica.init7.net'
22:24:36 dhcpcd[2180] : red0: probing address 80.253.88.254/24
22:24:36 dhcpcd[2180] : red0: soliciting an IPv6 router
22:24:41 dhcpcd[2180] : red0: leased 80.253.88.254 for 1800 seconds
22:24:41 dhcpcd[2180] : red0: adding route to 80.253.88.0/24
22:24:41 dhcpcd[2180] : red0: adding default route via 80.253.88.1
22:25:03 dhcpcd[2180] : forked to background, child pid 3529
22:50:56 dhcpcd[3529] : red0: failed to renew DHCP, rebinding
Please let me know how can I help troubleshooting the issue. I will need to downgrade soon as this situation is unacceptable.
Last edited by cfusco on October 19th, 2019, 12:18 pm, edited 2 times in total.
Image

BeBiMa
Posts: 2842
Joined: July 30th, 2011, 12:55 pm
Location: Mannheim

Re: after core update 136, dhcpd stops running on red0

Post by BeBiMa » October 12th, 2019, 9:04 pm

I've noticed a similiar behaviour.
After some random time dhcpcd stops with an "segfault error". The problem seems to be coupled to a change of the IP address on red0.

The mailing list of dhcpcd contains postings about an SEGV error. Version 8.1.0 should fix this issue ( according to the mail list ).
Last edited by BeBiMa on October 14th, 2019, 4:02 pm, edited 1 time in total.
Image
Unitymedia Cable Internet ( 32MBit )

cfusco
Posts: 184
Joined: March 23rd, 2015, 4:19 pm

Re: after core update 136, dhcpd stops running on red0

Post by cfusco » October 12th, 2019, 9:08 pm

BeBiMa wrote:
October 12th, 2019, 9:04 pm
I've noticed a similiar behaviour.
After some random time dhcpcd stops with an SEGV error. The problem seems to be coupled to a change of the IP address on red0.

The mailing list of dhcpcd contains postings about an SEGV error. Version 8.1.0 should fix this issue ( according to the mail list ).
Thanks, I thought I was crazy! Would be possible to downgrade to the previous version of dhcpd, so that I do not have to reinstall core 135?
Image

cfusco
Posts: 184
Joined: March 23rd, 2015, 4:19 pm

Re: after core update 136, dhcpd stops running on red0

Post by cfusco » October 12th, 2019, 9:10 pm

BeBiMa wrote:
October 12th, 2019, 9:04 pm
I've noticed a similiar behaviour.
After some random time dhcpcd stops with an SEGV error. The problem seems to be coupled to a change of the IP address on red0.
I do have a fix Ip though.
Image

User avatar
FischerM
Community Developer
Community Developer
Posts: 1024
Joined: November 2nd, 2011, 12:28 pm

Re: after core update 136, dhcpd stops running on red0

Post by FischerM » October 12th, 2019, 11:09 pm

Hi,

Update to dhcpcd 8.1.0 is on GIT and on Patchwork.

Best,
Matthias

RedneckMother
Posts: 94
Joined: June 21st, 2014, 1:34 am
Location: USA

Re: after core update 136, dhcpd stops running on red0

Post by RedneckMother » October 13th, 2019, 3:49 am

No offence intended, but how does the presence of updates on patchwork or git resolve the problems encountered by current users of Core 136?

User avatar
Arne.F
Core Developer
Core Developer
Posts: 8516
Joined: May 7th, 2006, 8:57 am
Location: BS <-> NDH
Contact:

Re: after core update 136, dhcpd stops running on red0

Post by Arne.F » October 13th, 2019, 6:15 am

The problem is that core136 doesnt touch dhcpcd, in core135 is the same version. So I'm not understand whats going on here.

I will add the update to core137 and rebuild...
Arne

Support the project on the donation!

Image

Image

Image
PS: I will not answer support questions via email and ignore IPFire related messages on my non IPFire.org mail addresses.

User avatar
FischerM
Community Developer
Community Developer
Posts: 1024
Joined: November 2nd, 2011, 12:28 pm

Re: after core update 136, dhcpd stops running on red0

Post by FischerM » October 13th, 2019, 6:52 am

Hi,

@RedneckMother:
No problem, I don't feel offended. ;)
I haven't had this problem yet, but I can provide at least an update. If the SEGV is actually due to 'dhcpcd' perhaps only a downgrade may help.

@Arne:
After a quick look in the cited 'dhcpcd'-mailing list I found Re: [Security][Patch] SEGV in dhcpcd-8.0.4.

@BeBiMa:
Was that the mailing you meant?

So, if Roys patch for 8.0.4 didn't work as intended (Murphys Law!) it could be, that both 8.0.5 and 8.0.6 are still affected in some way. How knows?

That's why I pushed the 8.1.0-version as fast as possible - and to get the other developers informed.

Besides, 8.1.0 is running here with no seen problems and: it was ~1:10am here and time for bed... ;)

Best,
Matthias

cfusco
Posts: 184
Joined: March 23rd, 2015, 4:19 pm

Re: after core update 136, dhcpd stops running on red0

Post by cfusco » October 13th, 2019, 6:58 am

Arne.F wrote:
October 13th, 2019, 6:15 am
The problem is that core136 doesnt touch dhcpcd, in core135 is the same version. So I'm not understand whats going on here.

I will add the update to core137 and rebuild...
Are you sure? According to the blog announcements 135 has 7.2.3 and 136 8.0.3

https://blog.ipfire.org/post/ipfire-2-2 ... 6-released
https://blog.ipfire.org/post/ipfire-2-2 ... 5-released
Image

User avatar
Arne.F
Core Developer
Core Developer
Posts: 8516
Joined: May 7th, 2006, 8:57 am
Location: BS <-> NDH
Contact:

Re: after core update 136, dhcpd stops running on red0

Post by Arne.F » October 13th, 2019, 7:05 am

sorry. You are correct. Core136 updates from 7 to 8.03...

I have added 8.10 to core137 and built for testing... If this not work we will downgrade...
Arne

Support the project on the donation!

Image

Image

Image
PS: I will not answer support questions via email and ignore IPFire related messages on my non IPFire.org mail addresses.

User avatar
FischerM
Community Developer
Community Developer
Posts: 1024
Joined: November 2nd, 2011, 12:28 pm

Re: after core update 136, dhcpd stops running on red0

Post by FischerM » October 13th, 2019, 11:47 am

We even could get problems with 8.1.0. *sigh*

cfusco
Posts: 184
Joined: March 23rd, 2015, 4:19 pm

Re: after core update 136, dhcpd stops running on red0

Post by cfusco » October 13th, 2019, 4:55 pm

It's happening 2/3 times per day. Waiting for the fix and not really wanting to reinstall from scratch 135 and then do a restore of the backup settings, I though to write a simple script that restarts the server:

Code: Select all

#!/bin/sh
echo -e "-------------------------\n$(date)"  >> /root/log_script_workaround_dhcpd_bug.txt
dhcpcd -T red0 -t 2 &>> /root/log_script_workaround_dhcpd_bug.txt
I did put the script with execution privilege in /etc/fcron.hourly. The intent is that when I am away from my network, if it goes down it stays down at the most 1 hour. Since I do not know what I am doing, does anyone have any advice or criticism? Thanks.

Edit: it seems it's working as intended. For now I will use this work around until 137 is released with the fix.
Image

cfusco
Posts: 184
Joined: March 23rd, 2015, 4:19 pm

Re: after core update 136, dhcpd stops running on red0

Post by cfusco » October 13th, 2019, 7:26 pm

FischerM wrote:
October 13th, 2019, 11:47 am
We even could get problems with 8.1.0. *sigh*
That is really unfortunate. Thanks for following the issue. It is really appreciated.
Image

BeBiMa
Posts: 2842
Joined: July 30th, 2011, 12:55 pm
Location: Mannheim

Re: after core update 136, dhcpd stops running on red0

Post by BeBiMa » October 13th, 2019, 7:28 pm

Just some logs with the crash of dhcpcd

Code: Select all

[root@BitschCop ~]# tail -f  /var/log/messages|grep dhcpcd
Oct 13 19:26:26 BitschCop dhcpcd[30074]: sending signal HUP to pid 3488    
rebindig forced by dhcpcd -n

Code: Select all

Oct 13 19:26:26 BitschCop dhcpcd[3488]: received SIGHUP, rebinding
Oct 13 19:26:27 BitschCop dhcpcd[3488]: red0: IAID b9:12:d0:31
Oct 13 19:26:27 BitschCop dhcpcd[3488]: red0: rebinding lease of 37.209.65.70
Oct 13 19:26:27 BitschCop dhcpcd[3488]: red0: soliciting an IPv6 router
Oct 13 19:26:28 BitschCop dhcpcd.exe[30092]: Overwritting DNS-Server addresses with 46.182.19.48,194.150.168.168
perodic renew

Code: Select all

Oct 13 20:18:00 BitschCop dhcpcd[3488]: red0: failed to renew DHCP, rebinding
Oct 13 20:18:00 BitschCop dhcpcd[3488]: red0: invalid UDP packet from 37.209.65.70: No buffer space available
Oct 13 20:18:00 BitschCop kernel: dhcpcd[3488]: segfault at bfb65000 ip 0806b173 sp bfb63140 error 4 in dhcpcd[804e000+34000]
restart red0 (/etc/rc.d/init.d/networking/red start)

Code: Select all

Oct 13 21:08:07 BitschCop dhcpcd.exe[5242]: red0 has been brought down
Oct 13 21:08:13 BitschCop dhcpcd[5241]: DUID 00:01:00:01:23:97:e9:c3:00:0d:b9:12:d0:31
Oct 13 21:08:13 BitschCop dhcpcd[5241]: red0: IAID b9:12:d0:31
Oct 13 21:08:13 BitschCop dhcpcd[5241]: red0: adding address fe80::20d:b9ff:fe12:d031
Oct 13 21:08:13 BitschCop dhcpcd[5241]: ipv6_addaddr1: Permission denied
Oct 13 21:08:14 BitschCop dhcpcd[5241]: red0: soliciting an IPv6 router
Oct 13 21:08:14 BitschCop dhcpcd[5241]: red0: soliciting a DHCP lease
Oct 13 21:08:14 BitschCop dhcpcd[5241]: red0: offered 37.209.65.70 from 10.124.224.1
Oct 13 21:08:14 BitschCop dhcpcd[5241]: red0: probing address 37.209.65.70/22
Oct 13 21:08:19 BitschCop dhcpcd[5241]: red0: leased 37.209.65.70 for 3600 seconds
Oct 13 21:08:19 BitschCop dhcpcd[5241]: red0: adding route to 37.209.64.0/22
Oct 13 21:08:19 BitschCop dhcpcd[5241]: red0: adding default route via 37.209.64.1
Oct 13 21:08:20 BitschCop dhcpcd.exe[5502]: Overwritting DNS-Server addresses with 46.182.19.48,194.150.168.168
Oct 13 21:08:20 BitschCop dhcpcd.exe[5502]: red0 has been (re)configured with IP=37.209.65.70
Oct 13 21:08:58 BitschCop dhcpcd[5241]: forked to background, child pid 6295

Maybe it helps a bit for clarifying. IP didn't change.
The error "failed to renew DHCP, rebinding" accompanies each refresh.
Image
Unitymedia Cable Internet ( 32MBit )

ipfireuser5150
Posts: 32
Joined: May 18th, 2019, 5:28 pm

Re: after core update 136, dhcpd stops running on red0

Post by ipfireuser5150 » October 13th, 2019, 7:30 pm

What's the story with this, is it a universal issue or only limited cases? I've held off upgrading one of my systems after I saw this thread. Should I wait for 137?

Post Reply