Ipfire Update 131 - Einwahlproblem

Steven
Posts: 12
Joined: April 18th, 2018, 2:58 pm

Ipfire Update 131 - Einwahlproblem

Post by Steven » May 20th, 2019, 1:03 pm

Hallo zusammen,

seit dem Update 131 habe ich ein Problem bei der Einwahl via DHCP auf RED. Leider nicht immer, sondern nur hin und wieder beim Neustart oder wenn die Verbindung unterbrochen war und die Einwahl erneut gemacht wird.

Und zwar bleibt in der WUI der Status "Verbindung wird aufgebaut..." stehen. Meine feste IP bekomme ich vom Provider zugewiesen, aber bei DNS-Server steht irgendwas mit "recursive...". Und dann geht überhaupt nichts, bis ich erneut neustarte und dann die Einwahl korrekt klappt.

Als Hinweis: Ich lasse den Provider-DNS mit eigenen überschreiben, welche ich unter "DNS-Server zuweisen" eingegeben habe.

Wenn die Einwahl fehlt schlecht, steht im RED-Log folgendes:
12:57:39 dhcpcd[2505] : red0: deleting route to 169.254.0.0/16
12:57:26 dhcpcd[2505] : red0: changing default route via 5.XX.XX.62
12:57:26 dhcpcd[2505] : red0: adding route to 5.XX.XX.0/26
12:57:26 dhcpcd[2505] : red0: leased 5.XX.XX.13 for 88918 seconds
12:57:25 dhcpcd[1807] : forked to background, child pid 2505
12:57:24 dhcpcd[1807] : red0: adding default route
12:57:24 dhcpcd[1807] : red0: adding route to 169.254.0.0/16
12:57:24 dhcpcd[1807] : red0: using IPv4LL address 169.254.21.97

12:57:20 dhcpcd[1807] : red0: probing address 5.XX.XX.13/26
12:57:19 dhcpcd[1807] : red0: probing for an IPv4LL address
12:57:17 dhcpcd[1807] : red0: offered 5.XX.XX.13 from 10.X.X.254
12:57:14 dhcpcd[1807] : red0: soliciting a DHCP lease
12:57:14 dhcpcd[1807] : red0: soliciting an IPv6 router
12:57:13 dhcpcd[1807] : ipv6_addaddr1: Permission denied
12:57:13 dhcpcd[1807] : red0: adding address fe80::794
12:57:13 dhcpcd[1807] : red0: IAID 6b::94
12:57:13 dhcpcd[1807] : DUID 00::94
12:57:13 dhcpcd[1807] : red0: carrier acquired
12:57:12 dhcpcd[1807] : red0: waiting for carrier
12:55:52 dhcpcd[3342] : dhcpcd exited
12:55:52 dhcpcd[3342] : red0: deleting default route via 5.XX.XX.62
12:55:52 dhcpcd[3342] : red0: deleting route to 5.XX.XX.0/26
12:55:51 dhcpcd[28147] : waiting for pid 3342 to exit
12:55:51 dhcpcd[3342] : red0: releasing lease of 5.XX.XX.13
12:55:51 dhcpcd[3342] : red0: removing interface
12:55:51 dhcpcd[3342] : received SIGALRM, releasing
12:55:51 dhcpcd[28147] : sending signal ALRM to pid 3342
Wenn alles funktioniert, dann dies:
13:36:50 dhcpcd[1807] : forked to background, child pid 3398
13:36:36 dhcpcd[1807] : red0: adding default route via 5.XX.XX.62
13:36:36 dhcpcd[1807] : red0: adding route to 5.XX.XX.0/26
13:36:36 dhcpcd[1807] : red0: leased 5.XX.XX.13 for 86396 seconds
13:36:32 dhcpcd[1807] : red0: probing address 5.XX.XX.13/26
13:36:31 dhcpcd[1807] : red0: offered 5.XX.XX.13 from 10.X.X.254
13:36:30 dhcpcd[1807] : red0: soliciting an IPv6 router
13:36:29 dhcpcd[1807] : red0: soliciting a DHCP lease
13:36:29 dhcpcd[1807] : ipv6_addaddr1: Permission denied
13:36:29 dhcpcd[1807] : red0: adding address fe80::794
13:36:29 dhcpcd[1807] : red0: IAID 6b::94
13:36:29 dhcpcd[1807] : DUID 00::94
13:36:29 dhcpcd[1807] : red0: carrier acquired
13:36:27 dhcpcd[1807] : red0: waiting for carrier
13:35:07 dhcpcd[2505] : dhcpcd exited
13:35:06 dhcpcd[2505] : red0: deleting default route via 5.XX.XX.62
13:35:06 dhcpcd[2505] : red0: deleting route to 5.XX.XX.0/26
13:35:06 dhcpcd[19568] : waiting for pid 2505 to exit
13:35:06 dhcpcd[2505] : red0: releasing lease of 5.XX.XX.13
13:35:06 dhcpcd[2505] : red0: removing interface
13:35:06 dhcpcd[2505] : received SIGALRM, releasing
13:35:06 dhcpcd[19568] : sending signal ALRM to pid 2505

