Kerlink downlink failed


(Stef839) #1

Hello,

my Kerlink gateway cannot transmit downlinks although the semtech packet forwarder acknowledges it.

Here a log from the spf:

Feb 26 14:03:46 (none) local1.notice spf: ##### 2018-02-26 13:03:46 GMT #####
Feb 26 14:03:46 (none) local1.notice spf: ### [UPSTREAM] ###
Feb 26 14:03:46 (none) local1.notice spf: # RF packets received by concentrator: 0
Feb 26 14:03:46 (none) local1.notice spf: # CRC_OK: 0.00%, CRC_FAIL: 0.00%, NO_CRC: 0.00%
Feb 26 14:03:46 (none) local1.notice spf: # RF packets forwarded: 0 (0 bytes)
Feb 26 14:03:46 (none) local1.notice spf: # PUSH_DATA datagrams sent: 1 (113 bytes)
Feb 26 14:03:46 (none) local1.notice spf: # PUSH_DATA acknowledged: 100.00%
Feb 26 14:03:46 (none) local1.notice spf: ### [DOWNSTREAM] ###
Feb 26 14:03:46 (none) local1.notice spf: # PULL_DATA sent: 3 (100.00% acknowledged)
Feb 26 14:03:46 (none) local1.notice spf: # PULL_RESP(onse) datagrams received: 0 (0 bytes)
Feb 26 14:03:46 (none) local1.notice spf: # RF packets sent to concentrator: 0 (0 bytes)
Feb 26 14:03:46 (none) local1.notice spf: # TX errors: 0
Feb 26 14:03:46 (none) local1.notice spf: # TX rejected (collision packet): 0.00% (req:71, rej:0)
Feb 26 14:03:46 (none) local1.notice spf: # TX rejected (collision beacon): 0.00% (req:71, rej:0)
Feb 26 14:03:46 (none) local1.notice spf: # TX rejected (too late): 0.00% (req:71, rej:0)
Feb 26 14:03:46 (none) local1.notice spf: # TX rejected (too early): 0.00% (req:71, rej:0)
Feb 26 14:03:46 (none) local1.notice spf: # BEACON queued: 0
Feb 26 14:03:46 (none) local1.notice spf: # BEACON sent so far: 0
Feb 26 14:03:46 (none) local1.notice spf: # BEACON rejected: 0
Feb 26 14:03:46 (none) local1.notice spf: ### [JIT] ###
Feb 26 14:03:46 (none) local1.notice spf: lora_pkt_fwd/src/jitqueue.c:448:jit_print_queue(): INFO: [jit] queue is empty
Feb 26 14:03:46 (none) local1.notice spf: ### [GPS] ###
Feb 26 14:03:46 (none) local1.notice spf: # GPS sync is disabled
Feb 26 14:03:46 (none) local1.notice spf: ##### END #####
Feb 26 14:03:46 (none) local1.notice spf:
Feb 26 14:03:46 (none) local1.notice spf: JSON up: {“stat”:{“time”:“2018-02-26 13:03:46 GMT”,“rxnb”:0,“rxok”:0,“rxfw”:0,“ackr”:100.0,“dwnb”:0,“txnb”:0}}
Feb 26 14:03:46 (none) local1.notice spf: INFO: [up] PUSH_ACK received in 7 ms
Feb 26 14:03:48 (none) local1.notice spf: INFO: [down] PULL_ACK received in 5 ms
Feb 26 14:03:57 (none) local1.notice spf:
Feb 26 14:03:57 (none) local1.notice spf: INFO: Received pkt from mote: 60000100 (fcnt=46037)
Feb 26 14:03:57 (none) local1.notice spf:
Feb 26 14:03:57 (none) local1.notice spf: JSON up: {“rxpk”:[{“tmst”:2281043652,“chan”:5,“rfch”:1,“freq”:868.100000,“stat”:1,“modu”:“LORA”,“datr”:“SF7BW125”,“codr”:“4/5”,“lsnr”:9.2,“rssi”:-33,“size”:23,“data”:“AAABAGAy1bNw00MAYDLVs3DseJIRdzs=”}]}
Feb 26 14:03:57 (none) local1.notice spf: INFO: [up] PUSH_ACK received in 20 ms
Feb 26 14:03:57 (none) local1.notice spf: INFO: [down] PULL_RESP received - token[21:165] :slight_smile:
Feb 26 14:03:57 (none) local1.notice spf:
Feb 26 14:03:57 (none) local1.notice spf: JSON down: {“txpk”:{“imme”:false,“tmst”:2286043652,“freq”:868.1,“rfch”:0,“powe”:14,“modu”:“LORA”,“datr”:“SF7BW125”,“codr”:“4/5”,“ipol”:true,“size”:17,“data”:“IP0XHk3tBlmNRz+XCicfzMU=”,“brd”:0,“ant”:0}}
Feb 26 14:03:58 (none) local1.notice spf: INFO: [down] PULL_ACK received in 6 ms
Feb 26 14:04:08 (none) local1.notice spf: INFO: [down] PULL_ACK received in 5 ms
Feb 26 14:04:14 (none) local1.notice spf:
Feb 26 14:04:14 (none) local1.notice spf: INFO: Received pkt from mote: 60000100 (fcnt=46037)
Feb 26 14:04:14 (none) local1.notice spf:
Feb 26 14:04:14 (none) local1.notice spf: JSON up: {“rxpk”:[{“tmst”:2297806892,“chan”:6,“rfch”:1,“freq”:868.300000,“stat”:1,“modu”:“LORA”,“datr”:“SF8BW125”,“codr”:“4/5”,“lsnr”:11.8,“rssi”:-37,“size”:23,“data”:“AAABAGAy1bNw00MAYDLVs3CNJR1gFiE=”}]}
Feb 26 14:04:14 (none) local1.notice spf: INFO: [up] PUSH_ACK received in 5 ms
Feb 26 14:04:14 (none) local1.notice spf: INFO: [down] PULL_RESP received - token[128:214] :slight_smile:
Feb 26 14:04:14 (none) local1.notice spf:
Feb 26 14:04:14 (none) local1.notice spf: JSON down: {“txpk”:{“imme”:false,“tmst”:2302806892,“freq”:868.3,“rfch”:0,“powe”:14,“modu”:“LORA”,“datr”:“SF8BW125”,“codr”:“4/5”,“ipol”:true,“size”:17,“data”:“IBdZ10APOr+ucUGge2IjK7Y=”,“brd”:0,“ant”:0}}
Feb 26 14:04:15 (none) local1.notice spf:
Feb 26 14:04:15 (none) local1.notice spf: INFO: Disabling GPS mode for concentrator’s counter…
Feb 26 14:04:15 (none) local1.notice spf: INFO: host/sx1301 time offset=(1519647956s:226094µs) - drift=-372µs
Feb 26 14:04:15 (none) local1.notice spf: INFO: Enabling GPS mode for concentrator’s counter.
Feb 26 14:04:15 (none) local1.notice spf:
Feb 26 14:04:16 (none) local1.notice spf:
Feb 26 14:04:16 (none) local1.notice spf: ##### 2018-02-26 13:04:16 GMT #####
Feb 26 14:04:16 (none) local1.notice spf: ### [UPSTREAM] ###
Feb 26 14:04:16 (none) local1.notice spf: # RF packets received by concentrator: 8
Feb 26 14:04:16 (none) local1.notice spf: # CRC_OK: 25.00%, CRC_FAIL: 75.00%, NO_CRC: 0.00%
Feb 26 14:04:16 (none) local1.notice spf: # RF packets forwarded: 2 (46 bytes)
Feb 26 14:04:16 (none) local1.notice spf: # PUSH_DATA datagrams sent: 3 (526 bytes)
Feb 26 14:04:16 (none) local1.notice spf: # PUSH_DATA acknowledged: 100.00%
Feb 26 14:04:16 (none) local1.notice spf: ### [DOWNSTREAM] ###
Feb 26 14:04:16 (none) local1.notice spf: # PULL_DATA sent: 3 (100.00% acknowledged)
Feb 26 14:04:16 (none) local1.notice spf: # PULL_RESP(onse) datagrams received: 2 (388 bytes)
Feb 26 14:04:16 (none) local1.notice spf: # RF packets sent to concentrator: 1 (34 bytes)
Feb 26 14:04:16 (none) local1.notice spf: # TX errors: 0
Feb 26 14:04:16 (none) local1.notice spf: # TX rejected (collision packet): 0.00% (req:73, rej:0)
Feb 26 14:04:16 (none) local1.notice spf: # TX rejected (collision beacon): 0.00% (req:73, rej:0)
Feb 26 14:04:16 (none) local1.notice spf: # TX rejected (too late): 0.00% (req:73, rej:0)
Feb 26 14:04:16 (none) local1.notice spf: # TX rejected (too early): 0.00% (req:73, rej:0)
Feb 26 14:04:16 (none) local1.notice spf: # BEACON queued: 0
Feb 26 14:04:16 (none) local1.notice spf: # BEACON sent so far: 0
Feb 26 14:04:16 (none) local1.notice spf: # BEACON rejected: 0
Feb 26 14:04:16 (none) local1.notice spf: ### [JIT] ###
Feb 26 14:04:16 (none) local1.notice spf: lora_pkt_fwd/src/jitqueue.c:452:jit_print_queue(): INFO: [jit] queue contains 1 packets:
Feb 26 14:04:16 (none) local1.notice spf: lora_pkt_fwd/src/jitqueue.c:453:jit_print_queue(): INFO: [jit] queue contains 0 beacons:
Feb 26 14:04:16 (none) local1.notice spf: lora_pkt_fwd/src/jitqueue.c:459:jit_print_queue(): - node[0]: count_us=2302806892 - type=0
Feb 26 14:04:16 (none) local1.notice spf: ### [GPS] ###
Feb 26 14:04:16 (none) local1.notice spf: # GPS sync is disabled
Feb 26 14:04:16 (none) local1.notice spf: ##### END #####
Feb 26 14:04:16 (none) local1.notice spf:
Feb 26 14:04:16 (none) local1.notice spf: JSON up: {“stat”:{“time”:“2018-02-26 13:04:16 GMT”,“rxnb”:8,“rxok”:2,“rxfw”:2,“ackr”:100.0,“dwnb”:2,“txnb”:1}}
Feb 26 14:04:16 (none) local1.notice spf: INFO: [up] PUSH_ACK received in 39 ms
Feb 26 14:04:18 (none) local1.notice spf: INFO: [down] PULL_ACK received in 5 ms

Anybody who can help me why all my nodes don’t receive data from the gateway?


(Vitorfylyk) #2

I believe @stef839 was already able to solve this issue as a similar problem was described at https://forum.loraserver.io/t/otaa-with-kerlink-station-failed/911/7 with sort of the same username.

To help anyone else seeing the same thing (I also had the same issue), the solution was to upgrade the Kerlink gateway from version 2.x to 3.1.

I’ve upgrade it to 3.3 and the downlinks are working for me as well.