NetworkManager の不要なログを止めたい

Contents

1 事象
- 1.1 /var/log/syslog
2 原因
3 対策
- 3.1 回避策
- 3.2 解決策
4 トラブルシューティング
5 参考

事象

NetworkManage がリンクダウンしている NIC インターフェースがあると auto-activation connection ポリシーが実行され以下のようなログが延々と出力されるためこれを止めたい。
確認した環境は Lubuntu 16.04 ですが他の Linux ディストリビューションでも同様のログが出力されるようです。

/var/log/syslog

Feb 25 16:49:34 hostname NetworkManager[954]:   [1488008974.6095] policy: auto-activating connection 'Wired connection 1'
Feb 25 16:49:34 hostname NetworkManager[954]:   [1488008974.6104] device (eth1): Activation: starting connection 'Wired connection 1' (444133fc-8c57-3c6a-bd30-ddd39f087f49)
Feb 25 16:49:34 hostname NetworkManager[954]:   [1488008974.6106] device (eth1): state change: disconnected -> prepare (reason 'none') [30 40 0]
Feb 25 16:49:34 hostname NetworkManager[954]:   [1488008974.6111] device (eth1): state change: prepare -> config (reason 'none') [40 50 0]
Feb 25 16:49:34 hostname NetworkManager[954]:   [1488008974.6126] device (eth1): state change: config -> ip-config (reason 'none') [50 70 0]
Feb 25 16:49:34 hostname NetworkManager[954]:   [1488008974.6130] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds)
Feb 25 16:49:34 hostname NetworkManager[954]:   [1488008974.6173] dhcp4 (eth1): dhclient started with pid 3878
Feb 25 16:49:34 hostname dhclient[3878]: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 3 (xid=0x5588cc06)
Feb 25 16:49:35 hostname avahi-daemon[846]: Joining mDNS multicast group on interface eth1.IPv6 with address fe80::a2e9:1173:bde2:141a.
Feb 25 16:49:35 hostname avahi-daemon[846]: New relevant interface eth1.IPv6 for mDNS.
Feb 25 16:49:35 hostname avahi-daemon[846]: Registering new address record for fe80::a2e9:1173:bde2:141a on eth1.*.
Feb 25 16:49:37 hostname dhclient[3878]: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 8 (xid=0x5588cc06)
Feb 25 16:49:53 hostname dhclient[3878]: message repeated 2 times: [ DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 8 (xid=0x5588cc06)]
Feb 25 16:50:01 hostname dhclient[3878]: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 20 (xid=0x5588cc06)
Feb 25 16:50:19 hostname NetworkManager[954]:   [1488009019.5813] dhcp4 (eth1): request timed out
Feb 25 16:50:19 hostname NetworkManager[954]:   [1488009019.5814] dhcp4 (eth1): state changed unknown -> timeout
Feb 25 16:50:19 hostname NetworkManager[954]:   [1488009019.5976] dhcp4 (eth1): canceled DHCP transaction, DHCP client pid 3878
Feb 25 16:50:19 hostname NetworkManager[954]:   [1488009019.5977] dhcp4 (eth1): state changed timeout -> done
Feb 25 16:50:19 hostname NetworkManager[954]:   [1488009019.5980] device (eth1): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
Feb 25 16:50:19 hostname NetworkManager[954]:   [1488009019.5982] policy: disabling autoconnect for connection 'Wired connection 1'.
Feb 25 16:50:19 hostname NetworkManager[954]:   [1488009019.5984] device (eth1): Activation: failed for connection 'Wired connection 1'
Feb 25 16:50:19 hostname NetworkManager[954]:   [1488009019.5990] device (eth1): state change: failed -> disconnected (reason 'none') [120 30 0]
Feb 25 16:50:19 hostname avahi-daemon[846]: Withdrawing address record for fe80::a2e9:1173:bde2:141a on eth1.
Feb 25 16:50:19 hostname avahi-daemon[846]: Leaving mDNS multicast group on interface eth1.IPv6 with address fe80::a2e9:1173:bde2:141a.
Feb 25 16:50:19 hostname avahi-daemon[846]: Interface eth1.IPv6 no longer relevant for mDNS.

原因

複数ポートを持つ NIC を利用していていずれかのポートが断線している状態のため。この例では Dual ポートの NIC を利用していて eth0 のみリンクアップしている状態です。
つまり、複数ポートを持つ NIC の場合、断線していても他のポートが利用できる状態のため厳密にはリンクダウンにはならない（少なくとも、ethtool で確認した限りではリンクアップ状態）ので繰り返し IP を取得しようとしています。シングルポートで発生している場合は、DHCP での IP 取得がうまくいっていないことが考えられます。

# ethtool eth1
Settings for eth1:
        Supported ports: [ TP ]
        Supported link modes:   10baseT/Half 10baseT/Full 
                                100baseT/Half 100baseT/Full 
                                1000baseT/Full 
        Supported pause frame use: No
        Supports auto-negotiation: Yes
        Advertised link modes:  10baseT/Half 10baseT/Full 
                                100baseT/Half 100baseT/Full 
                                1000baseT/Full 
        Advertised pause frame use: No
        Advertised auto-negotiation: Yes
        Speed: 1000Mb/s
        Duplex: Full
        Port: Twisted Pair
        PHYAD: 0
        Transceiver: internal
        Auto-negotiation: on
        MDI-X: off (auto)
        Supports Wake-on: d
        Wake-on: d
        Current message level: 0x00000007 (7)
                               drv probe link
        Link detected: yes

対策

eth1 が DHCP で IP を取得しようとしている状態のためこれを停止させる。

# nmcli device status
DEVICE  TYPE      STATE                                  CONNECTION         
eth1    ethernet  connecting (getting IP configuration)  Wired connection 1 
eth0    ethernet  unmanaged                              --                 
lo      loopback  unmanaged                              --

回避策

NetworkManager を停止し、自動起動しない（利用しない）ようにする。

# sudo systemctl stop NetworkManager
# sudo systemctl disable NetworkManager
Removed symlink /etc/systemd/system/dbus-org.freedesktop.nm-dispatcher.service.
Removed symlink /etc/systemd/system/multi-user.target.wants/NetworkManager.service.

解決策

断線しているポートの接続状態を回復させる。シングルポートの NIC ならば固定で IP を付与するか DHCP の設定を見直す。

トラブルシューティング

nmcli connection show で eth1 の UUID を確認して停止させる方法は複数ポートの NIC では解決しなかった。

# nmcli c show
NAME                UUID                                  TYPE            DEVICE 
Wired connection 1  444133fc-8c57-3c6a-bd30-ddd39f087f49  802-3-ethernet  eth1   
正常NIC                  af71c40b-3aac-4514-826e-aa418d67d707  802-3-ethernet  --     

# nmcli c down 444133fc-8c57-3c6a-bd30-ddd39f087f49
Error: '444133fc-8c57-3c6a-bd30-ddd39f087f49' is not an active connection.
Error: no active connection provided.