OTAA failed / Debugging device activation OTAA issues

Hey,

I am trying to activate one of my devices on loraserver.

When turning my device on I can see joinRequest being sent through the registered gateway and joinAccept being sent back. Even though the joinAccept is being sent my device does not get activated but it sends joinRequest again just to receive joinAccept again and keeps looping this way.

When I was doing OTAA over TTN under same conditions everything worked great.

What could be the reason for this?

Cheers!

Please make sure that the correct LoRaWAN version is selected in the device-profile, as 1.0.x and 1.1.x are not compatible. Most likely your device does not support LoRaWAN 1.1.x (as this is not yet supported by TTN).

Hey, thanks for the fast reply!

I have actually tried setting each and every possible version of LoRaWAN (just in case) in device-profile with the same result.

Hello,

could you confirm? Do you use lorawan mac version 1.0.2 and regarding provisionning of appkey, the field network key must be fill with your “OTAA appkey” and the appkey field must be left blank.

Hey,

I am using STM32CubeExpansion_LRWAN_V1.1.0 if you are familiar with it. After some digging I am pretty sure that the mac version in this program is 1.0.2 as you stated but I am not 100%.

And regarding the keys I have left the app key blank and network key is the actual app key.

I don’t think so, in this link https://www.st.com/content/st_com/en/products/embedded-software/mcus-embedded-software/stm32-embedded-software/stm32cube-expansion-packages/i-cube-lrwan.html you can see that from I-CUBE-LWAN_V1.1.0 the lorawan mac version used is V1.0.3.

You can also find a previous link which deals about this topic ==> https://forum.loraserver.io/t/lorawan-mac-version-1-0-3/1914

In my case I’m using I-CUBE-LWAN_V1.0.x (it works with loraserver) and I notified the same behavior with I-CUBE-LWAN_V1.1.0 that is to say join-request/accept loop.

Setting the device-profile to LoRaWAN 1.0.2 or 1.0.3 should not matter for LoRa Server as the only change in behavior of LoRa Server is between 1.0.x vs 1.1.x.

Yes I understand your point of view, normally with lorawan mac V1.0.3 implementation only class B devices could encounter an issue, but something is different from the release of I-CUBE-LRWAN_V1.1, certainly we have to get closer of STMicroelectronics to go deeper.

Julien, is the example code version you are using still available to download? I can’t find it anywhere.

Also, may I PM you about something.

Only the latest version is available from www.st.com but fortunately for you :wink: I have the .zip of I-CUBE-LRWAN_V.1.0.5 which work with lorawan mac version 1.0.2, if you are interested I can send you by email through google drive link.

@Gal @julien please note that there already is a v1.2.0 version of I-CUBE-LRWAN. Maybe that is worth trying?

I did try that version and after setting the connection data in commissioning.h and downloading it to my MCU nothing came through.

In fact, I have the same problem as you, whether the problem is solved now or not.

It is not resolved. I am using ABP whenever I need to test something.

Hello brocaar,
Why have I tried all the LoraWAN MAC versions and still have a join-request join-accept loop?

Hi @brocaar, thanks for your support.

Following the https://forum.loraserver.io/t/unable-to-receive-sensor-data-after-otaa/3428 thread, and after that uplink you see, the subsequent uplinks reach the “live lorawan frames” but they never reach the “live device data” tab.

As you can see here:

Can you help us understand what does this mean?

Thank you,
Best regards.
Miguel

Also having problems to OTAA join (with RN2483 and RHF76-052).

loraserver receives JoinRequest and issues JoinAccept:
loraserver1

