Packet-forwarder: ERROR: Packet REJECTED (jit error=2)

sagarpatel · August 11, 2021, 6:36am

Hi All,

We have installed more than 300+ streetlight application devices (CLASS C) and 2 Gateways on the field.

we are getting the below issue:

All devices are sending JoinRequest and the server is giving JoinAccept to the devices, but Gateway Packet Forwarder is rejecting the packet with the below error while sending JoinAccept/txpk to devices.

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":8256724,"freq":865.4025,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":17,"data":"IJswvADTOiS1ZT8juJ7
        Aug  5 20:13:17 nbl_ec2x local5.info lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ERROR: Packet REJECTED (jit error=2)

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":9256724,"freq":866.55,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":17,"data":"IJswvADTOiS1ZT8juJ77S
Aug  5 20:13:17 nbl_ec2x local5.info lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ERROR: Packet REJECTED (jit error=2)

Anyone can help me?

Thanks

Curt_Black · August 12, 2021, 10:04pm

Can you include the “JSON up” of those joins (which should be a few lines up)? “tmst” looks suspicious. Kinda looks like this log file is truncated and missing some lines also…

What gateway are you using? This is occurring 100% of the time, a device can never join? Or, this is intermittent?

sagarpatel · August 13, 2021, 4:01am

Hi @Curt_Black
Thanks for the reply, Please find below-attached logs.

