12:19 p.m.
Dear all,
I'm facing a strange problem regarding the re-enumeration of an ethernet
interface. Please see the following log:
root@my_hostname:/mnt/mmclog# cat log_lte.txt | grep
"daemon.info
connmand"
Jan 1 00:00:05 daemon.info connmand[294]: Connection Manager version
1.28
Jan 1 00:00:06 daemon.info connmand[294]: Checking loopback
interface settings
Jan 1 00:00:06 daemon.info connmand[294]: System hostname is my_hostname
Jan 1 00:00:06 daemon.info connmand[294]: lo {newlink} index 1
address 00:00:00:00:00:00 mtu 65536
Jan 1 00:00:06 daemon.info connmand[294]: lo {newlink} index 1
operstate 0 <UNKNOWN>
Jan 1 00:00:06 daemon.info connmand[294]: sit0 {newlink} index 2
address 00:00:00:00:08:00 mtu 1480
Jan 1 00:00:06 daemon.info connmand[294]: sit0 {newlink} index 2
operstate 2 <DOWN>
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {create} index 5 type
1 <ETHER>
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {update} flags 4098
<DOWN>
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {newlink} index 5
address 00:00:00:01:02:03 mtu 1500
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {newlink} index 5
operstate 2 <DOWN>
Dec 24 22:34:52 daemon.info connmand[294]: *Adding interface eth0 [
ethernet ]*
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {update} flags 102467
<UP,RUNNING,LOWER_UP>
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {newlink} index 5
address 00:00:00:01:02:03 mtu 1500
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {newlink} index 5
operstate 0 <UNKNOWN>
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {add} route ff00:: gw
:: scope 0 <UNIVERSE>
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {add} route fe80:: gw
:: scope 0 <UNIVERSE>
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {update} flags 36867 <UP>
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {newlink} index 5
address 00:00:00:01:02:03 mtu 1500
Dec 24 22:34:52 daemon.info connmand[294]: eth0 {newlink} index 5
operstate 2 <DOWN>
Dec 24 22:34:53 daemon.info connmand[294]: *eth0 {update} flags
102467 <UP,RUNNING,LOWER_UP>*
Dec 24 22:34:53 daemon.info connmand[294]: eth0 {newlink} index 5
address 00:00:00:01:02:03 mtu 1500
Dec 24 22:34:53 daemon.info connmand[294]: eth0 {newlink} index 5
operstate 6 <UP>
Dec 24 22:35:02 daemon.info connmand[294]: *Setting hostname to
my_hostname*
Dec 24 22:35:02 daemon.info connmand[294]: eth0 {add} address
192.168.1.100/24 label eth0 family 2
Dec 24 22:35:02 daemon.info connmand[294]: eth0 {add} route
192.168.1.0 gw 0.0.0.0 scope 253 <LINK>
Dec 24 22:35:02 daemon.info connmand[294]: eth0 {add} route
192.168.1.1 gw 0.0.0.0 scope 253 <LINK>
Dec 24 22:35:02 daemon.info connmand[294]: eth0 {add} route 0.0.0.0
gw 192.168.1.1 scope 0 <UNIVERSE>
Dec 24 22:35:02 daemon.info connmand[294]: eth0 {add} route
82.165.8.211 gw 192.168.1.1 scope 0 <UNIVERSE>
Dec 24 22:35:02 daemon.info connmand[294]: eth0 {del} route
82.165.8.211 gw 192.168.1.1 scope 0 <UNIVERSE>
Dec 24 22:38:26 daemon.info connmand[294]: *(null) {RX} 776 packets
148761 bytes*
Dec 24 22:38:26 daemon.info connmand[294]: *(null) {TX} 825 packets
78648 bytes*
Dec 24 22:38:26 daemon.info connmand[294]: *(null) {update} flags
36866 <DOWN>*
Dec 24 22:38:26 daemon.info connmand[294]: *eth0 {newlink} index 5
address 00:00:00:01:02:03 mtu 1500*
Dec 24 22:38:26 daemon.info connmand[294]: *eth0 {newlink} index 5
operstate 2 <DOWN>*
Dec 24 22:38:27 daemon.info connmand[294]: (null) {del} route fe80::
gw :: scope 0 <UNIVERSE>
Dec 24 22:38:27 daemon.info connmand[294]: (null) {del} route ff00::
gw :: scope 0 <UNIVERSE>
Dec 24 22:38:27 daemon.info connmand[294]: (null) {del} address
192.168.1.100/24 label eth0
Dec 24 22:38:27 daemon.info connmand[294]: eth0 {dellink} index 5
operstate 2 <DOWN>
Dec 24 22:38:27 daemon.info connmand[294]: (null) {RX} 776 packets
148761 bytes
Dec 24 22:38:27 daemon.info connmand[294]: (null) {TX} 825 packets
78648 bytes
Dec 24 22:38:27 daemon.info connmand[294]: (null) {remove} index 5
Dec 24 22:38:27 daemon.info connmand[294]: *Remove interface (null) [
ethernet ]*
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {create} index 6 type
1 <ETHER>
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {update} flags 4098
<DOWN>
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {newlink} index 6
address 00:00:00:01:02:03 mtu 1500
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {newlink} index 6
operstate 2 <DOWN>
Dec 24 22:39:12 daemon.info connmand[294]: *Adding interface eth0 [
ethernet ]*
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {update} flags 102467
<UP,RUNNING,LOWER_UP>
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {newlink} index 6
address 00:00:00:01:02:03 mtu 1500
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {newlink} index 6
operstate 0 <UNKNOWN>
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {add} route ff00:: gw
:: scope 0 <UNIVERSE>
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {add} route fe80:: gw
:: scope 0 <UNIVERSE>
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {update} flags 36867 <UP>
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {newlink} index 6
address 00:00:00:01:02:03 mtu 1500
Dec 24 22:39:12 daemon.info connmand[294]: eth0 {newlink} index 6
operstate 2 <DOWN>
Dec 24 22:39:14 daemon.info connmand[294]: eth0 {update} flags 102467
<UP,RUNNING,LOWER_UP>
Dec 24 22:39:14 daemon.info connmand[294]: eth0 {newlink} index 6
address 00:00:00:01:02:03 mtu 1500
Dec 24 22:39:14 daemon.info connmand[294]: eth0 {newlink} index 6
operstate 6 <UP>
Dec 24 22:39:22 daemon.info connmand[294]: Setting hostname to
my_hostname
Dec 24 22:39:22 daemon.info connmand[294]: eth0 {add} address
192.168.1.100/24 label eth0 family 2
Dec 24 22:39:22 daemon.info connmand[294]: eth0 {add} route
192.168.1.0 gw 0.0.0.0 scope 253 <LINK>
Dec 24 22:39:22 daemon.info connmand[294]: eth0 {add} route
192.168.1.1 gw 0.0.0.0 scope 253 <LINK>
Dec 24 22:39:22 daemon.info connmand[294]: eth0 {add} route 0.0.0.0
gw 192.168.1.1 scope 0 <UNIVERSE>
Dec 24 22:39:22 daemon.info connmand[294]: eth0 {add} route
82.165.8.211 gw 192.168.1.1 scope 0 <UNIVERSE>
Dec 24 22:39:22 daemon.info connmand[294]: eth0 {del} route
82.165.8.211 gw 192.168.1.1 scope 0 <UNIVERSE>
Until 22:35:02 everything goes
fine, eth0 interface is up and running.
After three minutes, at 22:38:26 I see some strange logs. What does
(null) mean? Why the interface is removed and then added again? What can
be the cause of the problem?
Thanks in advance for your time
Regards,
Fabio Emiliani
--
*Fabio Emiliani*
*/Software Engineer/*
/Ph. +39 075 8298 532/
/fabioemiliani(a)artgroup-spa.com/ <mailto:fabioemiliani@artgroup-spa.com>
DISCLAIMER
This email and any attachment may contain confidential information. If
you are not the intended recipient you are not authorised to copy or
disclose all or any part of it without the prior written consent of ART SpA.
/ART SpA – Step Forward With US - //www.artgroup-spa.com/logo_ART_firma-1//
/Ph. +39 075 8298 501 – Fax +39 075 8298 525 /
P*Please consider our environment before printing this e-mail***
3:04 p.m.
Hi Fabio,
On 12/29/2016 12:19 PM, Fabio Emiliani wrote:
> Dec 24 22:38:26 daemon.info connmand[294]: *(null) {RX} 776
packets
> 148761 bytes*
> Dec 24 22:38:26 daemon.info connmand[294]: *(null) {TX} 825 packets
> 78648 bytes*
> Dec 24 22:38:26 daemon.info connmand[294]: *(null) {update} flags
> 36866 <DOWN>*
These message are from ipconfig.c::update_stats(). For some reason we do
not know the ifname anymore. __connman_ipconfig_newlink() lookups
the ifname via SIOCGIFNAME. The kernel just retuns NULL for the given
index. So I guess the mapping has already been removed from the kernel.
This is harmless, because we use only the index inside ConnMan, never
the ifname. It is just used for debugging reasons.
> Dec 24 22:38:26 daemon.info connmand[294]: *eth0 {newlink} index
5
> address 00:00:00:01:02:03 mtu 1500*
> Dec 24 22:38:26 daemon.info connmand[294]: *eth0 {newlink} index 5
> operstate 2 <DOWN>*
The newlink message is issued from __connman_ipconfig_newlink()
function. That means the kernel has send ConnMan a RTNL message (see
process_newlink() in rtnl.c).
Until 22:35:02 everything goes fine, eth0 interface is up and
running.
After three minutes, at 22:38:26 I see some strange logs. What does
(null) mean? Why the interface is removed and then added again? What can
be the cause of the problem?
Usually, ConnMan tries to keep a connection up and running, so I guess
something else is disconnecting the link. Check also if the
hardware/firmware is doing something funny. For example certain WiFi
chips are known to do strange stuff. I assume from the logs you have an
Ethernet interface. If you have a free USB slot you could try to use an
externel USB Ethernet dongle and see if that is observable.
Another thing is I assume you are running ConnMan without the debug
option? Maybe if you turn it on you get more info what is happening (-d
command line option).
Next thing is to check if there is any other networking daemon running.
Check for dhclient, systemd-networkd or something like that.
Hope that helps.
Thanks,
Daniel
1479
days inactive
1481
days old
1 comments
2 participants
participants (2)
-
Daniel Wagner -
Fabio Emiliani