[dhcpcd] pas de connexion depuis la dernière maj (résolu)

[ourobolos]

Bonjour

Depuis la maj d'hier (5.5.4-1) j'ai mes deux PC sous arch qui n'arrivent plus à obtenir d'IP en ethernet ; si je downgrade le package ça fonctionne.

Une idée de pourquoi, quelles infos supplémentaires, où rapporter un éventuel bug ?

merci d'avance

[widapit]

Salut !

Je suppose que tu as essayé en lançant dhcpcd en ligne de commande ...
Le retour de cette commande ainsi que le type de matériel doit être intéressant à joindre au rapport si tu décide d'en faire un;
il faut que tu passes par ce lien pour ça.

[benjarobin]

Je ne sais pas si c'est une bonne idée de le poster ici, problème identique ? J'ai aussi un problème avec Wicd pour obtenir une IP mais pas de problème en ligne de commande.
La sortie des log avant et après.
Sortie qui ne fonctionne pas ? Bizarre Wicd fermerait dhcpcd car celui-ci disparaît des processus dès qu'il a obtenu une IP...

Code : Tout sélectionner

2012/02/14 21:02:20 :: Setting false IP...
2012/02/14 21:02:20 :: Stopping wpa_supplicant
2012/02/14 21:02:20 :: Flushing the routing table...
2012/02/14 21:02:20 :: Putting interface up...
2012/02/14 21:02:22 :: Running DHCP with hostname benjarobin-fixe
2012/02/14 21:02:22 :: dhcpcd[6206]: version 5.5.4 starting
2012/02/14 21:02:22 :: 
2012/02/14 21:02:22 :: dhcpcd[6206]: eth0: waiting for carrier
2012/02/14 21:02:22 :: 
2012/02/14 21:02:23 :: dhcpcd[6206]: eth0: carrier acquired
2012/02/14 21:02:23 :: 
2012/02/14 21:02:23 :: dhcpcd[6206]: eth0: sending IPv6 Router Solicitation
2012/02/14 21:02:23 :: 
2012/02/14 21:02:23 :: dhcpcd[6206]: eth0: sendmsg: Cannot assign requested address
2012/02/14 21:02:23 :: 
2012/02/14 21:02:23 :: dhcpcd[6206]: eth0: rebinding lease of 192.168.0.9
2012/02/14 21:02:23 :: 
2012/02/14 21:02:23 :: dhcpcd[6206]: eth0: acknowledged 192.168.0.9 from 192.168.0.254
2012/02/14 21:02:23 :: 
2012/02/14 21:02:23 :: dhcpcd[6206]: eth0: checking for 192.168.0.9
2012/02/14 21:02:23 :: 
2012/02/14 21:02:24 :: dhcpcd[6206]: eth0: Router Advertisement from fe80::xxxx:xxx:xxx:xxx # Censuré :-)
2012/02/14 21:02:24 :: 
2012/02/14 21:02:24 :: dhcpcd[6206]: forked to background, child pid 6242
2012/02/14 21:02:24 :: 
2012/02/14 21:02:24 :: 
2012/02/14 21:02:24 :: DHCP connection successful
2012/02/14 21:02:24 :: Connecting thread exiting.
2012/02/14 21:02:24 :: Sending connection attempt result success

Sortie avec l'ancienne version pour comparer :

Code : Tout sélectionner

2012/02/14 21:06:04 :: Setting false IP...
2012/02/14 21:06:04 :: Stopping wpa_supplicant
2012/02/14 21:06:04 :: Flushing the routing table...
2012/02/14 21:06:04 :: Putting interface up...
2012/02/14 21:06:06 :: Running DHCP with hostname benjarobin-fixe
2012/02/14 21:06:06 :: dhcpcd[6502]: version 5.2.9 starting
2012/02/14 21:06:06 :: 
2012/02/14 21:06:06 :: dhcpcd[6502]: eth0: waiting for carrier
2012/02/14 21:06:06 :: 
2012/02/14 21:06:06 :: dhcpcd[6502]: eth0: carrier acquired
2012/02/14 21:06:06 :: 
2012/02/14 21:06:06 :: dhcpcd[6502]: eth0: broadcasting for a lease
2012/02/14 21:06:06 :: 
2012/02/14 21:06:07 :: dhcpcd[6502]: eth0: offered 192.168.0.9 from 192.168.0.254
2012/02/14 21:06:07 :: 
2012/02/14 21:06:07 :: dhcpcd[6502]: eth0: acknowledged 192.168.0.9 from 192.168.0.254
2012/02/14 21:06:07 :: 
2012/02/14 21:06:07 :: dhcpcd[6502]: eth0: checking for 192.168.0.9
2012/02/14 21:06:07 :: 
2012/02/14 21:06:12 :: dhcpcd[6502]: eth0: leased 192.168.0.9 for 43200 seconds
2012/02/14 21:06:12 :: 
2012/02/14 21:06:12 :: dhcpcd[6502]: forked to background, child pid 6542
2012/02/14 21:06:12 :: 
2012/02/14 21:06:12 :: 
2012/02/14 21:06:12 :: DHCP connection successful
2012/02/14 21:06:12 :: Connecting thread exiting.
2012/02/14 21:06:12 :: Sending connection attempt result success

Pour information la sortie en ligne de commande affiche les même choses...

[ourobolos]

c'est avec wicd aussi pour mon cas

Code : Tout sélectionner

$dhcpcd
dhcpcd[876]: version 5.5.4 starting
dhcpcd[876]: wlan0: up_interface: Operation not possible due to RF-kill
dhcpcd[876]: eth0: sending IPv6 Router Solicitation
dhcpcd[876]: eth0: broadcasting for a lease
dhcpcd[876]: wlan0: waiting for carrier
dhcpcd[876]: eth0: Router Advertisement from fe80::207:cbff:fed1:dfd1
dhcpcd[876]: forked to background, child pid 913