Aug  5 20:13:11 nbl_ec2x local5.info lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:12 nbl_ec2x local5.info lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:12 nbl_ec2x local5.info lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:12 nbl_ec2x local5.info lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: 
{"rxpk":[{"tmst":21445692,"time":"2021-08-05T14:43:12.000000Z","chan":2,"rfch":1,"freq":865.985000,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":6.5,"rssi":-59,"size":23
Aug  5 20:13:13   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:13   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:14   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:14   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":23299716,"time":"2021-08-05T14:43:14.000000Z","chan":1,"rfch":0,"freq":865.402500,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":-9.2,"rssi":-109,"size":
Aug  5 20:13:14   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:14   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":23317300,"time":"2021-08-05T14:43:14.000000Z","chan":2,"rfch":1,"freq":865.985000,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":-10.8,"rssi":-111,"size"
Aug  5 20:13:14   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:14   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":23467884,"time":"2021-08-05T14:43:14.000000Z","chan":0,"rfch":0,"freq":865.062500,"stat":1,"modu":"LORA","datr":"SF11BW125","codr":"4/5","lsnr":-6.8,"rssi":-109,"size":
Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] received out-of-sync ACK  :: 2617
Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] received out-of-sync ACK  :: 2617
Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_ACK received in 3242 ms  :: 2614
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 00000000 (fcnt=0)  :: 1885
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":26125452,"time":"2021-08-05T14:43:17.000000Z","chan":0,"rfch":0,"freq":865.062500,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":3.2,"rssi":-97,"size":23
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_RESP received  - token[51:219] :)  :: 2624
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":8256724,"freq":865.4025,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":17,"data":"IJswvADTOiS1ZT8juJ7
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ERROR: Packet REJECTED (jit error=2)  :: 2932
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_RESP received  - token[51:219] :)  :: 2624
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":9256724,"freq":866.55,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":17,"data":"IJswvADTOiS1ZT8juJ77S
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ERROR: Packet REJECTED (jit error=2)  :: 2932
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":26538884,"time":"2021-08-05T14:43:17.000000Z","chan":2,"rfch":1,"freq":865.985000,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":-7.0,"rssi":-109,"size":
Aug  5 20:13:18   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:18   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":27674676,"time":"2021-08-05T14:43:18.000000Z","chan":1,"rfch":0,"freq":865.402500,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":-4.0,"rssi":-108,"size":
Aug  5 20:13:20   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:20   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":29450724,"time":"2021-08-05T14:43:20.000000Z","chan":0,"rfch":0,"freq":865.062500,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":-16.5,"rssi":-109,"size"
Aug  5 20:13:20   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:20   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":29918612,"time":"2021-08-05T14:43:20.000000Z","chan":2,"rfch":1,"freq":865.985000,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":6.0,"rssi":-44,"size":23
Aug  5 20:13:20   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:20   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":30114804,"time":"2021-08-05T14:43:20.000000Z","chan":2,"rfch":1,"freq":865.985000,"stat":1,"modu":"LORA","datr":"SF11BW125","codr":"4/5","lsnr":1.5,"rssi":-42,"size":23
Aug  5 20:13:22   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:22   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":31413340,"time":"2021-08-05T14:43:22.000000Z","chan":0,"rfch":0,"freq":865.062500,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":10.0,"rssi":-25,"size":2
Aug  5 20:13:22   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:22   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":31449628,"time":"2021-08-05T14:43:22.000000Z","chan":1,"rfch":0,"freq":865.402500,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":8.5,"rssi":-39,"size":23
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  ##### 2021-08-05 14:43:23 GMT #####  :: 1606
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ### [UPSTREAM] ###  :: 1607
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # RF packets received by concentrator: 68  :: 1608
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # CRC_OK: 61.76%, CRC_FAIL: 38.24%, NO_CRC: 0.00%  :: 1609
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # RF packets forwarded: 42 (959 bytes)  :: 1610
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # PUSH_DATA datagrams sent: 1 (244 bytes)  :: 1611
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # PUSH_DATA acknowledged: 0.00%  :: 1612
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ### [DOWNSTREAM] ###  :: 1613
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # PULL_DATA sent: 3 (33.33% acknowledged)  :: 1614
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # PULL_RESP(onse) datagrams received: 2 (388 bytes)  :: 1615
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # RF packets sent to concentrator: 0 (34 bytes)  :: 1616
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # TX errors: 0  :: 1617
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # TX rejected (collision packet): 0.00% (req:2, rej:0)  :: 1619
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # TX rejected (collision beacon): 0.00% (req:2, rej:0)  :: 1620
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # TX rejected (too late): 0.00% (req:2, rej:0)  :: 1621
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # TX rejected (too early): 100.00% (req:2, rej:2)  :: 1622
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # BEACON queued: 0  :: 1624
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # BEACON sent so far: 0  :: 1625
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # BEACON rejected: 0  :: 1626
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ### [JIT] ###  :: 1627
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # SX1301 time (PPS): 2514842  :: 1635
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ### [GPS] ###  :: 1638
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # Invalid time reference (age: 1628174603 sec)  :: 1644
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : # no valid GPS coordinates available yet  :: 1649
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ##### END #####  :: 1656
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"stat":{"time":"2021-08-05 14:43:23 GMT","rxnb":68,"rxok":42,"rxfw":42,"ackr":0.0,"dwnb":2,"txnb":0,"metadata":"OtherProperties=,rebooted=1"}}  :: 2191
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [up] PUSH_ACK received in 8 ms  :: 2260
Aug  5 20:13:23   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_ACK received in 0 ms  :: 2614
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":33338804,"time":"2021-08-05T14:43:24.000000Z","chan":1,"rfch":0,"freq":865.402500,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":-12.0,"rssi":-111,"size"
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [up] PUSH_ACK received in 0 ms  :: 3679
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: Internet connected :: 3684
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":33489420,"time":"2021-08-05T14:43:24.000000Z","chan":0,"rfch":0,"freq":865.062500,"stat":1,"modu":"LORA","datr":"SF11BW125","codr":"4/5","lsnr":-7.2,"rssi":-108,"size":
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [up] PUSH_ACK received in 0 ms  :: 3679
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: Internet connected :: 3684
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":33548172,"time":"2021-08-05T14:43:24.000000Z","chan":1,"rfch":0,"freq":865.402500,"stat":1,"modu":"LORA","datr":"SF11BW125","codr":"4/5","lsnr":-6.0,"rssi":-109,"size":
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [up] PUSH_ACK received in 0 ms  :: 3679
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: Internet connected :: 3684
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_RESP received  - token[189:0] :)  :: 2624
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":38338804,"freq":865.4025,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":17,"data":"IJOVy1Y3oZ/ADcAo3D
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_RESP received  - token[106:254] :)  :: 2624
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":38548172,"freq":865.4025,"modu":"LORA","datr":"SF11BW125","codr":"4/5","ipol":true,"size":17,"data":"IFCvB//dhvyRJZfcCL
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ERROR: Packet REJECTED (jit error=5)  :: 2932
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_RESP received  - token[106:254] :)  :: 2624
Aug  5 20:13:24   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":39548172,"freq":866.55,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":17,"data":"IFCvB//dhvyRJZfcCLal
Aug  5 20:13:25   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:25   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:25   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 00000000 (fcnt=0)  :: 1885
Aug  5 20:13:14   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:14   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":23467884,"time":"2021-08-05T14:43:14.000000Z","chan":0,"rfch":0,"freq":865.062500,"stat":1,"modu":"LORA","datr":"SF11BW125","codr":"4/5","lsnr":-6.8,"rssi":-109,"size":
Aug  5 20:13:15   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] received out-of-sync ACK  :: 2617
Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] received out-of-sync ACK  :: 2617
Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_ACK received in 3242 ms  :: 2614
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 00000000 (fcnt=0)  :: 1885
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":26125452,"time":"2021-08-05T14:43:17.000000Z","chan":0,"rfch":0,"freq":865.062500,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":3.2,"rssi":-97,"size":23
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_RESP received  - token[51:219] :)  :: 2624
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":8256724,"freq":865.4025,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":17,"data":"IJswvADTOiS1ZT8juJ7
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ERROR: Packet REJECTED (jit error=2)  :: 2932
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_RESP received  - token[51:219] :)  :: 2624
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":9256724,"freq":866.55,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":17,"data":"IJswvADTOiS1ZT8juJ77S
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : ERROR: Packet REJECTED (jit error=2)  :: 2932
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":26538884,"time":"2021-08-05T14:43:17.000000Z","chan":2,"rfch":1,"freq":865.985000,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":-7.0,"rssi":-109,"size":
Aug  5 20:13:18   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  INFO: Received pkt from mote: 01010101 (fcnt=257)  :: 1885
Aug  5 20:13:18   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":27674676,"time":"2021-08-05T14:43:18.000000Z","chan":1,"rfch":0,"freq":865.402500,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":-4.0,"rssi":-108,"size":
Aug  5 20:13:19   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:20   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064

