Unable to get network interface working

Unable to get network interface working

[Enrico Sau]

[-- Attachment #1: Type: text/plain, Size: 2082 bytes --]

Dear all,
I'm using oFono 1.15 on Ubuntu 14.4 with telit UE910 modem.
OFono is able to detect the modem but for some reason The network interface
never gets an IP resulting in an immediate detach from the network.
Here is the syslog:

ofonod[2841]:
drivers/atmodem/gprs-context.c[247]:at_gprs_activate_primary() cid 1
ofonod[2841]: drivers/atmodem/gprs-context.c[200]:at_cgdcont_cb() ok 1
ofonod[2841]: drivers/atmodem/gprs-context.c[176]:at_cgdata_cb() ok 1
ofonod[2841]: drivers/atmodem/gprs-context.c[141]:setup_ppp()
ofonod[2841]: drivers/atmodem/gprs-context.c[86]:ppp_connect()
ofonod[2841]: IP: 37.182.26.98
ofonod[2841]: DNS: 83.224.70.54, 83.224.70.77
ofonod[2841]: src/gprs.c[889]:pri_activate_callback() 0x8609670
NetworkManager[937]: <info> (/he910_0): IPv4 static configuration:
NetworkManager[937]: <info> (/he910_0): device state change: prepare ->
failed (reason 'ip-config-unavailable') [40 120 5]
NetworkManager[937]: <warn> Activation (/he910_0) failed for connection
'/222016503456238/context1'
NetworkManager[937]:    SCPlugin-Ifupdown: devices added (path:
/sys/devices/virtual/net/ppp0, iface: ppp0)
NetworkManager[937]:    SCPlugin-Ifupdown: device added (path:
/sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration
found.
NetworkManager[937]: <warn> /sys/devices/virtual/net/ppp0: couldn't
determine device driver; ignoring...
NetworkManager[937]: <info> (/he910_0): device state change: failed ->
disconnected (reason 'none') [120 30 0]
NetworkManager[937]: <info> (/he910_0): deactivating device (reason 'none')
[0]
ofonod[2841]: src/gprs.c[1286]:pri_set_property() deactivate_primary
ofonod[2841]:
drivers/atmodem/gprs-context.c[339]:at_gprs_deactivate_primary() cid 1
NetworkManager[937]:    SCPlugin-Ifupdown: devices removed (path:
/sys/devices/virtual/net/ppp0, iface: ppp0)
ofonod[2841]: plugins/udevng.c[976]:remove_device()
/sys/devices/virtual/net/ppp0
ofonod[2841]: drivers/atmodem/gprs-context.c[109]:ppp_disconnect() Reason: 6

Does anyone know what I'm missing?
Thank you!

Enrico

[-- Attachment #2: attachment.html --]
[-- Type: text/html, Size: 3410 bytes --]

Re: Unable to get network interface working

[Denis Kenzior]

[-- Attachment #1: Type: text/plain, Size: 2376 bytes --]

Hi Enrico,

On 09/25/2014 09:25 AM, Enrico Sau wrote:
> Dear all,
> I'm using oFono 1.15 on Ubuntu 14.4 with telit UE910 modem.
> OFono is able to detect the modem but for some reason The network
> interface never gets an IP resulting in an immediate detach from the
> network.
> Here is the syslog:
>
> ofonod[2841]:
> drivers/atmodem/gprs-context.c[247]:at_gprs_activate_primary() cid 1
> ofonod[2841]: drivers/atmodem/gprs-context.c[200]:at_cgdcont_cb() ok 1
> ofonod[2841]: drivers/atmodem/gprs-context.c[176]:at_cgdata_cb() ok 1
> ofonod[2841]: drivers/atmodem/gprs-context.c[141]:setup_ppp()
> ofonod[2841]: drivers/atmodem/gprs-context.c[86]:ppp_connect()
> ofonod[2841]: IP: 37.182.26.98
> ofonod[2841]: DNS: 83.224.70.54, 83.224.70.77
> ofonod[2841]: src/gprs.c[889]:pri_activate_callback() 0x8609670

Everything looks fine on the oFono end...

You can add AT command debugging to see what might be going wrong.  See 
HACKING for details.

> NetworkManager[937]: <info> (/he910_0): IPv4 static configuration:
> NetworkManager[937]: <info> (/he910_0): device state change: prepare ->
> failed (reason 'ip-config-unavailable') [40 120 5]
> NetworkManager[937]: <warn> Activation (/he910_0) failed for connection
> '/222016503456238/context1'
> NetworkManager[937]:    SCPlugin-Ifupdown: devices added (path:
> /sys/devices/virtual/net/ppp0, iface: ppp0)
> NetworkManager[937]:    SCPlugin-Ifupdown: device added (path:
> /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration
> found.
> NetworkManager[937]: <warn> /sys/devices/virtual/net/ppp0: couldn't
> determine device driver; ignoring...
> NetworkManager[937]: <info> (/he910_0): device state change: failed ->
> disconnected (reason 'none') [120 30 0]
> NetworkManager[937]: <info> (/he910_0): deactivating device (reason
> 'none') [0]

Sounds like NetworkManager is confused...

> ofonod[2841]: src/gprs.c[1286]:pri_set_property() deactivate_primary
> ofonod[2841]:
> drivers/atmodem/gprs-context.c[339]:at_gprs_deactivate_primary() cid 1
> NetworkManager[937]:    SCPlugin-Ifupdown: devices removed (path:
> /sys/devices/virtual/net/ppp0, iface: ppp0)
> ofonod[2841]: plugins/udevng.c[976]:remove_device()
> /sys/devices/virtual/net/ppp0
> ofonod[2841]: drivers/atmodem/gprs-context.c[109]:ppp_disconnect() Reason: 6
>

Regards,
-Denis

Re: Unable to get network interface working

[Enrico Sau]

[-- Attachment #1: Type: text/plain, Size: 3118 bytes --]

Hi Denis,
thanx for the quick answer.
This is the log including AT commands:

ofonod[14630]: Modem: < AT+CGDATA="PPP",1\r
ofonod[14630]: Modem: < \r\nCONNECT\r\n
ofonod[14630]: drivers/atmodem/gprs-context.c[176]:at_cgdata_cb() ok 1
ofonod[14630]: drivers/atmodem/gprs-context.c[141]:setup_ppp()
ofonod[14630]: Aux: < \r\n
ofonod[14630]: Aux: < +CRSM: 106,130\r\n\r\nOK\r\n
ofonod[14630]: Aux: > AT+CRSM=192,28613\r
ofonod[14630]: Aux: < \r\n
ofonod[14630]: Aux: < +CRSM: 106,130\r\n\r\nOK\r\n
ofonod[14630]: src/simfs.c[446]:sim_fs_op_read_block() bufoff: 0, seekoff:
39, toread: 17
ofonod[14630]: Aux: > AT+CRSM=192,28437\r
ofonod[14630]: Aux: < \r\n
ofonod[14630]: Aux: < +CRSM: 106,130\r\n\r\nOK\r\n
ofonod[14630]: Aux: < \r\n
ofonod[14630]: Aux: < +CIEV: rssi,1\r\n
ofonod[14630]: src/network.c[1536]:ofono_netreg_strength_notify() strength
20
ofonod[14630]: drivers/atmodem/gprs-context.c[86]:ppp_connect()
ofonod[14630]: IP: 109.113.70.48
ofonod[14630]: DNS: 83.224.70.77, 83.224.70.54
ofonod[14630]: src/gprs.c[889]:pri_activate_callback() 0x859e348
NetworkManager[876]: <info> (/he910_0): IPv4 static configuration:
NetworkManager[876]: <info> (/he910_0): device state change: prepare ->
failed (reason 'ip-config-unavailable') [40 120 5]
NetworkManager[876]: <info> NetworkManager state is now DISCONNECTED
NetworkManager[876]: <warn> Activation (/he910_0) failed for connection
'/222016503456238/context1'
NetworkManager[876]:    SCPlugin-Ifupdown: devices added (path:
/sys/devices/virtual/net/ppp0, iface: ppp0)
NetworkManager[876]:    SCPlugin-Ifupdown: device added (path:
/sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration
found.
NetworkManager[876]: <warn> /sys/devices/virtual/net/ppp0: couldn't
determine device driver; ignoring...
NetworkManager[876]: <info> (/he910_0): device state change: failed ->
disconnected (reason 'none') [120 30 0]
NetworkManager[876]: <info> (/he910_0): deactivating device (reason 'none')
[0]
ofonod[14630]:
drivers/atmodem/gprs-context.c[328]:at_gprs_deactivate_primary() cid 1
NetworkManager[876]:    SCPlugin-Ifupdown: devices removed (path:
/sys/devices/virtual/net/ppp0, iface: ppp0)
ofonod[14630]: plugins/udevng.c[976]:remove_device()
/sys/devices/virtual/net/ppp0
ofonod[14630]: Aux: < \r\n
ofonod[14630]: drivers/atmodem/gprs-context.c[109]:ppp_disconnect() Reason:
6
ofonod[14630]: Aux: < +CIEV: rssi,2\r\n
ofonod[14630]: src/network.c[1536]:ofono_netreg_strength_notify() strength
40
NetworkManager[876]: <info> (/he910_0): IPv4 static configuration:
NetworkManager[876]: modem_prepare_result: assertion 'state ==
NM_DEVICE_STATE_PREPARE' failed
ofonod[14630]: plugins/udevng.c[1292]:check_modem_list()
ofonod[14630]: Aux: < \r\n+CGEV: ME DEACT IP, "109.113.70.48",
1\r\n\r\n+CGREG: 0\r\n\r\n+CGEV: NW DETACH\r\n
ofonod[14630]: src/gprs.c[2223]:ofono_gprs_status_notify() /he910_0 status 0
ofonod[14630]: src/gprs.c[2209]:ofono_gprs_detached_notify() /he910_0
NetworkManager[876]: <info> WWAN now disabled by management service

Hope you see someting.

Regards,
Enrico

[-- Attachment #2: attachment.html --]
[-- Type: text/html, Size: 5158 bytes --]

Re: Unable to get network interface working

[Denis Kenzior]

[-- Attachment #1: Type: text/plain, Size: 1514 bytes --]

Hi Enrico,

On 09/25/2014 09:58 AM, Enrico Sau wrote:
> Hi Denis,
> thanx for the quick answer.
> This is the log including AT commands:
>
> ofonod[14630]: Modem: < AT+CGDATA="PPP",1\r
> ofonod[14630]: Modem: < \r\nCONNECT\r\n
> ofonod[14630]: drivers/atmodem/gprs-context.c[176]:at_cgdata_cb() ok 1
> ofonod[14630]: drivers/atmodem/gprs-context.c[141]:setup_ppp()
> ofonod[14630]: Aux: < \r\n
> ofonod[14630]: Aux: < +CRSM: 106,130\r\n\r\nOK\r\n
> ofonod[14630]: Aux: > AT+CRSM=192,28613\r
> ofonod[14630]: Aux: < \r\n
> ofonod[14630]: Aux: < +CRSM: 106,130\r\n\r\nOK\r\n
> ofonod[14630]: src/simfs.c[446]:sim_fs_op_read_block() bufoff: 0,
> seekoff: 39, toread: 17
> ofonod[14630]: Aux: > AT+CRSM=192,28437\r
> ofonod[14630]: Aux: < \r\n
> ofonod[14630]: Aux: < +CRSM: 106,130\r\n\r\nOK\r\n
> ofonod[14630]: Aux: < \r\n
> ofonod[14630]: Aux: < +CIEV: rssi,1\r\n
> ofonod[14630]: src/network.c[1536]:ofono_netreg_strength_notify()
> strength 20
> ofonod[14630]: drivers/atmodem/gprs-context.c[86]:ppp_connect()
> ofonod[14630]: IP: 109.113.70.48
> ofonod[14630]: DNS: 83.224.70.77, 83.224.70.54
> ofonod[14630]: src/gprs.c[889]:pri_activate_callback() 0x859e348

oFono side looks good to me.

> NetworkManager[876]: <info> (/he910_0): IPv4 static configuration:
> NetworkManager[876]: <info> (/he910_0): device state change: prepare ->
> failed (reason 'ip-config-unavailable') [40 120 5]

Figure out why this happens.  Are you running everything as root?

Regards,
-Denis

Re: Unable to get network interface working

[Enrico Sau]

[-- Attachment #1: Type: text/plain, Size: 562 bytes --]

2014-09-25 17:07 GMT+02:00 Denis Kenzior <denkenz@gmail.com>:

>
> oFono side looks good to me.
>
>  NetworkManager[876]: <info> (/he910_0): IPv4 static configuration:
>> NetworkManager[876]: <info> (/he910_0): device state change: prepare ->
>> failed (reason 'ip-config-unavailable') [40 120 5]
>>
>
> Figure out why this happens.  Are you running everything as root?
>
>
​Yes, I suppose.
I'm running ofono daemon as root ​. It is the only thing I'm running.
​I'll try to figure out the ip-config-unavailable.

Thanx​

​Enrico​

[-- Attachment #2: attachment.html --]
[-- Type: text/html, Size: 1783 bytes --]

Re: Unable to get network interface working

[Enrico Sau]

[-- Attachment #1: Type: text/plain, Size: 751 bytes --]

Hi Denis, hi all,

I finally got the network interface working by uninstalling the network
manager and manually setting the ppp0 interface with parameters retrieved
from the modem.

I have another problem:
I start the network interface with activate-context python script, and stop
with deactivate-context script.
Once I call deactivate-context, I'm no more able to activate it because ppp
disconnect function calls io_disconnect method, which clears the at channel.
The only way to resume the ppp connection is to disable and re-enable the
modem in order fot the at channel structures to be filled in again.

Is there any reason for that? Is deactivate-context the correct way to
temporary stop the network?

Thank you.


Enrico

[-- Attachment #2: attachment.html --]
[-- Type: text/html, Size: 1710 bytes --]

Re: Unable to get network interface working

[Denis Kenzior]

[-- Attachment #1: Type: text/plain, Size: 1662 bytes --]

Hi Enrico,

On 10/03/2014 07:40 AM, Enrico Sau wrote:
> Hi Denis, hi all,
>
> I finally got the network interface working by uninstalling the network
> manager and manually setting the ppp0 interface with parameters
> retrieved from the modem.
>

good

> I have another problem:
> I start the network interface with activate-context python script, and
> stop with deactivate-context script.
> Once I call deactivate-context, I'm no more able to activate it because
> ppp disconnect function calls io_disconnect method, which clears the at
> channel.
> The only way to resume the ppp connection is to disable and re-enable
> the modem in order fot the at channel structures to be filled in again.
>

This is a known issue on some hardware, or may be an issue with the 
modem driver for the hardware you are using.  First some background:

Whenever PPP is disconnected, there are several ways to indicate a hangup:
   1. Send an escape sequence to AT mode, followed by 'NO CARRIER'
   2. Send a low level serial line hang-up

Many modems do both, which results in the tty port being 'closed'.  This 
is why you see the ppp_disconnect being triggered.

oFono understands escape sequences, so #1 is sufficient.  On most 
hardware we can send AT&C0 to disable #2, but some hardware still 
ignores that.

Hardware that uses a high-speed interface (most anything released in the 
last few years) doesn't suffer from this issue.

> Is there any reason for that? Is deactivate-context the correct way to
> temporary stop the network?
>

Yes, that is the correct way, assuming your hardware is well behaved.

Regards,
-Denis

Re: Unable to get network interface working

[Enrico Sau]

[-- Attachment #1: Type: text/plain, Size: 346 bytes --]

>
> oFono understands escape sequences, so #1 is sufficient.  On most hardware
> we can send AT&C0 to disable #2, but some hardware still ignores that.
>
> ​
Thank you so much,
I solved the problem changing the port from "chat" to "modem" for the AT&C0
command in the he910 plugin.
It may be a fix for ofono.

Regards
Enrico Sau​

[-- Attachment #2: attachment.html --]
[-- Type: text/html, Size: 1262 bytes --]

Re: Unable to get network interface working

[Denis Kenzior]

[-- Attachment #1: Type: text/plain, Size: 510 bytes --]

Hi Enrico,

On 10/08/2014 09:50 AM, Enrico Sau wrote:
>     oFono understands escape sequences, so #1 is sufficient.  On most
>     hardware we can send AT&C0 to disable #2, but some hardware still
>     ignores that.
>
> ​
> Thank you so much,
> I solved the problem changing the port from "chat" to "modem" for the
> AT&C0 command in the he910 plugin.
> It may be a fix for ofono.
>

Feel free to send a patch if you think that the port detection logic is 
incorrect.

Regards,
-Denis