ensuite j'obtiens bien mon ip et pas de déconnexion

[benjarobin]

C'est un bug de wicd, j'ai bricolé le code python de wicd et ce la fonctionne ? J'ai juste rajouté un sleep dans une fonction, avec un peu d'intinct...
Donc je vais essayer de créer un rapport de bug upstream

Edit: Ok j'ai trouvé la raison Cela vient de dhcpcd qui met bien 4 secondes avant de définir l’adresse IP alors que celle-ci est bien trouvé et affiché par lui même...

[ourobolos]

après test ça fonctionne sur mon netbook mais pas sur mon fixe, je reste sur l'ancienne version sur cette machine

[benjarobin]

Voila ce que j'ai fait pour détecter le problème :

Code : Tout sélectionner

dhcpcd -d eth0; for i in {0..8}; do (echo "$i : $(ifconfig eth0 | grep 'inet 1')"; sleep 1;) done;

La sortie

Code : Tout sélectionner

dhcpcd[8989]: version 5.5.4 starting
dhcpcd[8989]: eth0: using hwaddr 48:5b:xx:xx:xx:xx
dhcpcd[8989]: eth0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks', reason PREINIT
dhcpcd[8989]: eth0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks', reason CARRIER
dhcpcd[8989]: eth0: sending IPv6 Router Solicitation
dhcpcd[8989]: eth0: reading lease `/var/lib/dhcpcd/dhcpcd-eth0.lease'
dhcpcd[8989]: eth0: rebinding lease of 192.168.0.9
dhcpcd[8989]: eth0: sending REQUEST (xid 0x87761ee1), next in 3.20 seconds
dhcpcd[8989]: eth0: Router Advertisement from fe80::xxxx:xxxx:xxxx:xxxx
dhcpcd[8989]: eth0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks', reason ROUTERADVERT
dhcpcd[8989]: forking to background
dhcpcd[8989]: forked to background, child pid 9016
0 : 
1 : 
2 : 
3 : 
4 : 
5 :         inet 192.168.0.9  netmask 255.255.255.0  broadcast 192.168.0.255
6 :         inet 192.168.0.9  netmask 255.255.255.0  broadcast 192.168.0.255
7 :         inet 192.168.0.9  netmask 255.255.255.0  broadcast 192.168.0.255
8 :         inet 192.168.0.9  netmask 255.255.255.0  broadcast 192.168.0.255

On constate qu'il met bien 4 grosses secondes pour que ifconfig retourne l’adresse ip trouvé par dhcpcd... étrange

Pour information c'est la même chose avec cette commande

Code : Tout sélectionner

dhcpcd -d eth0; for i in {0..8}; do (echo "$i : $(ip addr show eth0 | grep 'inet 1')"; sleep 1;) done;

Pour temporairement corriger le problème, voici un patch :

Code : Tout sélectionner

sed -i "s|self.dhcp_object.wait()|self.dhcp_object.wait()\n        time.sleep(5)|" /usr/lib/wicd/wnettools.py 
cp /usr/lib/wicd/wnettools.py /usr/lib/python2.7/site-packages/wicd/wnettools.py

[flapp]

Joli !

Merci ! (temporairement )

[ourobolos]

j'ai le même résultat avec la commande :

Code : Tout sélectionner

dhcpcd -d eth0; for i in {0..8}; do (echo "$i : $(ifconfig eth0 | grep 'inet 1')"; sleep 1;) done;
dhcpcd: unknown option -- no
dhcpcd[10975]: version 5.5.4 starting
dhcpcd: unknown option -- no
dhcpcd[10975]: eth0: using hwaddr 00:19:66:91:ee:7a
dhcpcd[10975]: eth0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks', reason PREINIT
dhcpcd[10975]: eth0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks', reason CARRIER
dhcpcd[10975]: eth0: sending IPv6 Router Solicitation
dhcpcd[10975]: eth0: broadcasting for a lease
dhcpcd[10975]: eth0: sending DISCOVER (xid 0x3f240235), next in 3.72 seconds
dhcpcd[10975]: eth0: Router Advertisement from fe80::207:cbff:fed1:dfd1
dhcpcd[10975]: eth0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks', reason ROUTERADVERT
dhcpcd[10975]: forking to background
dhcpcd[10975]: forked to background, child pid 11002
0 : 
1 : 
2 : 
3 : 
4 : 
5 :         inet 192.168.0.10  netmask 255.255.255.0  broadcast 192.168.0.255
6 :         inet 192.168.0.10  netmask 255.255.255.0  broadcast 192.168.0.255
7 :         inet 192.168.0.10  netmask 255.255.255.0  broadcast 192.168.0.255
8 :         inet 192.168.0.10  netmask 255.255.255.0  broadcast 192.168.0.255

en revanche le patch n'a pas d'effet, après l'avoir appliqué puis en déconnectant wicd, la reconnexion ne s'effectue pas.

[benjarobin]

Ce n'est pas vraiment un patch, c'est vraiment un contournement très sale.
As tu bien redémarré wicd ? /etc/rc.d/wicd stop; sleep 1; /etc/rc.d/wicd start
Sinon essaye avec un sleep (dans le fichier wnettools.py) un peu plus long, car 5s peut être un peu court, 6s devrait passer

[ourobolos]

effectivement, je n'avais pas redémarré wicd proprement, là ça fonctionne, merci !

[deap]

Désolé de déterrer ce fil, mais ce problème est toujours d'actualité...
Je dois faire l'ajout à chaque mise à jour de wicd...
En tout cas, merci pour l'info.