Was ist dieses 169.254.0.0/16 - Netz und was macht dies bei der Einwahl?
Ist dies eventuell ein Bug, der bisher nicht aufgefallen ist?

Danke für jeden Hinweis !

5p9
Mentor
Mentor
Posts: 1860
Joined: May 1st, 2011, 3:27 pm

Re: Ipfire Update 131 - Einwahlproblem

Post by 5p9 » May 20th, 2019, 2:02 pm

Hi,

die IP ist eine standard IP die zugewiesen wird, wenn das System keine IP von einem DHCP Server erhält/ erhalten kann:
https://www.heise.de/Reservierte-IPv4-A ... 84192.html

Es scheint fast so, als ob dein Modem/Router zulange brauch um der IPFire eine IP zu zuweisen. Was der Grund hierfür ist, k.A.
Was ist, wenn du den Standard-DNS vom Provider verwendest, geht es dann fehlerfrei durch?

VG, 5p9
Mail Gateway: mail proxy

Image

Image

Steven
Posts: 12
Joined: April 18th, 2018, 2:58 pm

Re: Ipfire Update 131 - Einwahlproblem

Post by Steven » May 20th, 2019, 9:02 pm

5p9 wrote:
May 20th, 2019, 2:02 pm
die IP ist eine standard IP die zugewiesen wird, wenn das System keine IP von einem DHCP Server erhält/ erhalten kann:
https://www.heise.de/Reservierte-IPv4-A ... 84192.html
Ah, wieder was gelernt. :-)
5p9 wrote:
May 20th, 2019, 2:02 pm
Es scheint fast so, als ob dein Modem/Router zulange brauch um der IPFire eine IP zu zuweisen. Was der Grund hierfür ist, k.A.
Ist eine direkte Glasfaser-Leitung. Es gibt kein Modem, sondern nur einen LAN-Konverter. Was ich im Log aber nicht verstehe, ist, dass er die korrekte IP angeboten bekommt, testet und währenddessen wird "using IPv4LL address 169.254.21.97" ausgeführt. Sobald dies passiert, egal, ob danach die korrekte IP gesetzt und geroutet wird, geht keinerlei Traffic nach draußen.
5p9 wrote:
May 20th, 2019, 2:02 pm
Was ist, wenn du den Standard-DNS vom Provider verwendest, geht es dann fehlerfrei durch?
Habe ich noch nicht getestet. Kann ich aber gerne mal machen. Ich glaube aber nicht, dass dies was bringt. Denke eher, dass er sich aufgrund der 169.254er Route intern verrennt. Es starten dann auch keinerlei VPN-Dienste (IPSEC, OpenVPN) und entsprechend auch keine Verbindungen.

Danke erstmal für deine Gedanken...

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 4th, 2019, 9:19 am

Hallo,
bei mir sind die gleichen Probleme aufgetaucht. Ich habe in einem Rutsch mehrere Core Updates installiert und bin jetzt gerade bei 133 (vorher war ich auf 124).
Jetzt wird bei jeder Trennung mittels dem connection scheduler eine Adresse aus dem 169.254.X.X Netz zugewiesen obwohl eine Adresse zuvor angeboten wurde.

Code: Select all

red0: offered 5.10.x.x from 10.x.x.254
red0: probing for an IPv4LL address
red0: using IPv4LL address 169.254.x.x
usw.
Danach hilft nur ein Neustart. Gibt es dafür schon eine Lösung?

Viele Grüße

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 6th, 2019, 5:43 am