gateway looks like this:
gateway/+/rx {“rxInfo”:{“mac”:“xxxxxxxxxxxxxxxxxx”,“timestamp”:1913704108,“frequency”:868299987,“channel”:0,“rfChain”:0,“crcStatus”:1,“codeRate”:“4/5”,“rssi”:-68,“loRaSNR”:9,“size”:23,“dataRate”:{“modulation”:“LORA”,“spreadFactor”:7,“bandwidth”:125},“board”:0,“antenna”:0},“phyPayload”:“ABAxAdB+1bNwQJ27/SrpMiLnbZeG51k=”}
gateway/+/tx {“token”:5804,“txInfo”:{“mac”:“xxxxxxxxxxxxxxxxxx”,“immediately”:false,“timestamp”:1918704108,“frequency”:868299987,“power”:14,“dataRate”:{“modulation”:“LORA”,“spreadFactor”:7,“bandwidth”:125},“codeRate”:“4/5”,“iPol”:true,“board”:0,“antenna”:0},“phyPayload”:“IOmnU702SgGjqNSTpJ+02PI=”}
gateway/+/ack {“mac”:“xxxxxxxxxxxxxxxxxxx”,“token”:0}

Hence, the nodes never receive a join accept.

ABP works fine here. And OTAA works fine with TTN but not here.

Using LoRaWAN 1.0.1 and 1.0.2, respectively, and https://github.com/things4u/ESP-1ch-Gateway-v5.0.

Any hints? Thanks a lot.

Good day!
I have a strange server behavior. We have two devices(1.0.2, OTAA) that, when turned on, send a message to join, you can see that the server responds to their acceptance, then the first messages are transmitted, and everything goes as expected, except for three things: the activation tab does not show activation keys , but there are information of battery and the device margin and third, messages on the mqtt are not transmitted!

мар 11 15:12:47 lws2 lora-app-server[970]: time="2019-03-11T15:12:47+03:00" level=info msg="js: request received" message_type=JoinReq receiver_id=7665676173693131 sender_id=000000 transaction_id=787587301
мар 11 15:12:47 lws2 lora-app-server[970]: time="2019-03-11T15:12:47+03:00" level=info msg="device-keys updated" dev_eui=393337386b375e0c
мар 11 15:12:47 lws2 lora-app-server[970]: time="2019-03-11T15:12:47+03:00" level=info msg="js: sending response" message_type=JoinAns receiver_id=000000 result_code=Success sender_id=7665676173693131 transaction_id=787587301
мар 11 15:13:03 lws2 lora-app-server[970]: time="2019-03-11T15:13:03+03:00" level=info msg="device updated" dev_eui=393337386b375e0c
мар 11 15:13:03 lws2 lora-app-server[970]: time="2019-03-11T15:13:03+03:00" level=info msg="handler/mqtt: publishing message" qos=0 topic=application/1/device/393337386b375e0c/status
мар 11 15:13:09 lws2 lora-app-server[970]: time="2019-03-11T15:13:09+03:00" level=info msg="handler/influxdb: status measurements written" dev_eui=393337386b375e0c

мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="gateway/mqtt: uplink frame received"
мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="packet(s) collected" dev_eui=393337386b375e0c gw_count=1 gw_ids=aad00dba19000013 mtype=JoinRequest
мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="gateway/mqtt: uplink frame received"
мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="device-queue flushed" dev_eui=393337386b375e0c
мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="device-session saved" dev_addr=0186e52a dev_eui=393337386b375e0c
мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="device-activation created" dev_eui=393337386b375e0c id=11764
мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="device updated" dev_eui=393337386b375e0c
мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="gateway/mqtt: publishing downlink frame" qos=0 topic=gateway/aad00dba19000013/tx
мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="downlink-frames saved" dev_eui=393337386b375e0c token=44034
мар 11 15:12:47 lws2 loraserver[971]: time="2019-03-11T15:12:47+03:00" level=info msg="backend/gateway: downlink tx acknowledgement received" gateway_id=aad00dba19000013
мар 11 15:12:53 lws2 loraserver[971]: time="2019-03-11T15:12:53+03:00" level=info msg="gateway/mqtt: gateway stats packet received" gateway_id=21000000080e0e38
мар 11 15:12:53 lws2 loraserver[971]: time="2019-03-11T15:12:53+03:00" level=info msg="gateway updated" gateway_id=21000000080e0e38
мар 11 15:12:53 lws2 loraserver[971]: time="2019-03-11T15:12:53+03:00" level=info msg="gateway/mqtt: publishing gateway configuration" qos=0 topic=gateway/21000000080e0e38/config
мар 11 15:12:53 lws2 loraserver[971]: time="2019-03-11T15:12:53+03:00" level=info msg="metrics saved" aggregation="[MINUTE HOUR DAY MONTH]" name="gw:21000000080e0e38"
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="gateway/mqtt: uplink frame received"
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="rx info sent to network-controller" dev_eui=393337386b375e0c
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="pending mac-command deleted" cid=LinkCheckReq dev_eui=393337386b375e0c
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="device gateway rx-info meta-data saved" dev_eui=393337386b375e0c
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="device-session saved" dev_addr=0186e52a dev_eui=393337386b375e0c
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="gateway/mqtt: uplink frame received"
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="requesting device-status" dev_eui=393337386b375e0c
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="pending mac-command block set" cid=LinkCheckReq commands=1 dev_eui=393337386b375e0c
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="pending mac-command block set" cid=DevStatusReq commands=1 dev_eui=393337386b375e0c
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="pending mac-command block set" cid=RXTimingSetupReq commands=1 dev_eui=393337386b375e0c
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="gateway/mqtt: publishing downlink frame" qos=0 topic=gateway/aad00dba19000013/tx
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="device-session saved" dev_addr=0186e52a dev_eui=393337386b375e0c
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="downlink-frames saved" dev_eui=393337386b375e0c token=22153
мар 11 15:12:57 lws2 loraserver[971]: time="2019-03-11T15:12:57+03:00" level=info msg="backend/gateway: downlink tx acknowledgement received" gateway_id=aad00dba19000013
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="gateway/mqtt: uplink frame received"
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="gateway/mqtt: uplink frame received"
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="gateway/mqtt: uplink frame received"
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="gateway/mqtt: uplink frame received"
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="rx info sent to network-controller" dev_eui=393337386b375e0c
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="pending mac-command deleted" cid=DevStatusReq dev_eui=393337386b375e0c
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="dev_status_ans answer received" battery=255 dev_eui=393337386b375e0c margin=31
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="pending mac-command deleted" cid=RXTimingSetupReq dev_eui=393337386b375e0c
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="rx_timing_setup request acknowledged" dev_eui=393337386b375e0c rx_delay=1
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="pending mac-command deleted" cid=LinkCheckReq dev_eui=393337386b375e0c
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="device gateway rx-info meta-data saved" dev_eui=393337386b375e0c
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="device-session saved" dev_addr=0186e52a dev_eui=393337386b375e0c
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="pending mac-command block set" cid=LinkCheckReq commands=1 dev_eui=393337386b375e0c
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="gateway/mqtt: publishing downlink frame" qos=0 topic=gateway/aad00dba19000013/tx
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="device-session saved" dev_addr=0186e52a dev_eui=393337386b375e0c
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="downlink-frames saved" dev_eui=393337386b375e0c token=32355
мар 11 15:13:03 lws2 loraserver[971]: time="2019-03-11T15:13:03+03:00" level=info msg="backend/gateway: downlink tx acknowledgement received" gateway_id=aad00dba19000013
мар 11 15:13:07 lws2 loraserver[971]: time="2019-03-11T15:13:07+03:00" level=info msg="gateway/mqtt: gateway stats packet received" gateway_id=0000985dad86df5b
мар 11 15:13:07 lws2 loraserver[971]: time="2019-03-11T15:13:07+03:00" level=info msg="gateway updated" gateway_id=0000985dad86df5b
мар 11 15:13:07 lws2 loraserver[971]: time="2019-03-11T15:13:07+03:00" level=info msg="gateway/mqtt: publishing gateway configuration" qos=0 topic=gateway/0000985dad86df5b/config

