Armadilloフォーラム

IPアドレスが取得できない問題

mcrest_kozawa

2023年3月15日 23時08分

DHCPに設定しても、固定IPアドレスに設定してもネットワークインターフェースがDown状態となります。
/var/log/messagesには以下のメッセージが出るのですが対処方法おわかりでしょうか。
--
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.3541] policy: auto-activating connection 'ethernet-eth0'
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.3633] device (eth0): Activation: starting connection 'ethernet-eth0' (15d88435-fadd-4904-91e4-1ed49b00a29c)
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.3657] device (eth0): state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.3701] device (eth0): state change: prepare -> config (reason 'none') [40 50 0]
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.3770] device (eth0): state change: config -> ip-config (reason 'none') [50 70 0]
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.3800] dhcp4 (eth0): activation: beginning transaction (timeout in 45 seconds)
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.3891] dhcp4 (eth0): dhclient started with pid 2520
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.4900] dhcp4 (eth0): address 192.168.102.49
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.4908] dhcp4 (eth0): plen 24 (255.255.255.0)
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.4910] dhcp4 (eth0): gateway 192.168.102.1
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.4910] dhcp4 (eth0): server identifier 192.168.2.189
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.4912] dhcp4 (eth0): lease time 10800
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.4913] dhcp4 (eth0): nameserver '192.168.2.140'
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.4913] dhcp4 (eth0): nameserver '192.168.2.141'
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.4914] dhcp4 (eth0): state changed unknown -> bound
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.5040] device (eth0): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.5068] device (eth0): Activation: failed for connection 'ethernet-eth0'
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.5212] device (eth0): state change: failed -> disconnected (reason 'none') [120 30 0]
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.5886] dhcp4 (eth0): canceled DHCP transaction, DHCP client pid 2520
Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.5887] dhcp4 (eth0): state changed bound -> done
--

コメント

mcrest_kozawa

2023年3月15日 23時08分

対象機器ですが、 G3L
になります。

at_dominique.m…

2023年3月16日 9時42分

mcrest_kozawa さん、

お世話になっています、
アットマークテクノのマルティネです。

> Mar 15 07:02:50 hoge NetworkManager[763]: [1661551370.5040] device (eth0): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]

IP を取得した後に何らかの理由で失敗していますね。
その理由を分かるためにログを少し増やしてもらえたら助かります。

/etc/NetworkManager/NetworkManager.conf に以下の内容を追加して、systemctl restart NetworkManager か再起動で適用された後にもう一度試してログを提供していただけますか?

[logging]
level=TRACE

よろしくお願いします。

mcrest_kozawa

2023年3月17日 16時19分