Hier nochmal das komplette dhcpcd Log eines misslungenen reconnect. Aktuell muss ich jeden Morgen neustarten :(
Meine IPFire hängt übrigens auch an Glasfaser und einem Medienkonverter.
Jul 6 01:10:01 ipfire dhcpcd[28325]: sending signal ALRM to pid 3451
Jul 6 01:10:01 ipfire dhcpcd[3451]: received SIGALRM, releasing
Jul 6 01:10:01 ipfire dhcpcd[3451]: red0: removing interface
Jul 6 01:10:01 ipfire dhcpcd[3451]: red0: releasing lease of 5.10.x.x
Jul 6 01:10:01 ipfire dhcpcd[3451]: red0: deleting route to 5.10.x.x/25
Jul 6 01:10:01 ipfire dhcpcd[3451]: red0: deleting default route via 5.10.x.x
Jul 6 01:10:01 ipfire dhcpcd[28325]: waiting for pid 3451 to exit
Jul 6 01:10:01 ipfire dhcpcd.exe[28328]: red0 has been brought down
Jul 6 01:10:02 ipfire dhcpcd[3451]: dhcpcd exited
Jul 6 01:10:04 ipfire dhcpcd.exe[28681]: red0 has been brought down
Jul 6 01:10:05 ipfire dhcpcd.exe[28898]: red0 has been brought down
Jul 6 01:10:06 ipfire dhcpcd[28680]: red0: waiting for carrier
Jul 6 01:10:07 ipfire dhcpcd[28680]: red0: carrier acquired
Jul 6 01:10:07 ipfire dhcpcd[28680]: DUID 00:04:00:00:00:00:00:00:00:00:00:00:0c:c4:7a:9f:36:b8
Jul 6 01:10:07 ipfire dhcpcd[28680]: red0: IAID 7a:9f:36:b9
Jul 6 01:10:07 ipfire dhcpcd[28680]: red0: adding address fe80::ec4:7aff:xxxx:xxxx
Jul 6 01:10:07 ipfire dhcpcd[28680]: ipv6_addaddr1: Permission denied
Jul 6 01:10:08 ipfire dhcpcd[28680]: red0: soliciting a DHCP lease
Jul 6 01:10:08 ipfire dhcpcd[28680]: red0: soliciting an IPv6 router
Jul 6 01:10:13 ipfire dhcpcd[28680]: red0: probing for an IPv4LL address
Jul 6 01:10:15 ipfire dhcpcd[28680]: red0: offered 91.137.x.x from 10.211.x.x
Jul 6 01:10:17 ipfire dhcpcd[28680]: red0: using IPv4LL address 169.254.148.39
Jul 6 01:10:17 ipfire dhcpcd[28680]: red0: adding route to 169.254.0.0/16
Jul 6 01:10:17 ipfire dhcpcd[28680]: red0: adding default route
Jul 6 01:10:18 ipfire dhcpcd.exe[29134]: red0 has been brought down
Jul 6 01:10:18 ipfire dhcpcd[28680]: forked to background, child pid 29352
Jul 6 01:10:18 ipfire dhcpcd[29352]: red0: probing address 91.137.x.x/24
Jul 6 01:10:23 ipfire dhcpcd[29352]: red0: leased 91.137.x.x for 172456 seconds
Jul 6 01:10:23 ipfire dhcpcd[29352]: red0: adding route to 91.137.x.x/24
Jul 6 01:10:23 ipfire dhcpcd[29352]: red0: changing default route via 91.137.x.x
Jul 6 01:10:24 ipfire dhcpcd.exe[29411]: red0 has been (re)configured with IP=91.137.x.x
Jul 6 01:10:43 ipfire dhcpcd[29352]: red0: deleting route to 169.254.0.0/16
Jul 6 01:10:44 ipfire dhcpcd.exe[30339]: red0 has been brought down
Viele Grüße

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 7th, 2019, 3:07 pm

Kann es sein, dass das ganze mit der Einführung von Suricata in 131 etwas zu tun hat? Hier ist ja ein Initscript bei red.up dazu gekommen.
Leider kann ich auf der IPS Konfigurationsseite nichts deaktivieren wie es im wiki zu sehen ist sonst hätte ich das schon probiert.

Ich hoffe es kann bald jemand helfen.

Viele Grüße

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 7th, 2019, 3:39 pm

So, nach dem ich mal auf der Konfigurationsseite von Suricata mal auf Speichern gedrückt hatte waren dann auch alle Optionen wie im Wiki da.
Suricata überall deaktiviert und per connscheduler einen reconnect versucht welcher vorher immer gescheitert ist und siehe da funktioniert.
Dann Suricata wieder aktiviert und nochmals getestet. Geht immer noch. Mal schauen ob es morgen früh immer noch funktioniert, ich werde berichten.

Viele Grüße

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 8th, 2019, 5:04 am

Leider hat es heute morgen mit aktiviertem Suricata wieder nicht funktioniert. Wiederverbinden mit dem connection scheduler ging auch nicht ohne weiteres, da dhcpc noch lief. Erst nach dem ich den Prozess dhcpc gekillt hatte konnte ich mich wieder mit dem connection scheduler neu verbinden. Ich werde Suricata jetzt komplett deaktivieren und nochmals abwarten ob der morgige reconnect funktioniert.

Was bedeutet
RTNETLINK answers: No such file or directory
bzw. wie kann ich die Fehlermeldung beseitigen?

Viele Grüße

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 9th, 2019, 5:32 am

Leider auch kein Erfolg mit deaktiviertem Suricata! Irgendwie hängt es am dhcpc...

Beim ersten reconnect mittels connection scheduler bekomme ich ein
Starting dhcpcd on the red0 interface...dhcpcd already running!
Und dann gehts nicht weiter, erst nachdem ich mittels killall dhcpcd beendet habe kann ich mich wieder verbinden.

Hoffe auf baldige Hilfe...
Viele Grüße

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 11th, 2019, 8:17 am

Heute werde ich unter /var/ipfire/dhcpc/ die dhcpcd.conf modifizieren und testen ob der Reconnect heute Nacht funktioniert... um die Zeit, zu der ich den Reconnect mache, scheint beim ISP viel los zu sein. Ein Lease dauert manchmal über 30 Sekunden. Führe ich tagsüber per conn-scheduler ein Reconnect durch, wird der Lease innerhalb von ca. 10 Sekunden geschickt.

Meine Modifikationen an der dhcpcd.conf

Code: Select all

# A sample configuration for dhcpcd.
# See dhcpcd.conf(5) for details.

# Inform the DHCP server of our hostname for DDNS.
hostname
# To share the DHCP lease across OSX and Windows a ClientID is needed.
# Enabling this may get a different lease than the kernel DHCP client.
# Some upstream DHCP servers may also require a ClientID, such as FRITZ!Box.
#clientid

# A list of options to request from the DHCP server.
option domain_name_servers, domain_name, domain_search, host_name
option classless_static_routes
# Most distributions have NTP support.
option ntp_servers
# Respect the network MTU.
option interface_mtu
# A ServerID is required by RFC2131.
require dhcp_server_identifier

# A hook script is provided to lookup the hostname if not set by the DHCP
# server, but it should not be run by default.
nohook lookup-hostname

# Modified custom part
# Don't solicit or accept IPv6 Router Advertisements and DHCPv6.
noipv6
#Don't start DHCPv6 or listen to DHCPv6 messages.
nodhcp6
#Don't attempt to obtain an IPv4LL address if we failed to get one via DHCP.
noipv4ll
#Time out after seconds, instead of the default 30.
timeout 60
Ob es funktioniert hat, werde ich berichten.
Viele Grüße

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 15th, 2019, 9:44 am

Da ich mit dem Problem nicht so richtig weiter komme, habe ich mich nochmal von vorne angefangen. Das ursprüngliche Problem welches ich von Anfang an hatte, war die Erneuerung des DHCP Leases nach Ablauf des Renew Timers. Dies hatte ich behoben durch den Connection Scheduler, welcher jetzt nicht mehr richtig funktioniert.

Aktuell habe ich in der dhcpcd.conf debug aktiviert. Hier mal das Log von heute Nacht nach deaktivierung des Connection Schedulers.
Jul 15 01:06:01 ipfire dhcpcd[4629]: red0: renewing lease of 91.137.66.176
Jul 15 01:06:01 ipfire dhcpcd[4629]: red0: sending REQUEST (xid 0xcd5cfbae), next in 5.0 seconds
Jul 15 01:06:01 ipfire dhcpcd[4629]: red0: failed to renew DHCP, rebinding
Jul 15 01:06:01 ipfire dhcpcd[4629]: red0: expire in 39891 seconds
Jul 15 01:06:01 ipfire dhcpcd[4629]: red0: sending REQUEST (xid 0xcd5cfbae), next in 3.3 seconds
Jul 15 01:06:01 ipfire dhcpcd[4629]: red0: op (1) is not BOOTREPLY
Jul 15 01:06:04 ipfire dhcpcd[4629]: red0: sending REQUEST (xid 0xcd5cfbae), next in 8.5 seconds
Jul 15 01:06:05 ipfire dhcpcd[4629]: red0: acknowledged 91.137.66.176 from 10.211.0.254
Jul 15 01:06:05 ipfire dhcpcd[4629]: red0: leased 91.137.66.176 for 174742 seconds
Jul 15 01:06:05 ipfire dhcpcd[4629]: red0: renew in 87371 seconds, rebind in 87371 seconds
Jul 15 01:06:05 ipfire dhcpcd[4629]: red0: writing lease `/var/ipfire/dhcpc/red0.lease'
Jul 15 01:06:05 ipfire dhcpcd[4629]: red0: IP address 91.137.66.176/24 already exists
Jul 15 01:06:05 ipfire dhcpcd[4629]: red0: ARP announcing 91.137.66.176 (1 of 2), next in 2.0 seconds
Jul 15 01:06:05 ipfire dhcpcd[4629]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' REBIND
Jul 15 01:06:07 ipfire dhcpcd[4629]: red0: ARP announcing 91.137.66.176 (2 of 2)
Was hier auffällt, der ISP (komdsl) schickt für die Counter T1 Renew und T2 Rebind die gleiche Zeit. Wobei aber T2 größer sein sollte als T1. Dies scheint der Grund für den Hänger der IPfire zu sein da sie gleich noch ein Rebind versucht.

Gibt es eine Möglichkeit die Zeiten selber aus der Leasezeit zu berechnen und zu setzen?

Viele Grüße

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

Re: Ipfire Update 131 - Einwahlproblem

Post by Arne.F » July 16th, 2019, 9:27 am

Beim ersten reconnect mittels connection scheduler bekomme ich ein
Starting dhcpcd on the red0 interface...dhcpcd already running!
Und dann gehts nicht weiter, erst nachdem ich mittels killall dhcpcd beendet habe kann ich mich wieder verbinden.
Da bin ich grad dran. Das liegt aber nicht am dhcp sondern am unbound
der versucht beim runterfahren der Interfaces die dns-forwarder zu aktualisieren was natürlich schiefgeht und ewig dauert.

Lösch mal /etc/rc.d/init.d/networking/red.down/05-update-dns-forwarders
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.

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 16th, 2019, 3:31 pm

Hallo Arne,
das werde ich direkt mal morgen früh testen, muss dazu aber meine dhcpcd.conf erst mal alles was reinkam auskommentieren ;) . Da das normale Prozedere mit renew nicht klappt habe ich heute Nacht wieder den connection scheduler angeschmissen und in der dhcpcd.conf noch ein release reingebastelt und die Verbindung hat heute zumindest geklappt.

Aktuelle dhcpcd.conf

Code: Select all

 
# A sample configuration for dhcpcd.
# See dhcpcd.conf(5) for details.

# Inform the DHCP server of our hostname for DDNS.
hostname
# To share the DHCP lease across OSX and Windows a ClientID is needed.
# Enabling this may get a different lease than the kernel DHCP client.
# Some upstream DHCP servers may also require a ClientID, such as FRITZ!Box.
#clientid

# A list of options to request from the DHCP server.
option domain_name_servers, domain_name, domain_search, host_name
option classless_static_routes
# Most distributions have NTP support.
option ntp_servers
# Respect the network MTU.
option interface_mtu

# A ServerID is required by RFC2131.
require dhcp_server_identifier

# A hook script is provided to lookup the hostname if not set by the DHCP
# server, but it should not be run by default.
nohook lookup-hostname

# Modified custom part
# Don't solicit or accept IPv6 Router Advertisements and DHCPv6.
noipv6
#Don't start DHCPv6 or listen to DHCPv6 messages.
nodhcp6
#Don't attempt to obtain an IPv4LL address if we failed to get one via DHCP.
noipv4ll
#Time out after seconds, instead of the default 30.
timeout 120
#Echo debug messages to the stderr and syslog.
debug
#dhcpcd will release the lease prior to stopping the interface.
release
Aktuelles Log von RED
Jul 16 01:05:01 ipfire dhcpcd[24438]: sending signal ALRM to pid 11127
Jul 16 01:05:01 ipfire dhcpcd[11127]: received SIGALRM, releasing
Jul 16 01:05:01 ipfire dhcpcd[11127]: red0: removing interface
Jul 16 01:05:01 ipfire dhcpcd[11127]: red0: releasing lease of 5.10.x.x
Jul 16 01:05:01 ipfire dhcpcd[11127]: red0: sending RELEASE with xid 0x248e1d84
Jul 16 01:05:01 ipfire dhcpcd[11127]: red0: deleting IP address 5.10.x.x/25
Jul 16 01:05:01 ipfire dhcpcd[11127]: red0: deleting route to 5.10.x.x/25
Jul 16 01:05:01 ipfire dhcpcd[11127]: red0: deleting default route via 5.10.x.x
Jul 16 01:05:01 ipfire dhcpcd[11127]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' STOP
Jul 16 01:05:01 ipfire dhcpcd[24438]: waiting for pid 11127 to exit
Jul 16 01:05:01 ipfire dhcpcd.exe[24445]: red0 has been brought down
Jul 16 01:05:08 ipfire dhcpcd[11127]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' STOPPED
Jul 16 01:05:08 ipfire dhcpcd[11127]: dhcpcd exited
Jul 16 01:05:10 ipfire dhcpcd[25025]: dhcpcd-7.2.2 starting
Jul 16 01:05:10 ipfire dhcpcd[25025]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' PREINIT
Jul 16 01:05:11 ipfire dhcpcd.exe[25026]: red0 has been brought down
Jul 16 01:05:18 ipfire dhcpcd[25025]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' NOCARRIER
Jul 16 01:05:18 ipfire dhcpcd.exe[25463]: red0 has been brought down
Jul 16 01:05:25 ipfire dhcpcd[25025]: red0: waiting for carrier
Jul 16 01:05:25 ipfire dhcpcd[25025]: red0: carrier acquired
Jul 16 01:05:25 ipfire dhcpcd[25025]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' CARRIER
Jul 16 01:05:25 ipfire dhcpcd[25025]: red0: delaying IPv4 for 0.2 seconds
Jul 16 01:05:25 ipfire dhcpcd[25025]: red0: using hwaddr 0c:c4:x
Jul 16 01:05:25 ipfire dhcpcd[25025]: red0: soliciting a DHCP lease
Jul 16 01:05:25 ipfire dhcpcd[25025]: red0: sending DISCOVER (xid 0xc795297f), next in 3.8 seconds
Jul 16 01:05:29 ipfire dhcpcd[25025]: red0: sending DISCOVER (xid 0xc795297f), next in 8.4 seconds
Jul 16 01:05:29 ipfire dhcpcd[25025]: red0: offered 91.137.x.x from 10.211.x.x
Jul 16 01:05:29 ipfire dhcpcd[25025]: red0: sending REQUEST (xid 0xc795297f), next in 3.5 seconds
Jul 16 01:05:30 ipfire dhcpcd[25025]: red0: acknowledged 91.137.x.x from 10.211.x.x
Jul 16 01:05:30 ipfire dhcpcd[25025]: red0: probing address 91.137.x.x/24
Jul 16 01:05:30 ipfire dhcpcd[25025]: red0: probing for 91.137.x.x
Jul 16 01:05:30 ipfire dhcpcd[25025]: red0: ARP probing 91.137.x.x (1 of 3), next in 1.5 seconds
Jul 16 01:05:31 ipfire dhcpcd[25025]: red0: ARP probing 91.137.x.x (2 of 3), next in 1.1 seconds
Jul 16 01:05:32 ipfire dhcpcd[25025]: red0: ARP probing 91.137.x.x (3 of 3), next in 2.0 seconds
Jul 16 01:05:34 ipfire dhcpcd[25025]: red0: DAD completed for 91.137.x.x
Jul 16 01:05:34 ipfire dhcpcd[25025]: red0: leased 91.137.x.x for 178270 seconds
Jul 16 01:05:34 ipfire dhcpcd[25025]: red0: renew in 89135 seconds, rebind in 89135 seconds
Jul 16 01:05:34 ipfire dhcpcd[25025]: red0: writing lease `/var/ipfire/dhcpc/red0.lease'
Jul 16 01:05:34 ipfire dhcpcd[25025]: red0: adding IP address 91.137.x.x/24 broadcast 91.137.x.x
Jul 16 01:05:34 ipfire dhcpcd[25025]: red0: adding route to 91.137.x.x/24
Jul 16 01:05:34 ipfire dhcpcd[25025]: red0: adding default route via 91.137.x.x
Jul 16 01:05:34 ipfire dhcpcd[25025]: red0: ARP announcing 91.137.x.x (1 of 2), next in 2.0 seconds
Jul 16 01:05:34 ipfire dhcpcd[25025]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' BOUND
Jul 16 01:05:35 ipfire dhcpcd.exe[25920]: red0 has been (re)configured with IP=91.137.x.x
Jul 16 01:06:01 ipfire dhcpcd[25025]: forking to background
Jul 16 01:06:01 ipfire dhcpcd[27093]: red0: ARP announcing 91.137.x.x (2 of 2)
Jul 16 01:06:01 ipfire dhcpcd[25025]: forked to background, child pid 27093
Viele Grüße

JoeBar
Posts: 17
Joined: February 2nd, 2013, 11:32 am

Re: Ipfire Update 131 - Einwahlproblem

Post by JoeBar » July 18th, 2019, 5:50 am

Hallo Arne,
hat leider nichts gebracht den Link aus red.up zu löschen. Heute morgen hing wieder der dhcpcd und ließ sich nur wieder mit killall zum Neustart überreden, weil wahrscheinlich ipv4ll ja eine Adresse vergeben hatte.

Log von heute Nacht:
Jul 18 01:05:01 ipfire dhcpcd[19762]: sending signal ALRM to pid 17202
Jul 18 01:05:01 ipfire dhcpcd[19762]: waiting for pid 17202 to exit
Jul 18 01:05:01 ipfire dhcpcd[17202]: received SIGALRM, releasing
Jul 18 01:05:01 ipfire dhcpcd[17202]: red0: removing interface
Jul 18 01:05:01 ipfire dhcpcd[17202]: red0: releasing lease of 5.10.x.x
Jul 18 01:05:01 ipfire dhcpcd[17202]: red0: sending RELEASE with xid 0x3e3fc2d7
Jul 18 01:05:01 ipfire dhcpcd[17202]: red0: deleting IP address 5.10.x.x/25
Jul 18 01:05:01 ipfire dhcpcd[17202]: red0: deleting route to 5.10.x.x/25
Jul 18 01:05:01 ipfire dhcpcd[17202]: red0: deleting default route via 5.10.x.x
Jul 18 01:05:01 ipfire dhcpcd[17202]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' STOP
Jul 18 01:05:01 ipfire dhcpcd.exe[19763]: red0 has been brought down
Jul 18 01:05:08 ipfire dhcpcd[17202]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' STOPPED
Jul 18 01:05:08 ipfire dhcpcd[17202]: dhcpcd exited
Jul 18 01:05:10 ipfire dhcpcd[20329]: dhcpcd-7.2.2 starting
Jul 18 01:05:10 ipfire dhcpcd[20329]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' PREINIT
Jul 18 01:05:10 ipfire dhcpcd.exe[20330]: red0 has been brought down
Jul 18 01:05:17 ipfire dhcpcd[20329]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' NOCARRIER
Jul 18 01:05:18 ipfire dhcpcd.exe[20757]: red0 has been brought down
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: waiting for carrier
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: carrier acquired
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' CARRIER
Jul 18 01:05:25 ipfire dhcpcd[20329]: DUID 00:04:00:00:00:00:00:00:00:00:00:00:0c:c4:7a:9f:36:b8
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: IAID 7a:9f:36:b9
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: adding address fe80::ec4
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: pltime infinity, vltime infinity
Jul 18 01:05:25 ipfire dhcpcd[20329]: ipv6_addaddr1: Permission denied
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: delaying IPv6 router solicitation for 0.6 seconds
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: delaying IPv4 for 0.2 seconds
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: using hwaddr 0c:c4:7a:
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: soliciting a DHCP lease
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: sending DISCOVER (xid 0x11a26732), next in 4.9 seconds
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: soliciting an IPv6 router
Jul 18 01:05:25 ipfire dhcpcd[20329]: red0: delaying Router Solicitation for LL address
Jul 18 01:05:27 ipfire dhcpcd[20329]: red0: offered 91.137.x.x from 10.211.x.x
Jul 18 01:05:27 ipfire dhcpcd[20329]: red0: sending REQUEST (xid 0x11a26732), next in 3.8 seconds
Jul 18 01:05:30 ipfire dhcpcd[20329]: red0: probing for an IPv4LL address
Jul 18 01:05:30 ipfire dhcpcd[20329]: red0: probing for 169.254.148.39
Jul 18 01:05:30 ipfire dhcpcd[20329]: red0: ARP probing 169.254.148.39 (1 of 3), next in 1.0 seconds
Jul 18 01:05:31 ipfire dhcpcd[20329]: red0: sending REQUEST (xid 0x11a26732), next in 7.3 seconds
Jul 18 01:05:31 ipfire dhcpcd[20329]: red0: ARP probing 169.254.148.39 (2 of 3), next in 1.2 seconds
Jul 18 01:05:31 ipfire dhcpcd[20329]: red0: acknowledged 91.137.x.x from 10.211.x.x
Jul 18 01:05:31 ipfire dhcpcd[20329]: red0: probing address 91.137.x.x/24
Jul 18 01:05:31 ipfire dhcpcd[20329]: red0: probing for 91.137.xx.x
Jul 18 01:05:31 ipfire dhcpcd[20329]: red0: ARP probing 91.137.x.x (1 of 3), next in 1.4 seconds
Jul 18 01:05:32 ipfire dhcpcd[20329]: red0: ARP probing 169.254.148.39 (3 of 3), next in 2.0 seconds
Jul 18 01:05:33 ipfire dhcpcd[20329]: red0: ARP probing 91.137.x.x (2 of 3), next in 1.9 seconds
Jul 18 01:05:34 ipfire dhcpcd[20329]: red0: using IPv4LL address 169.254.148.39
Jul 18 01:05:34 ipfire dhcpcd[20329]: red0: adding IP address 169.254.148.39/16 broadcast 169.254.255.255
Jul 18 01:05:34 ipfire dhcpcd[20329]: red0: adding route to 169.254.0.0/16
Jul 18 01:05:34 ipfire dhcpcd[20329]: red0: adding default route
Jul 18 01:05:34 ipfire dhcpcd[20329]: red0: ARP announcing 169.254.148.39 (1 of 2), next in 2.0 seconds
Jul 18 01:05:34 ipfire dhcpcd[20329]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' IPV4LL
Jul 18 01:05:34 ipfire dhcpcd.exe[21202]: red0 has been brought down
Jul 18 01:05:41 ipfire dhcpcd[20329]: forking to background
Jul 18 01:05:41 ipfire dhcpcd[21629]: red0: ARP probing 91.137.x.x (3 of 3), next in 2.0 seconds
Jul 18 01:05:41 ipfire dhcpcd[20329]: forked to background, child pid 21629
Jul 18 01:05:41 ipfire dhcpcd[21629]: red0: ARP announcing 169.254.148.39 (2 of 2)
Jul 18 01:05:43 ipfire dhcpcd[21629]: red0: DAD completed for 91.137.x.x
Jul 18 01:05:43 ipfire dhcpcd[21629]: red0: leased 91.137.x.x for 175562 seconds
Jul 18 01:05:43 ipfire dhcpcd[21629]: red0: renew in 87781 seconds, rebind in 87781 seconds
Jul 18 01:05:43 ipfire dhcpcd[21629]: red0: writing lease `/var/ipfire/dhcpc/red0.lease'
Jul 18 01:05:43 ipfire dhcpcd[21629]: red0: adding IP address 91.137.x.x/24 broadcast 91.137.x.x
Jul 18 01:05:43 ipfire dhcpcd[21629]: red0: adding route to 91.137.x.x/24
Jul 18 01:05:43 ipfire dhcpcd[21629]: red0: changing default route via 91.137.x.x
Jul 18 01:05:43 ipfire dhcpcd[21629]: red0: ARP announcing 91.137.x.x (1 of 2), next in 2.0 seconds
Jul 18 01:05:43 ipfire dhcpcd[21629]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' BOUND
Jul 18 01:05:43 ipfire dhcpcd.exe[21688]: red0 has been (re)configured with IP=91.137.x.x
Jul 18 01:06:10 ipfire dhcpcd[21629]: red0: deleting IP address 169.254.148.39/16
Jul 18 01:06:10 ipfire dhcpcd[21629]: red0: deleting route to 169.254.0.0/16
Jul 18 01:06:10 ipfire dhcpcd[21629]: red0: executing `/var/ipfire/dhcpc/dhcpcd-run-hooks' IPV4LL
Jul 18 01:06:10 ipfire dhcpcd.exe[22859]: red0 has been brought down
Jul 18 01:06:18 ipfire dhcpcd[21629]: red0: ARP announcing 91.137.x.x (2 of 2)
Ich werde jetzt wieder auf meine dhcpcd.conf umstellen und weiter testen.

Viele Grüße

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

Re: Ipfire Update 131 - Einwahlproblem

Post by Arne.F » July 18th, 2019, 5:57 am

Ich hoffe du hast nicht wirkich die Datei in red.up gelöscht. (die in red.down muss weg)

Ob das für das ipv4ll problem hilft weis ich auch nicht es ging mir um den hänger des dhcpcd beim beenden (das macht er beim connscheduler reconnect)
bei mir Versucht er komischerweise auch nicht mal ne ipv4ll zu bekommen wenn der dhcp server nicht antwortet. (der parameter in der conf kann aber nicht schaden.)
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.

Post Reply