search

ARMmbed / mbed-os-example-cellular

This is an example based on mbed-os cellular APIs that demonstrates a TCP or UDP echo transaction with a public echo server.
Apache License 2.0
21 stars 45 forks source link

Add necessary configs to support the EP_AGORA target #163

Closed trowbridgec closed 5 years ago

trowbridgec commented 5 years ago

This PR adds support for the Embedded Planet EP_AGORA target to this example app.

Relies on https://github.com/ARMmbed/mbed-os/pull/11566

trowbridgec commented 5 years ago

@ARMmbed/team-embeddedplanet @maclobdell

40Grit commented 5 years ago

| So, I guess you could argue we don't need to | remove the CORDIO labels if we're already | removing the BLE feature, but it doesn't hurt.

And if we do need to remove those features here, id say lets make the smallest change possible to keep our UX clean.

trowbridgec commented 5 years ago

@AnttiKauppila I'm not certain why, but the example failed if I didn't enable IPv6. Could be because we set the cellular_properties for the Telit ME910 driver to enable the IPV6 and IPV4V6 stacks (see here):

static const intptr_t cellular_properties[AT_CellularBase::PROPERTY_MAX] = {
    AT_CellularNetwork::RegistrationModeLAC,    // C_EREG
    AT_CellularNetwork::RegistrationModeLAC,    // C_GREG
    AT_CellularNetwork::RegistrationModeLAC,    // C_REG
    0,  // AT_CGSN_WITH_TYPE
    0,  // AT_CGDATA
    1,  // AT_CGAUTH
    1,  // AT_CNMI
    1,  // AT_CSMP
    1,  // AT_CMGF
    1,  // AT_CSDH
    1,  // PROPERTY_IPV4_STACK
    1,  // PROPERTY_IPV6_STACK
    1,  // PROPERTY_IPV4V6_STACK
    0,  // PROPERTY_NON_IP_PDP_TYPE
    1,  // PROPERTY_AT_CGEREP
};

The Telit ME910 module we're using does support IPv6, so I would argue that it doesn't hurt to turn it on for our target.

AnttiKauppila commented 5 years ago

@trowbridgec You should of course enable IPv6 when needed, but your application config is not general for all users. There might be some other AGORA user who needs IPv4 with some different shield. We have chosen IPv4 as a default value for this example application and developers can override that value if needed.

AnttiKauppila commented 5 years ago

Also there is no more FEATURE_LWIP so no need to set "target.features_add". Or are you using some older version of Mbed OS which still has it?

40Grit commented 5 years ago

I would say this PR is a testament to the entire reason this example is now the default Pelion example.

Pending https://github.com/ARMmbed/mbed-os/pull/11566, if this PR is closed and the example builds for EP_AGORA then this has all been an excellent thought experiment.

40Grit commented 5 years ago

Oh this is the cellular example... either way, good thought experiment

trowbridgec commented 5 years ago

@AnttiKauppila Thanks for the hint regarding FEATURE_LWIP. I removed that line, and the example still works.

Regarding the lwip.ipv6-enabled setting, I agree with you that it shouldn't be necessary, but the example does not work if I don't set it to true.

With lwip.ipv6-enabled set to true:

mbed-os-example-cellular

Built: Sep 27 2019, 08:22:47
Establishing connection
........

Connection Established.
TCP: connected with echo.mbedcloudtesting.com server
TCP: Sent 4 Bytes to echo.mbedcloudtesting.com
Received from echo server 4 Bytes

Success. Exiting

Without lwip.ipv6-enabled set to true:

mbed-os-example-cellular

Built: Sep 27 2019, 08:28:25
Establishing connection
........

Connection Established.
Couldn't resolve remote host: echo.mbedcloudtesting.com, code: -3009

Failure. Exiting

Any thoughts as to why that might be happening?

AnttiKauppila commented 5 years ago

@trowbridgec I think that is a real issue because the modem supports both IPv4 and 6 and DNS resolving fails for IPv4. This needs to be investigated @ARMmbed/mbed-os-wan

trowbridgec commented 5 years ago

@AnttiKauppila Can we merge this PR in until the issue was been investigated (then we can remove the setting lwip.ipv6-enabled)?

AriParkkila commented 5 years ago

I think IPv6 should always be enabled on cellular. Can you provide AT debug logs?

trowbridgec commented 5 years ago

@AriParkkila

With lwip.ipv6-enabled set to true:

mbed-os-example-cellular