頂いた内容を設定してみました。
以下ログになります。

Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.0963] policy: auto-activating connection 'ethernet-eth0'
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.1060] device (eth0): Activation: starting connection 'ethernet-eth0' (15d88435-fadd-4904-91e4-1ed49b00a29c)
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.1081] device (eth0): state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.1125] device (eth0): state change: prepare -> config (reason 'none') [40 50 0]
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.1210] device (eth0): state change: config -> ip-config (reason 'none') [50 70 0]
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.1238] dhcp4 (eth0): activation: beginning transaction (timeout in 45 seconds)
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.1332] dhcp4 (eth0): dhclient started with pid 12005
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2567] dhcp4 (eth0): address 192.168.102.49
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2568] dhcp4 (eth0): plen 24 (255.255.255.0)
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2569] dhcp4 (eth0): gateway 192.168.102.1
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2569] dhcp4 (eth0): server identifier 192.168.2.189
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2571] dhcp4 (eth0): lease time 10800
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2572] dhcp4 (eth0): nameserver '192.168.2.140'
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2573] dhcp4 (eth0): nameserver '192.168.2.141'
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2574] dhcp4 (eth0): state changed unknown -> bound
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2812] device (eth0): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2842] device (eth0): Activation: failed for connection 'ethernet-eth0'
Mar 17 09:37:46 hoge NetworkManager[10881]: [1679013466.2913] device (eth0): state change: failed -> disconnected (reason 'none') [120 30 0]
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.3345] dhcp4 (eth0): canceled DHCP transaction, DHCP client pid 12005
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.3346] dhcp4 (eth0): state changed bound -> done
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.3740] policy: auto-activating connection 'ethernet-eth0'
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.3841] device (eth0): Activation: starting connection 'ethernet-eth0' (15d88435-fadd-4904-91e4-1ed49b00a29c)
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.3863] device (eth0): state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.3918] device (eth0): state change: prepare -> config (reason 'none') [40 50 0]
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.4231] device (eth0): state change: config -> ip-config (reason 'none') [50 70 0]
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.4295] dhcp4 (eth0): activation: beginning transaction (timeout in 45 seconds)
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.4510] dhcp4 (eth0): dhclient started with pid 12014
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.6229] dhcp4 (eth0): address 192.168.102.49
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.6230] dhcp4 (eth0): plen 24 (255.255.255.0)
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.6231] dhcp4 (eth0): gateway 192.168.102.1
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.6231] dhcp4 (eth0): server identifier 192.168.2.189
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.6233] dhcp4 (eth0): lease time 10800
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.6234] dhcp4 (eth0): nameserver '192.168.2.140'
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.6235] dhcp4 (eth0): nameserver '192.168.2.141'
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.6236] dhcp4 (eth0): state changed unknown -> bound
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.7096] device (eth0): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.7171] policy: disabling autoconnect for connection 'ethernet-eth0'.
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.7197] device (eth0): Activation: failed for connection 'ethernet-eth0'
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.7336] device (eth0): state change: failed -> disconnected (reason 'none') [120 30 0]
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.7900] dhcp4 (eth0): canceled DHCP transaction, DHCP client pid 12014
Mar 17 09:37:47 hoge NetworkManager[10881]: [1679013466.7903] dhcp4 (eth0): state changed bound -> done
Mar 17 09:37:53 hoge NetworkManager[10881]: [1679013473.3082] keyfile: update /etc/NetworkManager/system-connections/ethernet-eth0 (15d88435-fadd-4904-91e4-1ed49b00a29c,"ethernet-eth0")
Mar 17 09:37:53 hoge NetworkManager[10881]: [1679013473.3121] audit: op="connection-update" uuid="15d88435-fadd-4904-91e4-1ed49b00a29c" name="ethernet-eth0" pid=12044 uid=0 result="success"
Mar 17 09:37:53 hoge NetworkManager[10881]: [1679013473.7015] keyfile: update /etc/NetworkManager/system-connections/ethernet-eth0 (15d88435-fadd-4904-91e4-1ed49b00a29c,"ethernet-eth0")
Mar 17 09:37:53 hoge NetworkManager[10881]: [1679013473.7055] audit: op="connection-update" uuid="15d88435-fadd-4904-91e4-1ed49b00a29c" name="ethernet-eth0" pid=12050 uid=0 result="success"
Mar 17 09:37:54 hoge NetworkManager[10881]: [1679013474.1019] keyfile: update /etc/NetworkManager/system-connections/ethernet-eth0 (15d88435-fadd-4904-91e4-1ed49b00a29c,"ethernet-eth0")
Mar 17 09:37:54 hoge NetworkManager[10881]: [1679013474.1099] audit: op="connection-update" uuid="15d88435-fadd-4904-91e4-1ed49b00a29c" name="ethernet-eth0" pid=12054 uid=0 result="success"
Mar 17 09:37:54 hoge NetworkManager[10881]: [1679013474.5084] keyfile: update /etc/NetworkManager/system-connections/ethernet-eth0 (15d88435-fadd-4904-91e4-1ed49b00a29c,"ethernet-eth0")
Mar 17 09:37:54 hoge NetworkManager[10881]: [1679013474.5159] audit: op="connection-update" uuid="15d88435-fadd-4904-91e4-1ed49b00a29c" name="ethernet-eth0" pid=12058 uid=0 result="success"

mcrest_kozawa

2023年3月17日 16時22分

/var/log/syslogに
以下ログが出ていました。
Mar 17 06:28:19 kurokogear NetworkManager[13332]: [1679002099.9458] platform-linux: do-add-ip4-route[2: 192.168.0.0/16 100]: failure 101 (Network is unreachable)
Mar 17 06:28:19 kurokogear NetworkManager[13332]: [1679002099.9463] platform-linux: do-add-ip4-route[2: 192.168.2.0/24 100]: failure 101 (Network is unreachable)

静的ルート設定が正しくなく設定に失敗しているから、eth0のインターフェースがDownしてしまうのでしょうか。

at_dominique.m…

2023年3月17日 16時59分

mcrest_kozawaさん

マルティネです。

> Mar 17 06:28:19 kurokogear NetworkManager[13332]: [1679002099.9458] platform-linux: do-add-ip4-route[2: 192.168.0.0/16 100]: failure 101 (Network is unreachable)
> Mar 17 06:28:19 kurokogear NetworkManager[13332]: [1679002099.9463] platform-linux: do-add-ip4-route[2: 192.168.2.0/24 100]: failure 101 (Network is unreachable)
>
> 静的ルート設定が正しくなく設定に失敗しているから、eth0のインターフェースがDownしてしまうのでしょうか。

追加のログありがとうございます。これですね。
https://bugzilla.redhat.com/show_bug.cgi?id=1428334 によると、前の NetworkManager のバージョンではこういう失敗を無視していましたが、今では失敗してネットワークが down されます。

Linux では、ルートの next hop (gateway) にすでにルートがない限りそのルートを追加できません。おそらくこの問題だと思いますので、設定を見直せば解決されると思います。
できなかったらコンフィグ(かそれに似たような物)を提供してくだされば具体的な助言はできるかもしれません。

よろしくお願いします。

mcrest_kozawa

2023年3月21日 15時42分

お世話になります。

設定ファイルを確認したところ、静的ルート設定が残っていたためそちらの削除を行い、
nmtuiから再活性してみましたが、うまくいかず、再起動することでeth0が正常に活性化することを確認できました。

ありがとうございました。