End node doesn't see lora-app-server's join response

almaz · November 21, 2018, 9:22pm

Hi!
I see that lora-app-server sends join responce on join request:

INFO[1579] js: request received                          message_type=JoinReq receiver_id=70b3d57ed000f706 sender_id=000000 transaction_id=3006702747
INFO[1579] device-keys updated                           dev_eui=343934354c377516
INFO[1579] js: sending response                          message_type=JoinAns receiver_id=000000 result_code=Success sender_id=70b3d57ed000f706 transaction_id=3006702747

I see that gateway retransmit this to device:

JSON up: {"rxpk":[{"tmst":10133212,"chan":2,"rfch":1,"freq":868.500000,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":-15.5,"rssi":-95,"size":23,"data":"AAb3ANB+1bNwFnU3TDU0OTRsC9ryrHQ="}]}
INFO: [up] PUSH_ACK received in 40 ms
INFO: [down] PULL_RESP received  - token[74:92] :)

JSON down: {"txpk":{"imme":false,"tmst":15133212,"freq":868.5,"rfch":0,"powe":14,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":17,"data":"ICBc8ixkhjjyanPEMhKExiQ=","brd":0,"ant":0}}
INFO: [down] PULL_ACK received in 38 ms
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
INFO: Enabling TX notch filter
59.20.0.0.e6.e4.45.9.0.9c.11.18.0.8.0.0.20.20.5c.f2.2c.64.86.38.f2.6a.73.c4.32.12.84.c6.24.end
INFO: [down] PULL_ACK received in 38 ms

But end node doesn’t recognize join responce: MlConNO

But when I use same end node with exactly same gateway and exactly same global_conf.json I see gateway logs which end node able to recognize as join responce:

JSON up: {"rxpk":[{"tmst":5547948,"chan":0,"rfch":1,"freq":868.100000,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":-13.0,"rssi":-93,"size":23,"data":"AAb3ANB+1bNwFnU3TDU0OTQREfXBwvA="}]}
INFO: [up] PUSH_ACK received in 63 ms
INFO: [down] PULL_RESP received  - token[203:148] :)

JSON down: {"txpk":{"imme":false,"tmst":11547948,"freq":869.525,"rfch":0,"powe":27,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":33,"ncrc":true,"data":"IFsuiuIddWbNo2QSMAlz9vsWIXvaNcu+dbXoQ73DLVxt"}}
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
Info: packet will be sent without CRC
INFO: Enabling TX notch filter
59.61.99.0.b0.2f.55.f.0.1c.21.18.0.8.0.0.20.5b.2e.8a.e2.1d.75.66.cd.a3.64.12.30.9.73.f6.fb.16.21.7b.da.35.cb.be.75.b5.e8.43.bd.c3.2d.5c.6d.end
INFO: [down] PULL_ACK received in 62 ms
lgw_receive:1155: FIFO content: 1 37 0 5 1d
lgw_receive:1174: [0 17]
Note: LoRa packet

Tried to change TX frequency on network-server settings in lora-app-server - no luck.
Can someone suggest me what should I change in lora-app-server or loraserver configs to complete JOIN request-responce mission succefully please?)

almaz · December 15, 2018, 10:57am

Here is JoinRequest:

* * * * gatewayId:"aa555a0000000005"
      * time:null
      * timeSinceGpsEpoch:null
      * timestamp:846050228
      * rssi:-97
      * loraSnr:-1.8
      * channel:1
      * rfChain:1
      * board:0
      * antenna:0
      * ▶

location:{} 5 keys
        * latitude:0
        * longitude:0
        * altitude:0
        * source:"UNKNOWN"
        * accuracy:0
      * fineTimestampType:"NONE"
  * ▶

txInfo:{} 3 keys
    * frequency:868300000
    * modulation:"LORA"
    * ▶

loRaModulationInfo:{} 4 keys
      * bandwidth:125
      * spreadingFactor:12
      * codeRate:"4/5"
      * polarizationInversion:false

* * ▶

phyPayload:{} 3 keys
    * ▶

mhdr:{} 2 keys
      * mType:"JoinRequest"
      * major:"LoRaWANR1"
    * ▶

macPayload:{} 3 keys
      * joinEUI:"70b3d57ed000f706"
      * devEUI:"343934354d377316"
      * devNonce:47988
    * mic:"31d0c219"

Here is JoinAccept:

* * * gatewayId:"aa555a0000000005"
    * immediately:false
    * timeSinceGpsEpoch:null
    * timestamp:851050228
    * frequency:868300000
    * power:14
    * modulation:"LORA"
    * ▶

loraModulationInfo:{} 4 keys
      * bandwidth:125
      * spreadingFactor:12
      * codeRate:"4/5"
      * polarizationInversion:true
    * board:0
    * antenna:0

* * ▶

phyPayload:{} 3 keys
    * ▶

mhdr:{} 2 keys
      * mType:"JoinAccept"
      * major:"LoRaWANR1"
    * ▶

macPayload:{} 1 key
      * bytes:"a6Qtb74Arlhfpugb"
    * mic:"5c3f8cc7"

End node doesn’t see responce.

almaz · December 15, 2018, 11:09am

Here is what I see in a case of TTN:

JoinRequest:

{
  "gw_id": "eui-aa555a0000000005",
  "payload": "AAb3ANB+1bNwFnM3TTU0OTQt9RrMSPA=",
  "dev_eui": "343934354D377316",
  "lora": {
    "spreading_factor": 12,
    "bandwidth": 125,
    "air_time": 1482752000
  },
  "coding_rate": "4/5",
  "timestamp": "2018-12-15T11:03:21.251Z",
  "rssi": -99,
  "snr": -1.2,
  "app_eui": "70B3D57ED000F706",
  "frequency": 868300000
}

Join responce:

{
  "gw_id": "eui-aa555a0000000005",
  "payload": "IDFKD1Ew/tSnqS4A1HLEsR1Ek087dzDQ1z3UbqS21f/Y",
  "lora": {
    "spreading_factor": 12,
    "bandwidth": 125,
    "air_time": 1810432000
  },
  "coding_rate": "4/5",
  "timestamp": "2018-12-15T11:03:26.251Z",
  "frequency": 869525000
}

JoinAccept received by node successfully.
I noticed that loraserver returns JoinResponce immediately without 5sec delay while TTN truly waits 5 sec.
Who is responsible for waiting required delay between joinRequest and JoinResponce?
I use packet_forwarder version 4.0.1 and Loraserver downloaded from github just few weeks ago.
Help me solve this (probably) obvious issue?

iegomez · December 15, 2018, 5:14pm

You can change the rx delay at loraserver’s conf. See https://www.loraserver.io/loraserver/install/config/.

almaz · December 23, 2018, 2:54pm

get_downlink_data_delay or rx1_delay?
Doesn’t this delay should me implemented out of box depending on choosed Region?

almaz · December 23, 2018, 3:24pm

I changed get_downlink_data_delay in configuration/loraserver/loraserver.toml and /etc/loraserver/loraserver.toml, did make clean and make build but ./build/loraserver configfile still shows me 100ms for get_downlink_data_delay

iegomez · December 23, 2018, 5:57pm

From the docs:

# Get downlink data delay.
#
# This is the time that LoRa Server waits between forwarding data to the
# application-server and reading data from the queue. A higher value
# means that the application-server has more time to schedule a downlink
# queue item which can be processed within the same uplink / downlink
# transaction.
# Please note that this value has influence on the uplink / downlink
# roundtrip time. Setting this value too high means LoRa Server will be
# unable to respond to the device within its receive-window.
get_downlink_data_delay="100ms"

On the other hand:

# Class A RX1 delay
  #
  # 0=1sec, 1=1sec, ... 15=15sec. A higher value means LoRa Server has more
  # time to respond to the device as the delay between the uplink and the
  # first receive-window will be increased.
  rx1_delay=1

You mentioned you wanted loraserver to wait 5 seconds, so it seems to me you want to modify the rx delay to match your receive window, not the downlink data delay.

almaz · December 25, 2018, 7:20pm

If I use same end node with same gateway I am able to receive join accept:

JSON up: {"rxpk":    [{"tmst":517330020,"chan":0,"rfch":1,"freq":868.100000,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":-16.8,"rssi":-96,"size":29,"data":"QA37rweAAAAC9NtVmH93Soq1uE0dLzZF8R3CbMU="}]}
INFO: [up] PUSH_ACK received in 1 ms
INFO: [down] PULL_RESP received  - token[0:0] :)

JSON down: {"txpk":{"imme":false,"tmst":518330020,"freq":868.1,"rfch":0,"powe":14,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":17,"data":"YA37rweFAAADAAcAARrkTt0=","brd":0,"ant":0}}
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)

INFO: Received pkt from mote: 07AFFB0D (fcnt=1)

JSON up: {"rxpk":[{"tmst":522535924,"chan":0,"rfch":1,"freq":868.100000,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":-16.8,"rssi":-97,"size":31,"data":"QA37rweCAQADBwLABBxzl6QbRl+9S2Vlem36EBOWoQ=="}]}
INFO: [up] PUSH_ACK received in 1 ms
INFO: [down] PULL_ACK received in 1 ms

INFO: Received pkt from mote: 07AFFB0D (fcnt=2)

JSON up: {"rxpk":[{"tmst":528103124,"chan":1,"rfch":1,"freq":868.300000,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":-15.8,"rssi":-97,"size":29,"data":"QA37rweAAgAC/Bsr5N6D9DQtrV1aglywe5oMtvQ="}]}
INFO: [up] PUSH_ACK received in 1 ms

INFO: Received pkt from mote: 07AFFB0D (fcnt=3)

JSON up: {"rxpk":[{"tmst":533762172,"chan":0,"rfch":1,"freq":868.100000,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":-15.0,"rssi":-97,"size":29,"data":"QA37rweAAwACyYvRCxvQq3Qd/aE+i4HWavx7yJY="}]}
INFO: [up] PUSH_ACK received in 1 ms
INFO: [down] PULL_ACK received in 0 ms

lora-app-server:

 lora-app-server[1571]: time="2018-12-25T22:06:48+03:00" level=info msg="js: request received" message_type=JoinReq receiver_id=70b3d57ed000f706 sender_id=010203 transaction_id=3784958283
 lora-app-server[1571]: time="2018-12-25T22:06:48+03:00" level=info msg="device-keys updated" dev_eui=343934354d377316
 lora-app-server[1571]: time="2018-12-25T22:06:48+03:00" level=info msg="device-activation created" dev_eui=343934354d377316 id=881
 lora-app-server[1571]: time="2018-12-25T22:06:48+03:00" level=info msg="handler/mqtt: publishing join notification" topic="application/3/node/343934354d377316/join"
 lora-app-server[1571]: time="2018-12-25T22:06:48+03:00" level=info msg="js: sending response" message_type=JoinAns receiver_id=010203 result_code=Success sender_id=70b3d57ed000f706 transaction_id=3784958283

loraserver:

 loraserver[1572]: time="2018-12-25T22:06:48+03:00" level=info msg="packet(s) collected" dev_eui=343934354d377316 gw_count=1 gw_macs=aa555a0000000005 mtype=JoinRequest
дек 25 22:06:48 pc loraserver[1572]: time="2018-12-25T22:06:48+03:00" level=info msg="device-queue flushed" dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:06:48+03:00" level=info msg="device-session saved" dev_addr=07affb0d dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:06:48+03:00" level=info msg="device-activation created" dev_eui=343934354d377316 id=881
 loraserver[1572]: time="2018-12-25T22:06:48+03:00" level=info msg="backend/gateway: publishing tx packet" topic="gateway/aa555a0000000005/tx"
 loraserver[1572]: time="2018-12-25T22:06:51+03:00" level=info msg="backend/gateway: gateway stats packet received" mac=aa555a0000000005
 loraserver[1572]: time="2018-12-25T22:06:51+03:00" level=info msg="gateway updated" mac=aa555a0000000005
 loraserver[1572]: time="2018-12-25T22:06:56+03:00" level=info msg="backend/gateway: rx packet received"
 loraserver[1572]: time="2018-12-25T22:06:56+03:00" level=info msg="packet(s) collected" dev_eui=343934354d377316 gw_count=1 gw_macs=aa555a0000000005 mtype=UnconfirmedDataUp
 loraserver[1572]: time="2018-12-25T22:06:56+03:00" level=info msg="rx info sent to network-controller" dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:06:56+03:00" level=info msg="mac-command block added to queue" cid=LinkADRReq dev_eui=343934354d377316 frmpayload=false
 loraserver[1572]: time="2018-12-25T22:06:56+03:00" level=info msg="adr request added to mac-command queue" dev_eui=343934354d377316 dr=0 nb_trans=0 req_dr=0 req_nb_trans=1 req_tx_power_idx=0 tx_power=0
 loraserver[1572]: time="2018-12-25T22:06:56+03:00" level=info msg="device-session saved" dev_addr=07affb0d dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:06:57+03:00" level=info msg="pending mac-command block set" cid=LinkADRReq commands=1 dev_eui=343934354d377316 frm_payload=false
 loraserver[1572]: time="2018-12-25T22:06:57+03:00" level=info msg="mac-command block removed from queue" cid=LinkADRReq dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:06:57+03:00" level=info msg="backend/gateway: publishing tx packet" topic="gateway/aa555a0000000005/tx"
 loraserver[1572]: time="2018-12-25T22:06:57+03:00" level=info msg="device-session saved" dev_addr=07affb0d dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:07:01+03:00" level=info msg="backend/gateway: rx packet received"
 loraserver[1572]: time="2018-12-25T22:07:02+03:00" level=info msg="packet(s) collected" dev_eui=343934354d377316 gw_count=1 gw_macs=aa555a0000000005 mtype=UnconfirmedDataUp
 loraserver[1572]: time="2018-12-25T22:07:02+03:00" level=info msg="rx info sent to network-controller" dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:07:02+03:00" level=info msg="mac-command block removed from pending" cid=LinkADRReq dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:07:02+03:00" level=info msg="link_adr request acknowledged" dev_eui=343934354d377316 dr=0 enabled_channels=[0 1 2] nb_trans=1 tx_power_idx=0
 loraserver[1572]: time="2018-12-25T22:07:02+03:00" level=info msg="device-session saved" dev_addr=07affb0d dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:07:07+03:00" level=info msg="backend/gateway: rx packet received"
 loraserver[1572]: time="2018-12-25T22:07:07+03:00" level=info msg="packet(s) collected" dev_eui=343934354d377316 gw_count=1 gw_macs=aa555a0000000005 mtype=UnconfirmedDataUp
 loraserver[1572]: time="2018-12-25T22:07:07+03:00" level=info msg="rx info sent to network-controller" dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:07:07+03:00" level=info msg="device-session saved" dev_addr=07affb0d dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:07:13+03:00" level=info msg="backend/gateway: rx packet received"
 loraserver[1572]: time="2018-12-25T22:07:13+03:00" level=info msg="packet(s) collected" dev_eui=343934354d377316 gw_count=1 gw_macs=aa555a0000000005 mtype=UnconfirmedDataUp
 loraserver[1572]: time="2018-12-25T22:07:13+03:00" level=info msg="rx info sent to network-controller" dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:07:13+03:00" level=info msg="device-session saved" dev_addr=07affb0d dev_eui=343934354d377316
 loraserver[1572]: time="2018-12-25T22:07:21+03:00" level=info msg="backend/gateway: gateway stats packet received" mac=aa555a0000000005
 loraserver[1572]: time="2018-12-25T22:07:21+03:00" level=info msg="gateway updated" mac=aa555a0000000005

Lora-gateway-bridge:

 lora-gateway-bridge[1567]: time="2018-12-25T22:06:46+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullData
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:46+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:48+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushData
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:48+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.100.7:33746" data="AAb3ANB+1bNwFnM3TTU0OTSXgYbZvc8=" mac=aa555a0000000005
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:48+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/rx
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:48+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:48+03:00" level=info msg="backend: packet received" topic=gateway/aa555a0000000005/tx
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:48+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullResp
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:48+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33071" protocol_version=2 type=TXACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:48+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/ack
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:51+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushData
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:51+03:00" level=info msg="gateway: stat packet received" addr="192.168.100.7:33746" mac=aa555a0000000005
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:51+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:51+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:56+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullData
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:56+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:56+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushData
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:56+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.100.7:33746" data="QA37rweAAAAC9NtVmH93Soq1uE0dLzZF8R3CbMU=" mac=aa555a0000000005
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:56+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:56+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/rx
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:57+03:00" level=info msg="backend: packet received" topic=gateway/aa555a0000000005/tx
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:57+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullResp
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:57+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33071" protocol_version=2 type=TXACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:06:57+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/ack
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:01+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushData
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:01+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.100.7:33746" data="QA37rweCAQADBwLABBxzl6QbRl+9S2Vlem36EBOWoQ==" mac=aa555a0000000005
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:01+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:01+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/rx
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:06+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullData
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:06+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:07+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushData
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:07+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.100.7:33746" data="QA37rweAAgAC/Bsr5N6D9DQtrV1aglywe5oMtvQ=" mac=aa555a0000000005
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:07+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/rx
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:07+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:13+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushData
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:13+03:00" level=info msg="gateway: rxpk packet received" addr="192.168.100.7:33746" data="QA37rweAAwACyYvRCxvQq3Qd/aE+i4HWavx7yJY=" mac=aa555a0000000005
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:13+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/rx
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:13+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:16+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullData
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:16+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33071" protocol_version=2 type=PullACK
 lora-gateway-bridge[1567]: time="2018-12-25T22:07:21+03:00" level=info msg="gateway: received udp packet from gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushData
 pc lora-gateway-bridge[1567]: time="2018-12-25T22:07:21+03:00" level=info msg="gateway: stat packet received" addr="192.168.100.7:33746" mac=aa555a0000000005
 pc lora-gateway-bridge[1567]: time="2018-12-25T22:07:21+03:00" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
 pc lora-gateway-bridge[1567]: time="2018-12-25T22:07:21+03:00" level=info msg="gateway: sending udp packet to gateway" addr="192.168.100.7:33746" protocol_version=2 type=PushACK

End node:

INJOIN
OTAMlConOKSLoUpLink
SEND
LoRaUpLink
SEND
LoRaUpLink
SEND
LoRaUpLink

almaz · December 25, 2018, 7:29pm

But no luck with ver2 loraserver:

JSON up: {"rxpk":[{"tmst":43969380,"chan":1,"rfch":1,"freq":868.300000,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":-11.8,"rssi":-96,"size":23,"data":"AAb3ANB+1bNwFnM3TTU0OTSJQQV/qJc="}]}
INFO: [up] PUSH_ACK received in 36 ms
INFO: [down] PULL_RESP received  - token[69:206] :)

JSON down: {"txpk":{"imme":false,"tmst":48969380,"freq":868.3,"rfch":0,"powe":14,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":17,"data":"IErjdnmAEdrbWfSBmAqt0Bo=","brd":0,"ant":0}}
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
INFO: [down] PULL_ACK received in 36 ms

lora-app-server:

INFO[0079] js: request received                          message_type=JoinReq receiver_id=70b3d57ed000f706 sender_id=000000 transaction_id=3169447337
INFO[0079] device-keys updated                           dev_eui=343934354d377316
INFO[0079] js: sending response                          message_type=JoinAns receiver_id=000000 result_code=Success sender_id=70b3d57ed000f706 transaction_id=3169447337

loraserver:

INFO[0109] gateway/mqtt: gateway stats packet received   gateway_id=aa555a0000000005
INFO[0109] gateway updated                               gateway_id=aa555a0000000005
INFO[0121] gateway/mqtt: uplink frame received          
INFO[0121] packet(s) collected                           dev_eui=343934354d377316 gw_count=1 gw_ids=aa555a0000000005 mtype=JoinRequest
INFO[0121] device-queue flushed                          dev_eui=343934354d377316
INFO[0121] device-session saved                          dev_addr=010c6044 dev_eui=343934354d377316
INFO[0121] device-activation created                     dev_eui=343934354d377316 id=12
INFO[0121] gateway/mqtt: publishing downlink frame       qos=0 topic=gateway/aa555a0000000005/tx
INFO[0121] downlink-frames saved                         dev_eui=343934354d377316 token=52805
INFO[0121] backend/gateway: downlink tx acknowledgement received  gateway_id=aa555a0000000005
INFO[0139] gateway/mqtt: gateway stats packet received   gateway_id=aa555a0000000005
INFO[0139] gateway updated                               gateway_id=aa555a0000000005

lora-gateway-bridge:

time="2018-12-25T18:49:17Z" level=info msg="backend: subscribing to topic" qos=0 topic=gateway/aa555a0000000005/config
time="2018-12-25T18:49:47Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:49:47Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:50:17Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:50:17Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:50:47Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:50:47Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:51:17Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:51:17Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:51:47Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:51:47Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:52:17Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:52:17Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:52:47Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:52:47Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:53:17Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:53:17Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:53:47Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:53:47Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:54:14Z" level=info msg="gateway: rxpk packet received" addr="79.139.136.203:52799" data="AAb3ANB+1bNwFnU3UTU0OTQG69PV1VE=" mac=aa555a0000000005
time="2018-12-25T18:54:14Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/rx
time="2018-12-25T18:54:17Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:54:17Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:54:46Z" level=info msg="gateway: rxpk packet received" addr="79.139.136.203:52799" data="AAb3ANB+1bNwFnM3TTU0OTRBtxYknls=" mac=aa555a0000000005
time="2018-12-25T18:54:46Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/rx
time="2018-12-25T18:54:46Z" level=info msg="backend: downlink packet received" topic=gateway/aa555a0000000005/tx
time="2018-12-25T18:54:46Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/ack
time="2018-12-25T18:54:47Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:52799" mac=aa555a0000000005
time="2018-12-25T18:54:47Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T18:56:34Z" level=info msg="backend: unsubscribing from topic" topic=gateway/aa555a0000000005/tx
time="2018-12-25T18:56:34Z" level=info msg="backend: unsubscribing from topic" topic=gateway/aa555a0000000005/config
time="2018-12-25T19:24:34Z" level=info msg="backend: subscribing to topic" qos=0 topic=gateway/aa555a0000000005/tx
time="2018-12-25T19:24:34Z" level=info msg="backend: subscribing to topic" qos=0 topic=gateway/aa555a0000000005/config
time="2018-12-25T19:25:04Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:39921" mac=aa555a0000000005
time="2018-12-25T19:25:04Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats
time="2018-12-25T19:25:16Z" level=info msg="gateway: rxpk packet received" addr="79.139.136.203:39921" data="AAb3ANB+1bNwFnM3TTU0OTSJQQV/qJc=" mac=aa555a0000000005
time="2018-12-25T19:25:16Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/rx
time="2018-12-25T19:25:16Z" level=info msg="backend: downlink packet received" topic=gateway/aa555a0000000005/tx
time="2018-12-25T19:25:16Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/ack
time="2018-12-25T19:25:34Z" level=info msg="gateway: stat packet received" addr="79.139.136.203:39921" mac=aa555a0000000005
time="2018-12-25T19:25:34Z" level=info msg="backend: publishing packet" qos=0 topic=gateway/aa555a0000000005/stats

End node:

INJOIN
OTAMlConNO

almaz · December 25, 2018, 7:47pm

I use same:

LoRaWAN MAC version: 1.1.0
Lorawan regional parameters revision: A
Max EIRP = 16
Maximum allowed data-rate: 5
Minimum allowed data-rate: 0

almaz · December 25, 2018, 7:47pm

Can someone try to make Join procedure with my Loraserver to point me for some issue with it?

almaz · December 26, 2018, 4:43pm

Obvious. My end node is Lorawan v1.0.2)
After changing device profile in Lora-app-server for this I got join procedure passed successfully.
Don’t know how it worked on old version of Lora-App-Server.

brocaar · December 27, 2018, 3:36pm

I’m closing this topic as there are already other topics related to OTAA issues (with the same suggestion to make sure the mac version is set correctly).