Send connman mailing list submissions to
connman@lists.01.org
To subscribe or unsubscribe via email, send a message with subject or
body 'help' to
connman-requ...@lists.01.org
You can reach the person managing the list at
connman-ow...@lists.01.org
When replying, please edit your Subject line so it is more specific
than "Re: Contents of connman digest..."
Today's Topics:
1. Re: Unreliable 4G LTE interface IP address (Daniel Wagner)
2. Re: Unreliable 4G LTE interface IP address (Jupiter)
3. Re: Unreliable 4G LTE interface IP address (Daniel Wagner)
4. Re: Unreliable 4G LTE interface IP address (Jupiter)
----------------------------------------------------------------------
Date: Wed, 30 Dec 2020 10:04:35 +0100
From: Daniel Wagner <w...@monom.org>
Subject: Re: Unreliable 4G LTE interface IP address
To: Jupiter <jupiter....@gmail.com>
Cc: connman <connman@lists.01.org>
Message-ID: <20201230090435.oopdul3owsixd...@beryllium.lan>
Content-Type: text/plain; charset=us-ascii
Hi,
On Wed, Dec 30, 2020 at 01:15:17PM +1100, Jupiter wrote:
> Before running CONNMAN_DHCP_DEBUG=1 connmand -n -d
> src/dhcp.c:src/network.c, I did a a debug to examine connman logs,
The logs don't contain the debug output. Only the info output. There
should be a lot more log entries.
> Here is the procedures I was doing debug:
>
> - Connect LTE antenna, connected
>
> - Disconnect LTE antenna, lost LTE IP
>
> - Connect LTE antenna, resumed
>
> - Repeat above until no IP
When it is working, this log pattern repeats?
connmand[2375]: ipconfig state 3 ipconfig method 1
connmand[2375]: wwan0 {add} address 10.114.169.197/30 label wwan0 family 2
connmand[2375]: wwan0 {add} route 10.114.169.196 gw 0.0.0.0 scope 253 <LINK>
connmand[2375]: wwan0 {add} route 10.114.169.198 gw 0.0.0.0 scope 253 <LINK>
connmand[2375]: wwan0 {add} route 10.4.130.164 gw 10.114.169.198 scope 0
<UNIVERSE>
connmand[2375]: wwan0 {add} route 10.4.58.204 gw 10.114.169.198 scope 0
<UNIVERSE>
connmand[2375]: wwan0 {add} route 0.0.0.0 gw 10.114.169.198 scope 0 <UNIVERSE>
connmand[2375]: wwan0 {add} route 212.227.81.55 gw 10.114.169.198 scope 0
<UNIVERSE>
connmand[2375]: wwan0 {del} route 212.227.81.55 gw 10.114.169.198 scope 0
<UNIVERSE>
connmand[2375]: wwan0 {del} route 0.0.0.0 gw 10.114.169.198 scope 0 <UNIVERSE>
connmand[2375]: wwan0 {del} route 10.4.130.164 gw 10.114.169.198 scope 0
<UNIVERSE>
connmand[2375]: wwan0 {del} route 10.4.58.204 gw 10.114.169.198 scope 0
<UNIVERSE>
connmand[2375]: wwan0 {del} route 10.114.169.198 gw 0.0.0.0 scope 253 <LINK>
connmand[2375]: wwan0 {del} address 10.114.169.197/30 label wwan0
connmand[2375]: wwan0 {del} route 10.114.169.196 gw 0.0.0.0 scope 253 <LINK>
This is triggered by RTNL message by the kernel. They are the result of
ConnMan configuring the wwan0 interface. The lack of it, just says,
ConnMan didn't configure the interface.
Maybe also add plugins/ofono.c to the debug list filter.
CONNMAN_DHCP_DEBUG=1 connmand -n -d src/dhpc.c:src/network.c:plugins/ofono.c
Daniel
------------------------------
Date: Wed, 30 Dec 2020 20:42:59 +1100
From: Jupiter <jupiter....@gmail.com>
Subject: Re: Unreliable 4G LTE interface IP address
To: Daniel Wagner <w...@monom.org>
Cc: connman <connman@lists.01.org>
Message-ID:
<CAA=hcwsls3ufcbbalirfubysbd4ockkbjev3-yvwnueb6ds...@mail.gmail.com>
Content-Type: text/plain; charset="UTF-8"
Hi Daniel,
> The logs don't contain the debug output. Only the info output. There
> should be a lot more log entries.
Hmm, that might be caused by running the following command in a shell
script in systemd service, the reason I had to run it in systemd
service because I could not get faulty occurred when I run following
command in console command line, running in command line seems much
stable than to run it in systemd service.
CONNMAN_DHCP_DEBUG=1 connmand -n -d
src/dhpc.c:src/network.c:plugins/ofono.c > /data/log/connman.log 2>&1
> When it is working, this log pattern repeats?
Yes, here is I run tail -f /data/log/connman.log:
Disconnect antenna (No IP)
[14138.869278] ieee80211 phy0: mwifiex_cfg80211_sched_scan_start : Invalid Sched
_scan parameters
connmand[1272]: wwan0 {del} route 0.0.0.0 gw 10.116.101.194 scope 0 <UNIVERSE>
connmand[1272]: wwan0 {del} route 10.4.149.70 gw 10.116.101.194 scope 0 <UNIVERS
E>
connmand[1272]: wwan0 {del} route 10.4.27.70 gw 10.116.101.194 scope 0 <UNIVERSE
>
connmand[1272]: wwan0 {del} route 10.116.101.194 gw 0.0.0.0 scope 253 <LINK>
connmand[1272]: wwan0 {del} address 10.116.101.193/30 label wwan0
connmand[1272]: wwan0 {del} route 10.116.101.192 gw 0.0.0.0 scope 253 <LINK>
Connect antenna
connmand[1272]: ipconfig state 3 ipconfig method 1
connmand[1272]: wwan0 {add} address 10.116.101.193/30 label wwan0 family 2
connmand[1272]: wwan0 {add} route 10.116.101.192 gw 0.0.0.0 scope 253 <LINK>
connmand[1272]: wwan0 {add} route 10.116.101.194 gw 0.0.0.0 scope 253 <LINK>
connmand[1272]: wwan0 {add} route 10.4.149.70 gw 10.116.101.194 scope 0 <UNIVERS
E>
connmand[1272]: wwan0 {add} route 10.4.27.70 gw 10.116.101.194 scope 0 <UNIVERSE
>
connmand[1272]: wwan0 {add} route 0.0.0.0 gw 10.116.101.194 scope 0 <UNIVERSE>
connmand[1272]: wwan0 {del} route 0.0.0.0 gw 10.116.101.194 scope 0 <UNIVERSE>
connmand[1272]: wwan0 {del} route 10.4.149.70 gw 10.116.101.194 scope 0 <UNIVERS
E>
connmand[1272]: wwan0 {del} route 10.4.27.70 gw 10.116.101.194 scope 0 <UNIVERSE
>
connmand[1272]: wwan0 {del} route 10.116.101.194 gw 0.0.0.0 scope 253 <LINK>
connmand[1272]: wwan0 {del} address 10.116.101.193/30 label wwan0
connmand[1272]: wwan0 {del} route 10.116.101.192 gw 0.0.0.0 scope 253 <LINK>
connmand[1272]: ipconfig state 3 ipconfig method 1
connmand[1272]: wwan0 {add} address 10.116.101.193/30 label wwan0 family 2
connmand[1272]: wwan0 {add} route 10.116.101.192 gw 0.0.0.0 scope 253 <LINK>
connmand[1272]: wwan0 {add} route 10.116.101.194 gw 0.0.0.0 scope 253 <LINK>
connmand[1272]: wwan0 {add} route 10.4.149.70 gw 10.116.101.194 scope 0 <UNIVERS
E>
connmand[1272]: wwan0 {add} route 10.4.27.70 gw 10.116.101.194 scope 0 <UNIVERSE
>
connmand[1272]: wwan0 {add} route 0.0.0.0 gw 10.116.101.194 scope 0 <UNIVERSE>
connmand[1272]: wwan0 {add} route 212.227.81.55 gw 10.116.101.194 scope 0 <UNIVE
RSE>
connmand[1272]: wwan0 {del} route 212.227.81.55 gw 10.116.101.194 scope 0 <UNIVE
RSE>
Repeated ... until no IP address comming, there was no more output of
above messages either, looks like some connman polling or callback
process stopped despite connmand was still running:
# systemctl status connman
* connman.service - Connection service
Loaded: loaded (8;;file://solar/lib/systemd/system/connman.service/lib/system
d/system/connman.service8;;; enabled; vendor preset: enabled)
Active: active (running) since Wed 2020-12-30 09:15:32 UTC; 14min ago
Main PID: 1271 (sa_connman_debu)
CGroup: /system.slice/connman.service
|-1271 /bin/sh /data/bin/sa_connman_debug.sh
`-1272 connmand -n -d src/dhpc.c:src/network.c:plugins/ofono.c
Dec 30 09:26:37 solar connmand[1272]: wwan0 {add} route 10.4.27.70 gw 10.116.101
.194 scope 0 <UNIVERSE>
Dec 30 09:26:37 solar connmand[1272]: wwan0 {add} route 0.0.0.0 gw 10.116.101.19
4 scope 0 <UNIVERSE>
Dec 30 09:26:39 solar connmand[1272]: wwan0 {add} route 212.227.81.55 gw 10.116.
101.194 scope 0 <UNIVERSE>
Dec 30 09:26:41 solar connmand[1272]: wwan0 {del} route 212.227.81.55 gw 10.116.
101.194 scope 0 <UNIVERSE>
Dec 30 09:29:39 solar connmand[1272]: wwan0 {del} route 0.0.0.0 gw 10.116.101.19
4 scope 0 <UNIVERSE>
Dec 30 09:29:39 solar connmand[1272]: wwan0 {del} route 10.4.149.70 gw 10.116.10
1.194 scope 0 <UNIVERSE>
Dec 30 09:29:39 solar connmand[1272]: wwan0 {del} route 10.4.27.70 gw 10.116.101
.194 scope 0 <UNIVERSE>
Dec 30 09:29:39 solar connmand[1272]: wwan0 {del} route 10.116.101.194 gw 0.0.0.
0 scope 253 <LINK>
Dec 30 09:29:39 solar connmand[1272]: wwan0 {del} address 10.116.101.193/30 labe
l wwan0
Dec 30 09:29:39 solar connmand[1272]: wwan0 {del} route 10.116.101.192 gw 0.0.0.
0 scope 253 <LINK>
Thank you.
Kind regards,
Jupiter
------------------------------
Date: Wed, 30 Dec 2020 13:42:07 +0100
From: Daniel Wagner <w...@monom.org>
Subject: Re: Unreliable 4G LTE interface IP address
To: Jupiter <jupiter....@gmail.com>
Cc: connman <connman@lists.01.org>
Message-ID: <20201230124207.lfm42uhzcu26t...@beryllium.lan>
Content-Type: text/plain; charset=us-ascii
On Wed, Dec 30, 2020 at 08:42:59PM +1100, Jupiter wrote:
> CONNMAN_DHCP_DEBUG=1 connmand -n -d
> src/dhpc.c:src/network.c:plugins/ofono.c > /data/log/connman.log 2>&1
Sorry, still no debug output log.
------------------------------
Date: Thu, 31 Dec 2020 07:27:23 +1100
From: Jupiter <jupiter....@gmail.com>
Subject: Re: Unreliable 4G LTE interface IP address
To: Daniel Wagner <w...@monom.org>
Cc: connman <connman@lists.01.org>
Message-ID:
<CAA=hcwtkxydfkf+deizcn40vzyskiw2u3jv0msh+ou02kmd...@mail.gmail.com>
Content-Type: text/plain; charset="UTF-8"
Hi Daniel,
> Sorry, still no debug output log.
OK, I am running the connmand debug manually in command line, it is
the same debug messages and it is able to be reproduced, the connmand
works many loops for disconnecting antenna, then it finally stopped to
poll or to call DHCP, there is no IP address now but the connmand is
still running. Here is whole debug from the console screen:
# CONNMAN_DHCP_DEBUG=1 connmand -n -d src/dhpc.c:src/net
work.c:plugins/ofono.c
connmand[3135]: Connection Manager version 1.37
connmand[3135]: Adding configuration wifi
connmand[3135]: Ignore group named 'global' because prefix is not 'service_'
connmand[3135]: Adding service configuration wifi
connmand[3135]: Adding configuration cellular
connmand[3135]: Ignore group named 'global' because prefix is not 'service_'
connmand[3135]: Adding service configuration cellular
connmand[3135]: Checking loopback interface settings
connmand[3135]: System hostname is solar
connmand[3135]: lo {newlink} index 1 address 00:00:00:00:00:00 mtu 65536
connmand[3135]: lo {newlink} index 1 operstate 0 <UNKNOWN>
connmand[3135]: sit0 {newlink} index 2 address 00:00:00:00:08:00 mtu 1480
connmand[3135]: sit0 {newlink} index 2 operstate 2 <DOWN>
connmand[3135]: mlan0 {create} index 3 type 1 <ETHER>
connmand[3135]: mlan0 {RX} 14 packets 2382 bytes
connmand[3135]: mlan0 {TX} 33 packets 4316 bytes
connmand[3135]: mlan0 {update} flags 36867 <UP>
connmand[3135]: mlan0 {newlink} index 3 address D4:CA:6E:9A:7E:53 mtu 1500
connmand[3135]: mlan0 {newlink} index 3 operstate 2 <DOWN>
connmand[3135]: Adding interface mlan0 [ wifi ]
connmand[3135]: wwan0 {create} index 4 type 65534 <NONE>
connmand[3135]: wwan0 {RX} 30 packets 2572 bytes
connmand[3135]: wwan0 {TX} 36 packets 2699 bytes
connmand[3135]: wwan0 {update} flags 4240 <DOWN>
connmand[3135]: wwan0 {newlink} index 4 address 00:00:00:00:00:00 mtu 1500
connmand[3135]: wwan0 {newlink} index 4 operstate 2 <DOWN>
connmand[3135]: mlan0 {RX} 14 packets 2382 bytes
connmand[3135]: mlan0 {TX} 33 packets 4316 bytes
connmand[3135]: mlan0 {newlink} index 3 address D4:CA:6E:9A:7E:53 mtu 1500
connmand[3135]: mlan0 {newlink} index 3 operstate 2 <DOWN>
[ 275.244356] mwifiex_sdio mmc0:0001:1: info: trying to associate to 'JupiterIo
T' bssid 34:08:04:12:b1:a2
[ 275.300753] mwifiex_sdio mmc0:0001:1: info: associated to bssid 34:08:04:12:b
1:a2 successfully
connmand[3135]: mlan0 {RX} 15 packets 2495 bytes
connmand[3135]: mlan0 {TX} 33 packets 4316 bytes
connmand[3135]: mlan0 {update} flags 102403 <UP,LOWER_UP>
connmand[3135]: mlan0 {newlink} index 3 address D4:CA:6E:9A:7E:53 mtu 1500
connmand[3135]: mlan0 {newlink} index 3 operstate 5 <DORMANT>
[ 275.378601] IPv6: ADDRCONF(NETDEV_CHANGE): mlan0: link becomes ready
connmand[3135]: mlan0 {add} route ff00:: gw :: scope 0 <UNIVERSE>
connmand[3135]: mlan0 {add} route fe80:: gw :: scope 0 <UNIVERSE>
connmand[3135]: mlan0 {RX} 16 packets 2712 bytes
connmand[3135]: mlan0 {TX} 35 packets 4564 bytes
connmand[3135]: mlan0 {update} flags 102467 <UP,RUNNING,LOWER_UP>
connmand[3135]: mlan0 {newlink} index 3 address D4:CA:6E:9A:7E:53 mtu 1500
co[ 275.447911] mwifiex_sdio mmc0:0001:1: CMD_RESP: cmd 0x23f error, result=0x2
nnmand[3135]: mlan0 {newlink} index 3 operstate 6 <UP>
connmand[3135]: DHCP index 3: switch listening mode (0 ==> 1)
connmand[3135]: DHCP index 3: sending DHCP discover request
connmand[3135]: DHCP index 3: received DHCP packet xid 0xf5970486 (current state
0)
connmand[3135]: DHCP index 3: init ip 192.168.0.101 secs 0 -> broadcast flag off
connmand[3135]: DHCP index 3: start request (retries 0)
connmand[3135]: DHCP index 3: sending DHCP request (state 2)
connmand[3135]: DHCP index 3: received DHCP packet xid 0xf5970486 (current state
2)
connmand[3135]: DHCP index 3: switch listening mode (1 ==> 0)
connmand[3135]: DHCP index 3: start bound
connmand[3135]: DHCP index 3: processed DHCP packet (new state 3)
connmand[3135]: mlan0 {add} address 192.168.0.101/24 label mlan0 family 2
connmand[3135]: mlan0 {add} route 192.168.0.0 gw 0.0.0.0 scope 253 <LINK>
connmand[3135]: mlan0 {add} route 192.168.0.1 gw 0.0.0.0 scope 253 <LINK>
connmand[3135]: mlan0 {add} route 0.0.0.0 gw 192.168.0.1 scope 0 <UNIVERSE>
connmand[3135]: mlan0 {add} route 212.227.81.55 gw 192.168.0.1 scope 0 <UNIVERSE
>
connmand[3135]: wwan0 {RX} 30 packets 2572 bytes
connmand[3135]: wwan0 {TX} 36 packets 2699 bytes
connmand[3135]: wwan0 {update} flags 69841 <UP,RUNNING,LOWER_UP>
connmand[3135]: wwan0 {newlink} index 4 address 00:00:00:00:00:00 mtu 1500
connmand[3135]: wwan0 {newlink} index 4 operstate 0 <UNKNOWN>
connmand[3135]: wwan0 {add} route ff00:: gw :: scope 0 <UNIVERSE>
connmand[3135]: wwan0 {add} route fe80:: gw :: scope 0 <UNIVERSE>
connmand[3135]: rp_filter set to 2 (loose mode routing), old value was 2
connmand[3135]: ipconfig state 3 ipconfig method 1
connmand[3135]: wwan0 {add} address 10.114.11.88/28 label wwan0 family 2
connmand[3135]: wwan0 {add} route 10.114.11.80 gw 0.0.0.0 scope 253 <LINK>
connmand[3135]: wwan0 {add} route 10.114.11.89 gw 0.0.0.0 scope 253 <LINK>
connmand[3135]: wwan0 {add} route 10.4.130.164 gw 10.114.11.89 scope 0 <UNIVERSE
>
connmand[3135]: wwan0 {add} route 10.4.58.204 gw 10.114.11.89 scope 0 <UNIVERSE>
connmand[3135]: wwan0 {del} route fe80:: gw :: scope 0 <UNIVERSE>
connmand[3135]: wwan0 {del} route ff00:: gw :: scope 0 <UNIVERSE>
connmand[3135]: wwan0 {add} route 212.227.81.55 gw 10.114.11.89 scope 0 <UNIVERS
E>
connmand[3135]: mlan0 {del} route 212.227.81.55 gw 192.168.0.1 scope 0 <UNIVERSE
>
connmand[3135]: wwan0 {del} route 212.227.81.55 gw 10.114.11.89 scope 0 <UNIVERS
E>
[ 371.772925] mwifiex_sdio mmc0:0001:1: CMD_RESP: cmd 0x23f error, result=0x2
Disconnect antenna
connmand[3135]: rp_filter restored to 2
connmand[3135]: wwan0 {del} route 10.4.130.164 gw 10.114.11.89 scope 0 <UNIVERSE
>
connmand[3135]: wwan0 {del} route 10.4.58.204 gw 10.114.11.89 scope 0 <UNIVERSE>
connmand[3135]: wwan0 {del} route 10.114.11.89 gw 0.0.0.0 scope 253 <LINK>
connmand[3135]: wwan0 {del} address 10.114.11.88/28 label wwan0
connmand[3135]: wwan0 {del} route 10.114.11.80 gw 0.0.0.0 scope 253 <LINK>
Connect antenna
connmand[3135]: rp_filter set to 2 (loose mode routing), old value was 2
connmand[3135]: ipconfig state 3 ipconfig method 1
connmand[3135]: wwan0 {add} address 10.114.11.88/28 label wwan0 family 2
connmand[3135]: wwan0 {add} route 10.114.11.80 gw 0.0.0.0 scope 253 <LINK>
connmand[3135]: wwan0 {add} route 10.114.11.89 gw 0.0.0.0 scope 253 <LINK>
connmand[3135]: wwan0 {add} route 10.4.130.164 gw 10.114.11.89 scope 0 <UNIVERSE
>
connmand[3135]: wwan0 {add} route 10.4.58.204 gw 10.114.11.89 scope 0 <UNIVERSE>
connmand[3135]: wwan0 {add} route 212.227.81.55 gw 10.114.11.89 scope 0 <UNIVERS
E>
connmand[3135]: wwan0 {del} route 212.227.81.55 gw 10.114.11.89 scope 0 <UNIVERS
E>
Disconnect antenna
connmand[3135]: rp_filter restored to 2
connmand[3135]: wwan0 {del} route 10.4.130.164 gw 10.114.11.89 scope 0 <UNIVERSE
>
connmand[3135]: wwan0 {del} route 10.4.58.204 gw 10.114.11.89 scope 0 <UNIVERSE>
connmand[3135]: wwan0 {del} route 10.114.11.89 gw 0.0.0.0 scope 253 <LINK>
connmand[3135]: wwan0 {del} address 10.114.11.88/28 label wwan0
connmand[3135]: wwan0 {del} route 10.114.11.80 gw 0.0.0.0 scope 253 <LINK>
[ 612.404088] mwifiex_sdio mmc0:0001:1: CMD_RESP: cmd 0x23f error, result=0x2
Repeat .......
Connect antenna
It stopped working, no IP address and no more messages coming, but the
connmand is still running, I think that is the main issue some DHCP
polling process stopped.
~# ps | grep connmand
3135 root 8552 S connmand -n -d
src/dhpc.c:src/network.c:plugins/ofono.c
Does it give some clues?
Thank you.
Kind regards,
Jupiter
------------------------------
Subject: Digest Footer
_______________________________________________
connman mailing list -- connman@lists.01.org
To unsubscribe send an email to connman-le...@lists.01.org
------------------------------
End of connman Digest, Vol 62, Issue 33
***************************************