Built: Sep 27 2019, 11:09:29
Establishing connection
[00000001ms][DBG ][CELL]: Modem flow control: RTS 14 CTS 40
[00000003ms][INFO][CELL]: New CellularContext  (0x2000a300)
[00000004ms][INFO][CELL]: CellularContext connect
[00000006ms][DBG ][CELL]: callback: 4106, err: 0, data: 4096
[00000014ms][INFO][CELL]: Start connecting (timeout 1000 ms)
[00000022ms][INFO][CELL]: AT TX ( 2): AT
[00000029ms][INFO][CELL]: AT TX ( 1): <cr>
[00001023ms][DBG ][CELL]: AT error -3012
[00001023ms][INFO][CELL]: AT TX ( 2): AT
[00001024ms][INFO][CELL]: AT TX ( 1): <cr>
[00002024ms][DBG ][CELL]: AT error -3012
[00002024ms][DBG ][CELL]: Init => Power
[00002025ms][DBG ][CELL]: callback: 4106, err: 0, data: 4096
[00002027ms][INFO][CELL]: Modem power ON (timeout 1000 ms)
[00002028ms][DBG ][CELL]: Power => Device ready
[00002032ms][DBG ][CELL]: callback: 4106, err: 0, data: 4096
[00002041ms][DBG ][CELL]: Device was not ready, calling soft_power_on()
[00027250ms][DBG ][CELL]: AT flush
[00027251ms][INFO][CELL]: AT RX (32): AT<cr><cr><ln>OK<cr><ln><cr><ln>#QSS: 1<cr                                                                                                                                                             ><ln><cr><ln>+PACSP0<cr><ln><cr>
[00027253ms][INFO][CELL]: AT RX (11): <ln>#PSNT: 4<cr><ln>
[00027254ms][INFO][CELL]: AT TX ( 4): ATE0
[00027257ms][INFO][CELL]: AT TX ( 1): <cr>
[00027263ms][INFO][CELL]: AT RX (11): ATE0<cr><cr><ln>OK<cr><ln>
[00027292ms][INFO][CELL]: AT TX ( 9): AT+CMEE=1
[00027293ms][INFO][CELL]: AT TX ( 1): <cr>
[00027299ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027320ms][INFO][CELL]: AT TX ( 9): AT+CFUN=1
[00027321ms][INFO][CELL]: AT TX ( 1): <cr>
[00027327ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027348ms][INFO][CELL]: AT TX (13): AT&K3;&C1;&D0
[00027349ms][INFO][CELL]: AT TX ( 1): <cr>
[00027356ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027377ms][INFO][CELL]: AT TX ( 8): AT#QSS=1
[00027378ms][INFO][CELL]: AT TX ( 1): <cr>
[00027384ms][INFO][CELL]: AT RX ( 4): <cr><ln>OK
[00027385ms][INFO][CELL]: AT RX ( 2): <cr><ln>
[00027406ms][INFO][CELL]: AT TX ( 9): AT#PSNT=1
[00027407ms][INFO][CELL]: AT TX ( 1): <cr>
[00027414ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027435ms][INFO][CELL]: AT TX ( 9): AT+CMER=2
[00027436ms][INFO][CELL]: AT TX ( 1): <cr>
[00027442ms][INFO][CELL]: AT RX ( 4): <cr><ln>OK
[00027443ms][INFO][CELL]: AT RX ( 2): <cr><ln>
[00027464ms][INFO][CELL]: AT TX ( 9): AT+CMEE=2
[00027465ms][INFO][CELL]: AT TX ( 1): <cr>
[00027471ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027492ms][INFO][CELL]: AT TX (12): AT#PORTCFG=3
[00027493ms][INFO][CELL]: AT TX ( 1): <cr>
[00027514ms][INFO][CELL]: AT RX ( 1): <cr>
[00027515ms][INFO][CELL]: AT RX ( 5): <ln>OK<cr><ln>
[00027536ms][INFO][CELL]: AT TX ( 6): AT&W&P
[00027537ms][INFO][CELL]: AT TX ( 1): <cr>
[00027621ms][INFO][CELL]: AT RX ( 5): <cr><ln>OK<cr>
[00027622ms][INFO][CELL]: AT RX ( 1): <ln>
[00027623ms][INFO][CELL]: Modem ready
[00027624ms][DBG ][CELL]: callback: 4096, err: 0, data: 4096
[00027625ms][DBG ][CELL]: Device ready => SIM pin
[00027629ms][DBG ][CELL]: callback: 4106, err: 0, data: 4097
[00027638ms][INFO][CELL]: Setup SIM (timeout 1000 ms)
[00027645ms][DBG ][CELL]: AT flush
[00027651ms][INFO][CELL]: AT TX ( 8): AT+CPIN?
[00027658ms][INFO][CELL]: AT TX ( 1): <cr>
[00027664ms][INFO][CELL]: AT RX (22): <cr><ln>+CPIN: READY<cr><ln><cr><ln>OK<cr>                                                                                                                                                             <ln>
[00027675ms][INFO][CELL]: SIM is ready
[00027681ms][DBG ][CELL]: callback: 4097, err: 0, data: 0
[00027689ms][DBG ][CELL]: Automatic network registration
[00027697ms][INFO][CELL]: AT TX ( 8): AT+COPS?
[00027704ms][INFO][CELL]: AT TX ( 1): <cr>
[00027711ms][INFO][CELL]: AT RX (18): <cr><ln>+COPS: 0,0,"AT&T
[00027719ms][INFO][CELL]: AT RX (11): ",8<cr><ln><cr><ln>OK<cr><ln>
[00027729ms][DBG ][CELL]: STM: set_registration: 0, plmn: NULL
[00027749ms][INFO][CELL]: AT TX ( 9): AT+CEREG=
[00027750ms][INFO][CELL]: AT TX ( 1): 2
[00027751ms][INFO][CELL]: AT TX ( 1): <cr>
[00027757ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027785ms][INFO][CELL]: AT TX ( 9): AT+CGREG=
[00027786ms][INFO][CELL]: AT TX ( 1): 2
[00027787ms][INFO][CELL]: AT TX ( 1): <cr>
[00027791ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027813ms][INFO][CELL]: AT TX ( 8): AT+CREG=
[00027814ms][INFO][CELL]: AT TX ( 1): 2
[00027815ms][INFO][CELL]: AT TX ( 1): <cr>
[00027818ms][INFO][CELL]: AT RX ( 4): <cr><ln>OK
[00027819ms][INFO][CELL]: AT RX ( 2): <cr><ln>
[00027846ms][INFO][CELL]: AT TX ( 9): AT+CGACT?
[00027847ms][INFO][CELL]: AT TX ( 1): <cr>
[00027852ms][INFO][CELL]: AT RX ( 4): <cr><ln>+C
[00027855ms][INFO][CELL]: AT RX (30): GACT: 1,1<cr><ln>+CGACT: 2,0<cr><ln>+CGACT
[00027859ms][DBG ][CELL]: Found active context
[00027865ms][INFO][CELL]: AT RX (26): : 3,0<cr><ln>+CGACT: 4,0<cr><ln>+CGACT
[00027875ms][INFO][CELL]: AT RX (26): : 5,0<cr><ln>+CGACT: 6,0<cr><ln><cr><ln>OK                                                                                                                                                             <cr><ln>
[00027887ms][DBG ][CELL]: Active context found.
[00027907ms][INFO][CELL]: AT TX ( 9): AT+CGATT?
[00027908ms][INFO][CELL]: AT TX ( 1): <cr>
[00027914ms][INFO][CELL]: AT RX ( 4): <cr><ln>+C
[00027916ms][INFO][CELL]: AT RX (15): GATT: 1<cr><ln><cr><ln>OK<cr><ln>
[00027924ms][DBG ][CELL]: Cellular already attached.
[00027944ms][INFO][CELL]: AT TX (10): AT+CGEREP=
[00027945ms][INFO][CELL]: AT TX ( 1): 1
[00027946ms][INFO][CELL]: AT TX ( 1): <cr>
[00027951ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027959ms][DBG ][CELL]: SIM pin => Signal quality
[00027979ms][INFO][CELL]: AT TX ( 6): AT+CSQ
[00027980ms][INFO][CELL]: AT TX ( 1): <cr>
[00027986ms][INFO][CELL]: AT RX (14): <cr><ln>+CSQ: 24,5<cr><ln>
[00027989ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027997ms][DBG ][CELL]: callback: 4104, err: 0, data: -1
[00028005ms][DBG ][CELL]: Signal quality => Registering network
[00028017ms][INFO][CELL]: AT TX ( 6): AT+CSQ
[00028021ms][INFO][CELL]: AT TX ( 1): <cr>
[00028027ms][INFO][CELL]: AT RX (20): <cr><ln>+CSQ: 24,5<cr><ln><cr><ln>OK<cr><l                                                                                                                                                             n>
[00028038ms][DBG ][CELL]: callback: 4104, err: 0, data: -1
[00028046ms][INFO][CELL]: RSSI -65 dBm
[00028052ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098
[00028061ms][INFO][CELL]: AT TX ( 9): AT+CEREG?
[00028068ms][INFO][CELL]: AT TX ( 1): <cr>
[00028075ms][INFO][CELL]: AT RX (32): <cr><ln>+CEREG: 2,1,"5304","2986711",8
[00028084ms][INFO][CELL]: AT RX ( 8): <cr><ln><cr><ln>OK<cr><ln>
[00028093ms][DBG ][CELL]: +CEREG: RegisteredHomeNetwork, LAC 21252, cell 4354228                                                                                                                                                             9, CATM1
[00028105ms][DBG ][CELL]: callback: 4098, err: 0, data: 1
[00028113ms][INFO][CELL]: Registering network => Attaching network
[00028125ms][INFO][CELL]: AT TX ( 6): AT+CSQ
[00028129ms][INFO][CELL]: AT TX ( 1): <cr>
[00028135ms][INFO][CELL]: AT RX (20): <cr><ln>+CSQ: 24,5<cr><ln><cr><ln>OK<cr><l                                                                                                                                                             n>
[00028146ms][DBG ][CELL]: callback: 4104, err: 0, data: -1
[00028154ms][INFO][CELL]: RSSI -65 dBm
[00028160ms][DBG ][CELL]: callback: 4106, err: 0, data: 4102
[00028169ms][INFO][CELL]: Attaching network (timeout 60000 ms)
[00028177ms][INFO][CELL]: AT TX ( 9): AT+CGATT?
[00028184ms][INFO][CELL]: AT TX ( 1): <cr>
[00028191ms][INFO][CELL]: AT RX (19): <cr><ln>+CGATT: 1<cr><ln><cr><ln>OK<cr><ln                                                                                                                                                             >
[00028202ms][DBG ][CELL]: callback: 4102, err: 0, data: 1
[00028222ms][INFO][CELL]: AT TX (11): AT+CGDCONT?
[00028223ms][INFO][CELL]: AT TX ( 1): <cr>
[00028234ms][INFO][CELL]: AT RX ( 6): <cr><ln>+CGD
[00028238ms][INFO][CELL]: AT RX (28): CONT: 1,"IPV4V6","","0.0.0.0
[00028242ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028251ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 2,"IPV4V6","","0.0.
[00028260ms][INFO][CELL]: AT RX ( 3): 0.0
[00028267ms][ERR ][CELL]: AT overflow
[00028273ms][INFO][CELL]: AT ERR (32): +CGDCONT: 2,"IPV4V6","","0.0.0.0
[00028283ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028292ms][ERR ][CELL]: AT overflow
[00028298ms][INFO][CELL]: AT ERR (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028308ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 3,"IPV4V6","","0.0.
[00028318ms][INFO][CELL]: AT RX ( 3): 0.0
[00028324ms][ERR ][CELL]: AT overflow
[00028330ms][INFO][CELL]: AT ERR (32): +CGDCONT: 3,"IPV4V6","","0.0.0.0
[00028340ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028349ms][ERR ][CELL]: AT overflow
[00028355ms][INFO][CELL]: AT ERR (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028365ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 4,"IPV4V6","","0.0.
[00028375ms][INFO][CELL]: AT RX ( 3): 0.0
[00028382ms][ERR ][CELL]: AT overflow
[00028387ms][INFO][CELL]: AT ERR (32): +CGDCONT: 4,"IPV4V6","","0.0.0.0
[00028397ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028407ms][ERR ][CELL]: AT overflow
[00028413ms][INFO][CELL]: AT ERR (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028422ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 5,"IPV4V6","","0.0.
[00028432ms][INFO][CELL]: AT RX ( 3): 0.0
[00028439ms][ERR ][CELL]: AT overflow
[00028445ms][INFO][CELL]: AT ERR (32): +CGDCONT: 5,"IPV4V6","","0.0.0.0
[00028454ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028464ms][ERR ][CELL]: AT overflow
[00028470ms][INFO][CELL]: AT ERR (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028479ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 6,"IPV4V6","","0.0.
[00028490ms][INFO][CELL]: AT RX ( 3): 0.0
[00028496ms][ERR ][CELL]: AT overflow
[00028502ms][INFO][CELL]: AT ERR (32): +CGDCONT: 6,"IPV4V6","","0.0.0.0
[00028512ms][INFO][CELL]: AT RX (20): .0.0.0.0.0.0.0.0.0.0
[00088211ms][DBG ][CELL]: AT error -3012
[00088211ms][INFO][CELL]: Found PDP context 1
[00088212ms][DBG ][CELL]: AT OoB readable 0, len 20
[00088233ms][DBG ][CELL]: AT OoB done
[00088233ms][INFO][CELL]: CellularContext PPP connect
[00088235ms][INFO][CELL]: AT TX ( 9): ATD*99***
[00088236ms][INFO][CELL]: AT TX ( 1): 1
[00088237ms][INFO][CELL]: AT TX ( 1): #
[00088238ms][INFO][CELL]: AT TX ( 1): <cr>
[00088245ms][INFO][CELL]: AT RX (16): <cr><ln>CONNECT 150000
[00088462ms][DBG ][CELL]: ppp_status_cb: event 0, ptr 3
[00088466ms][DBG ][CELL]: callback: 0, ptr: 3
[00093523ms][DBG ][CELL]: ppp_status_cb: event 0, ptr 1
[00093524ms][DBG ][CELL]: callback: 0, ptr: 1
[00093524ms][INFO][CELL]: CellularContext IP 10.33.137.164
[00098526ms][INFO][CELL]: Cellular local IP: 10.33.137.164

Connection Established.
[00098529ms][DBG ][CELL]: process_oob, filehandle is not usable, return...
[00098531ms][DBG ][CELL]: process_oob, filehandle is not usable, return...
TCP: connected with echo.mbedcloudtesting.com server
TCP: Sent 4 Bytes to echo.mbedcloudtesting.com
Received from echo server 4 Bytes
[00100882ms][INFO][CELL]: CellularContext disconnect()
[00100883ms][DBG ][CELL]: ppp_status_cb: event 0, ptr 2
[00100893ms][DBG ][CELL]: AT sync
[00100894ms][INFO][CELL]: AT TX ( 8): AT+CMEE?
[00100896ms][INFO][CELL]: AT TX ( 1): <cr>
[00101895ms][DBG ][CELL]: AT error -3012
[00101896ms][INFO][CELL]: AT RX (11): <cr><ln>NO CARRIE
[00101897ms][INFO][CELL]: AT RX ( 3): R<cr><ln>
[00101898ms][WARN][CELL]: AT timeout
[00101899ms][DBG ][CELL]: AT error -3012
[00101900ms][DBG ][CELL]: consume_to_tag not found
[00101908ms][DBG ][CELL]: AT stop tag not found
[00101914ms][DBG ][CELL]: AT error -3012
[00101920ms][WARN][CELL]: AT timeout
[00101926ms][DBG ][CELL]: AT error -3012
[00101932ms][DBG ][CELL]: consume_to_tag not found
[00101939ms][DBG ][CELL]: AT stop tag not found
[00101945ms][DBG ][CELL]: AT error -3012
[00101951ms][INFO][CELL]: AT TX ( 8): AT+CMEE?
[00101958ms][INFO][CELL]: AT TX ( 1): <cr>
[00101964ms][INFO][CELL]: AT RX (18): <cr><ln>+CMEE: 2<cr><ln><cr><ln>OK<cr><ln>
[00101974ms][DBG ][CELL]: callback: 0, ptr: 2
[00101981ms][INFO][CELL]: cellular_callback: PPP mode and NSAPI_STATUS_DISCONNECTED
[00101991ms][DBG ][CELL]: AT sync
[00101996ms][INFO][CELL]: AT TX ( 8): AT+CMEE?
[00102003ms][INFO][CELL]: AT TX ( 1): <cr>
[00102009ms][INFO][CELL]: AT RX (18): <cr><ln>+CMEE: 2<cr><ln><cr><ln>OK<cr><ln>
[00102019ms][INFO][CELL]: CellularContext disconnected

Success. Exiting

Without lwip.ipv6-enabled set to true:

mbed-os-example-cellular

Built: Oct  1 2019, 16:36:27
Establishing connection
[00000001ms][DBG ][CELL]: Modem flow control: RTS 14 CTS 40
[00000003ms][INFO][CELL]: New CellularContext  (0x20009b20)
[00000004ms][INFO][CELL]: CellularContext connect
[00000006ms][DBG ][CELL]: callback: 4106, err: 0, data: 4096
[00000014ms][INFO][CELL]: Start connecting (timeout 1000 ms)
[00000022ms][INFO][CELL]: AT TX ( 2): AT
[00000029ms][INFO][CELL]: AT TX ( 1): <cr>
[00001023ms][DBG ][CELL]: AT error -3012
[00001023ms][INFO][CELL]: AT TX ( 2): AT
[00001024ms][INFO][CELL]: AT TX ( 1): <cr>
[00002024ms][DBG ][CELL]: AT error -3012
[00002024ms][DBG ][CELL]: Init => Power
[00002025ms][DBG ][CELL]: callback: 4106, err: 0, data: 4096
[00002027ms][INFO][CELL]: Modem power ON (timeout 1000 ms)
[00002028ms][DBG ][CELL]: Power => Device ready
[00002032ms][DBG ][CELL]: callback: 4106, err: 0, data: 4096
[00002041ms][DBG ][CELL]: Device was not ready, calling soft_power_on()
[00027250ms][DBG ][CELL]: AT flush
[00027251ms][INFO][CELL]: AT RX (32): AT<cr><cr><ln>OK<cr><ln><cr><ln>#QSS: 1<cr><ln><cr><ln>+PACSP0<cr><ln><cr>
[00027253ms][INFO][CELL]: AT RX (11): <ln>#PSNT: 4<cr><ln>
[00027254ms][INFO][CELL]: AT TX ( 4): ATE0
[00027257ms][INFO][CELL]: AT TX ( 1): <cr>
[00027263ms][INFO][CELL]: AT RX (11): ATE0<cr><cr><ln>OK<cr><ln>
[00027292ms][INFO][CELL]: AT TX ( 9): AT+CMEE=1
[00027293ms][INFO][CELL]: AT TX ( 1): <cr>
[00027299ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027320ms][INFO][CELL]: AT TX ( 9): AT+CFUN=1
[00027321ms][INFO][CELL]: AT TX ( 1): <cr>
[00027327ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027348ms][INFO][CELL]: AT TX (13): AT&K3;&C1;&D0
[00027349ms][INFO][CELL]: AT TX ( 1): <cr>
[00027356ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027377ms][INFO][CELL]: AT TX ( 8): AT#QSS=1
[00027378ms][INFO][CELL]: AT TX ( 1): <cr>
[00027384ms][INFO][CELL]: AT RX ( 5): <cr><ln>OK<cr>
[00027385ms][INFO][CELL]: AT RX ( 1): <ln>
[00027406ms][INFO][CELL]: AT TX ( 9): AT#PSNT=1
[00027407ms][INFO][CELL]: AT TX ( 1): <cr>
[00027414ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027435ms][INFO][CELL]: AT TX ( 9): AT+CMER=2
[00027436ms][INFO][CELL]: AT TX ( 1): <cr>
[00027442ms][INFO][CELL]: AT RX ( 3): <cr><ln>O
[00027443ms][INFO][CELL]: AT RX ( 3): K<cr><ln>
[00027464ms][INFO][CELL]: AT TX ( 9): AT+CMEE=2
[00027465ms][INFO][CELL]: AT TX ( 1): <cr>
[00027471ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027492ms][INFO][CELL]: AT TX (12): AT#PORTCFG=3
[00027493ms][INFO][CELL]: AT TX ( 1): <cr>
[00027514ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027535ms][INFO][CELL]: AT TX ( 6): AT&W&P
[00027536ms][INFO][CELL]: AT TX ( 1): <cr>
[00027615ms][INFO][CELL]: AT RX ( 5): <cr><ln>OK<cr>
[00027616ms][INFO][CELL]: AT RX ( 1): <ln>
[00027617ms][INFO][CELL]: Modem ready
[00027618ms][DBG ][CELL]: callback: 4096, err: 0, data: 4096
[00027619ms][DBG ][CELL]: Device ready => SIM pin
[00027623ms][DBG ][CELL]: callback: 4106, err: 0, data: 4097
[00027632ms][INFO][CELL]: Setup SIM (timeout 1000 ms)
[00027639ms][DBG ][CELL]: AT flush
[00027645ms][INFO][CELL]: AT TX ( 8): AT+CPIN?
[00027652ms][INFO][CELL]: AT TX ( 1): <cr>
[00027658ms][INFO][CELL]: AT RX (22): <cr><ln>+CPIN: READY<cr><ln><cr><ln>OK<cr><ln>
[00027669ms][INFO][CELL]: SIM is ready
[00027675ms][DBG ][CELL]: callback: 4097, err: 0, data: 0
[00027684ms][DBG ][CELL]: Automatic network registration
[00027692ms][INFO][CELL]: AT TX ( 8): AT+COPS?
[00027698ms][INFO][CELL]: AT TX ( 1): <cr>
[00027705ms][INFO][CELL]: AT RX (29): <cr><ln>+COPS: 0,0,"AT&T",8<cr><ln><cr><ln>OK<cr><ln>
[00027717ms][DBG ][CELL]: STM: set_registration: 0, plmn: NULL
[00027737ms][INFO][CELL]: AT TX ( 9): AT+CEREG=
[00027738ms][INFO][CELL]: AT TX ( 1): 2
[00027739ms][INFO][CELL]: AT TX ( 1): <cr>
[00027745ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027773ms][INFO][CELL]: AT TX ( 9): AT+CGREG=
[00027774ms][INFO][CELL]: AT TX ( 1): 2
[00027775ms][INFO][CELL]: AT TX ( 1): <cr>
[00027779ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027801ms][INFO][CELL]: AT TX ( 8): AT+CREG=
[00027802ms][INFO][CELL]: AT TX ( 1): 2
[00027803ms][INFO][CELL]: AT TX ( 1): <cr>
[00027806ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027828ms][INFO][CELL]: AT TX ( 9): AT+CGACT?
[00027829ms][INFO][CELL]: AT TX ( 1): <cr>
[00027833ms][INFO][CELL]: AT RX (10): <cr><ln>+CGACT:
[00027837ms][INFO][CELL]: AT RX (32): 1,1<cr><ln>+CGACT: 2,0<cr><ln>+CGACT: 3,0<cr><ln>+
[00027842ms][DBG ][CELL]: Found active context
[00027848ms][INFO][CELL]: AT RX (31): CGACT: 4,0<cr><ln>+CGACT: 5,0<cr><ln>+CGACT
[00027859ms][INFO][CELL]: AT RX (13): : 6,0<cr><ln><cr><ln>OK<cr><ln>
[00027869ms][DBG ][CELL]: Active context found.
[00027889ms][INFO][CELL]: AT TX ( 9): AT+CGATT?
[00027890ms][INFO][CELL]: AT TX ( 1): <cr>
[00027896ms][INFO][CELL]: AT RX (16): <cr><ln>+CGATT: 1<cr><ln><cr><ln>O
[00027899ms][INFO][CELL]: AT RX ( 3): K<cr><ln>
[00027906ms][DBG ][CELL]: Cellular already attached.
[00027926ms][INFO][CELL]: AT TX (10): AT+CGEREP=
[00027927ms][INFO][CELL]: AT TX ( 1): 1
[00027928ms][INFO][CELL]: AT TX ( 1): <cr>
[00027933ms][INFO][CELL]: AT RX ( 6): <cr><ln>OK<cr><ln>
[00027941ms][DBG ][CELL]: SIM pin => Signal quality
[00027961ms][INFO][CELL]: AT TX ( 6): AT+CSQ
[00027962ms][INFO][CELL]: AT TX ( 1): <cr>
[00027968ms][INFO][CELL]: AT RX ( 6): <cr><ln>+CSQ
[00027970ms][INFO][CELL]: AT RX (14): : 24,5<cr><ln><cr><ln>OK<cr><ln>
[00027979ms][DBG ][CELL]: callback: 4104, err: 0, data: -1
[00027987ms][DBG ][CELL]: Signal quality => Registering network
[00027999ms][INFO][CELL]: AT TX ( 6): AT+CSQ
[00028003ms][INFO][CELL]: AT TX ( 1): <cr>
[00028009ms][INFO][CELL]: AT RX (20): <cr><ln>+CSQ: 24,5<cr><ln><cr><ln>OK<cr><ln>
[00028020ms][DBG ][CELL]: callback: 4104, err: 0, data: -1
[00028028ms][INFO][CELL]: RSSI -65 dBm
[00028034ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098
[00028043ms][INFO][CELL]: AT TX ( 9): AT+CEREG?
[00028050ms][INFO][CELL]: AT TX ( 1): <cr>
[00028059ms][INFO][CELL]: AT RX (18): <cr><ln>+CEREG: 2,1,"530
[00028065ms][INFO][CELL]: AT RX (22): 4","2986711",8<cr><ln><cr><ln>OK<cr><ln>
[00028075ms][DBG ][CELL]: +CEREG: RegisteredHomeNetwork, LAC 21252, cell 43542289, CATM1
[00028087ms][DBG ][CELL]: callback: 4098, err: 0, data: 1
[00028095ms][INFO][CELL]: Registering network => Attaching network
[00028107ms][INFO][CELL]: AT TX ( 6): AT+CSQ
[00028111ms][INFO][CELL]: AT TX ( 1): <cr>
[00028117ms][INFO][CELL]: AT RX (20): <cr><ln>+CSQ: 24,5<cr><ln><cr><ln>OK<cr><ln>
[00028128ms][DBG ][CELL]: callback: 4104, err: 0, data: -1
[00028136ms][INFO][CELL]: RSSI -65 dBm
[00028142ms][DBG ][CELL]: callback: 4106, err: 0, data: 4102
[00028151ms][INFO][CELL]: Attaching network (timeout 60000 ms)
[00028160ms][INFO][CELL]: AT TX ( 9): AT+CGATT?
[00028167ms][INFO][CELL]: AT TX ( 1): <cr>
[00028174ms][INFO][CELL]: AT RX ( 5): <cr><ln>+CG
[00028180ms][INFO][CELL]: AT RX (14): ATT: 1<cr><ln><cr><ln>OK<cr><ln>
[00028190ms][DBG ][CELL]: callback: 4102, err: 0, data: 1
[00028210ms][INFO][CELL]: AT TX (11): AT+CGDCONT?
[00028211ms][INFO][CELL]: AT TX ( 1): <cr>
[00028222ms][INFO][CELL]: AT RX (10): <cr><ln>+CGDCONT
[00028226ms][INFO][CELL]: AT RX (24): : 1,"IPV4V6","","0.0.0.0
[00028230ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028239ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 2,"IPV4V6","","0.0.
[00028250ms][INFO][CELL]: AT RX ( 3): 0.0
[00028255ms][ERR ][CELL]: AT overflow
[00028261ms][INFO][CELL]: AT ERR (32): +CGDCONT: 2,"IPV4V6","","0.0.0.0
[00028272ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028281ms][ERR ][CELL]: AT overflow
[00028287ms][INFO][CELL]: AT ERR (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028297ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 3,"IPV4V6","","0.0.
[00028307ms][INFO][CELL]: AT RX ( 3): 0.0
[00028313ms][ERR ][CELL]: AT overflow
[00028319ms][INFO][CELL]: AT ERR (32): +CGDCONT: 3,"IPV4V6","","0.0.0.0
[00028329ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028338ms][ERR ][CELL]: AT overflow
[00028344ms][INFO][CELL]: AT ERR (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028354ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 4,"IPV4V6","","0.0.
[00028364ms][INFO][CELL]: AT RX ( 3): 0.0
[00028370ms][ERR ][CELL]: AT overflow
[00028376ms][INFO][CELL]: AT ERR (32): +CGDCONT: 4,"IPV4V6","","0.0.0.0
[00028386ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028396ms][ERR ][CELL]: AT overflow
[00028402ms][INFO][CELL]: AT ERR (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028411ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 5,"IPV4V6","","0.0.
[00028421ms][INFO][CELL]: AT RX ( 3): 0.0
[00028428ms][ERR ][CELL]: AT overflow
[00028434ms][INFO][CELL]: AT ERR (32): +CGDCONT: 5,"IPV4V6","","0.0.0.0
[00028443ms][INFO][CELL]: AT RX (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028453ms][ERR ][CELL]: AT overflow
[00028459ms][INFO][CELL]: AT ERR (32): .0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,
[00028468ms][INFO][CELL]: AT RX (32): 0<cr><ln>+CGDCONT: 6,"IPV4V6","","0.0.
[00028479ms][INFO][CELL]: AT RX ( 3): 0.0
[00028485ms][ERR ][CELL]: AT overflow
[00028491ms][INFO][CELL]: AT ERR (32): +CGDCONT: 6,"IPV4V6","","0.0.0.0
[00028501ms][INFO][CELL]: AT RX (20): .0.0.0.0.0.0.0.0.0.0
[00088199ms][DBG ][CELL]: AT error -3012
[00088199ms][INFO][CELL]: Found PDP context 1
[00088200ms][DBG ][CELL]: AT OoB readable 0, len 20
[00088221ms][DBG ][CELL]: AT OoB done
[00088221ms][INFO][CELL]: CellularContext PPP connect
[00088223ms][INFO][CELL]: AT TX ( 9): ATD*99***
[00088224ms][INFO][CELL]: AT TX ( 1): 1
[00088225ms][INFO][CELL]: AT TX ( 1): #
[00088226ms][INFO][CELL]: AT TX ( 1): <cr>
[00088233ms][INFO][CELL]: AT RX (12): <cr><ln>CONNECT 15
[00088448ms][DBG ][CELL]: ppp_status_cb: event 0, ptr 3
[00088453ms][DBG ][CELL]: callback: 0, ptr: 3
[00094500ms][DBG ][CELL]: ppp_status_cb: event 0, ptr 1
[00094501ms][DBG ][CELL]: callback: 0, ptr: 1
[00094502ms][INFO][CELL]: CellularContext IP 10.50.70.80
[00094503ms][INFO][CELL]: Cellular local IP: 10.50.70.80

Connection Established.
[00094509ms][DBG ][CELL]: process_oob, filehandle is not usable, return...
[00094516ms][DBG ][CELL]: process_oob, filehandle is not usable, return...
Couldn't resolve remote host: echo.mbedcloudtesting.com, code: -3009
[00194538ms][INFO][CELL]: CellularContext disconnect()
[00194539ms][DBG ][CELL]: ppp_status_cb: event 0, ptr 2
[00194552ms][DBG ][CELL]: AT sync
[00194553ms][INFO][CELL]: AT TX ( 8): AT+CMEE?
[00194554ms][INFO][CELL]: AT TX ( 1): <cr>
[00195553ms][DBG ][CELL]: AT error -3012
[00195553ms][WARN][CELL]: AT timeout
[00195554ms][DBG ][CELL]: AT error -3012
[00195555ms][DBG ][CELL]: consume_to_tag not found
[00195556ms][DBG ][CELL]: AT stop tag not found
[00195558ms][DBG ][CELL]: AT error -3012
[00195564ms][WARN][CELL]: AT timeout
[00195569ms][DBG ][CELL]: AT error -3012
[00195575ms][DBG ][CELL]: consume_to_tag not found
[00195582ms][DBG ][CELL]: AT stop tag not found
[00195589ms][DBG ][CELL]: AT error -3012
[00195595ms][INFO][CELL]: AT TX ( 8): AT+CMEE?
[00195601ms][INFO][CELL]: AT TX ( 1): <cr>
[00195607ms][INFO][CELL]: AT RX (14): <cr><ln>NO CARRIER<cr><ln>
[00196596ms][WARN][CELL]: AT timeout
[00196596ms][DBG ][CELL]: AT error -3012
[00196597ms][DBG ][CELL]: consume_to_tag not found
[00196598ms][DBG ][CELL]: AT stop tag not found
[00196599ms][DBG ][CELL]: AT error -3012
[00196601ms][WARN][CELL]: AT timeout
[00196606ms][DBG ][CELL]: AT error -3012
[00196612ms][DBG ][CELL]: consume_to_tag not found
[00196619ms][DBG ][CELL]: AT stop tag not found
[00196626ms][DBG ][CELL]: AT error -3012
[00196632ms][INFO][CELL]: AT TX ( 8): AT+CMEE?
[00196638ms][INFO][CELL]: AT TX ( 1): <cr>
[00196644ms][INFO][CELL]: AT RX (18): <cr><ln>+CMEE: 2<cr><ln><cr><ln>OK<cr><ln>
[00196654ms][DBG ][CELL]: callback: 0, ptr: 2
[00196661ms][INFO][CELL]: cellular_callback: PPP mode and NSAPI_STATUS_DISCONNECTED
[00196671ms][DBG ][CELL]: AT sync
[00196676ms][INFO][CELL]: AT TX ( 8): AT+CMEE?
[00196683ms][INFO][CELL]: AT TX ( 1): <cr>
[00196689ms][INFO][CELL]: AT RX (18): <cr><ln>+CMEE: 2<cr><ln><cr><ln>OK<cr><ln>
[00196699ms][INFO][CELL]: CellularContext disconnected

Failure. Exiting
AriParkkila commented 5 years ago

@trowbridgec Thanks for the logs. It's difficult to say actual reason, but as you have PROPERTY_IPV6_STACK or PROPERTY_IPV4V6_STACK defined the modem creates IPV4V6 context. If you want to stick to IPV4 then you need to remove IPV6 and IPV4V6 properties, but I'd say that for an IPV4V6 PDP context type you need to have lwip.ipv6-enabled. In this PR, I'd like to see lwip.ipv6-enabled for all targets to hear if someone has a good reason to disagree :)

40Grit commented 5 years ago

Agree. @Chris, do it.

trowbridgec commented 5 years ago

@AriParkkila I'll make the change for all targets :)

40Grit commented 5 years ago

@AnttiKauppila all good?

@0xc0170 who runs maintenance and tests for examples?

AnttiKauppila commented 5 years ago

Restarted tests

kimlep01 commented 5 years ago

Fixed target in Raas - smoke is passing now.