Gateway: we are using our Gateway
Device: only 10-15 devices are joined out of 300+ (All the devices are sending JoinRequests)

Thanks

Curt_Black · August 13, 2021, 9:01am

@sagarpatel The logfile is still truncated to certain line length. Are you using a terminal program that is not copy/pasting or including the full lines?

Curt_Black · August 13, 2021, 9:07am

@brocaar despite the truncated logs, the ‘tmst’ seems goofy here - any idea on this few lines?

Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON up: {"rxpk":[{"tmst":26125452,"time":"2021-08-05T14:43:17.000000Z","chan":0,"rfch":0,"freq":865.062500,"stat":1,"modu":"LORA","datr":"SF12BW125","codr":"4/5","lsnr":3.2,"rssi":-97,"size":23
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : WARNING: [gps] could not get GPS time from GPS  :: 3064
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_RESP received  - token[51:219] :)  :: 2624
Aug  5 20:13:17   lora_pkt_fwd: [I] src/lora_pkt_fwd.c :  JSON down: {"txpk":{"imme":false,"rfch":0,"powe":27,"ant":0,"brd":0,"tmst":8256724,"freq":865.4025,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":17,"data":"IJswvADTOiS1ZT8juJ7

The uplink tmst is 26,125,452, but the join accept reply is tmst of 8,256,724 – obviously very wrong. Rollover? wrong Down reply for that Up?

sagarpatel · August 16, 2021, 4:22am

Hi @Curt_Black @brocaar ,

Please finds attached the logs file…

Download | file.io

Thanks

brocaar · August 17, 2021, 8:54am

Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] PULL_ACK received in 3242 ms  :: 2614

It took > 3 seconds to receive the PULL_ACK, I think your network has a very high latency and therefore you might want to tune your rx1_delay to make sure there is enough roundtrip time.

Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] received out-of-sync ACK  :: 2617
Aug  5 20:13:16   lora_pkt_fwd: [I] src/lora_pkt_fwd.c : INFO: [down] received out-of-sync ACK  :: 2617

Also it seems that the ACKs do not arrive in the correct order, which could be caused by the high latency if the latency is not predictable.

sagarpatel · August 17, 2021, 10:17am

Hii @brocaar ,

Thanks for your quick reply.

Is it safe to increase rx1_delay?

brocaar · August 17, 2021, 12:04pm

I believe the drawback from increasing it too much might be timedrift at the device-side, depending its clock source. Depending the firmware implementation, this could mean that the device will open its receive-window a bit earlier and closing it a bit later to avoid missing any downlinks, which means that the power-consumption will increase.