мар 11 15:12:47 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:47+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.111.92:52859" data="ADExaXNhZ2V2DF43azg3MzmjkYUpLsc=" mac=aad00dba19000013
мар 11 15:12:47 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:47+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aad00dba19000013/rx
мар 11 15:12:47 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:47+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.111.159:34685" data="ADExaXNhZ2V2DF43azg3MzmjkYUpLsc=" mac=0000985dad86df5b
мар 11 15:12:47 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:47+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/0000985dad86df5b/rx
мар 11 15:12:47 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:47+03:00" level=info msg="backend: downlink packet received" topic=gateway/aad00dba19000013/tx
мар 11 15:12:47 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:47+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aad00dba19000013/ack
мар 11 15:12:53 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:53+03:00" level=info msg="gateway: stat packet received" addr="192.168.111.74:42690" mac=21000000080e0e38
мар 11 15:12:53 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:53+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/21000000080e0e38/stats
мар 11 15:12:53 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:53+03:00" level=info msg="backend: config packet received" topic=gateway/21000000080e0e38/config
мар 11 15:12:53 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:53+03:00" level=warning msg="gateway: configuration was not applied, gateway is not configured for managed configuration" mac=21000000080e0e38
мар 11 15:12:57 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:57+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.111.92:52859" data="QCrlhgEAAAAAu439aQE=" mac=aad00dba19000013
мар 11 15:12:57 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:57+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aad00dba19000013/rx
мар 11 15:12:57 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:57+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.111.159:34685" data="QCrlhgEAAAAAu439aQE=" mac=0000985dad86df5b
мар 11 15:12:57 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:57+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/0000985dad86df5b/rx
мар 11 15:12:57 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:57+03:00" level=info msg="backend: downlink packet received" topic=gateway/aad00dba19000013/tx
мар 11 15:12:57 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:12:57+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aad00dba19000013/ack
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.111.92:52859" data=QCrlhgEAAQAAOoUyumnuJE83 mac=aad00dba19000013
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.111.92:52859" data=QCrlhgEAAQAAOoUyumnuJE83 mac=aad00dba19000013
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aad00dba19000013/rx
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aad00dba19000013/rx
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.111.74:42690" data=QCrlhgEAAQAAOoUyumnuJE83 mac=21000000080e0e38
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/21000000080e0e38/rx
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.111.159:34685" data=QCrlhgEAAQAAOoUyumnuJE83 mac=0000985dad86df5b
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/0000985dad86df5b/rx
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="backend: downlink packet received" topic=gateway/aad00dba19000013/tx
мар 11 15:13:03 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:03+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aad00dba19000013/ack
мар 11 15:13:07 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:07+03:00" level=info msg="gateway: stat packet received" addr="192.168.111.159:34685" mac=0000985dad86df5b
мар 11 15:13:07 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:07+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/0000985dad86df5b/stats
мар 11 15:13:07 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:07+03:00" level=info msg="backend: config packet received" topic=gateway/0000985dad86df5b/config
мар 11 15:13:07 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:07+03:00" level=warning msg="gateway: configuration was not applied, gateway is not configured for managed configuration" mac=0000985dad86df5b
мар 11 15:13:11 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:11+03:00" level=info msg="gateway: stat packet received" addr="192.168.111.92:52859" mac=aad00dba19000013
мар 11 15:13:11 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:11+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aad00dba19000013/stats
мар 11 15:13:11 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:11+03:00" level=info msg="backend: config packet received" topic=gateway/aad00dba19000013/config
мар 11 15:13:11 lws2 lora-gateway-bridge[976]: time="2019-03-11T15:13:11+03:00" level=warning msg="gateway: configuration was not applied, gateway is not configured for managed configuration" mac=aad00dba19000013

image

Hi, does anyone found a solution for this yet?