LoRa Server receives just few frames from dozens sent.

[Kamil Gardziejczyk]

Hi,

We are trying to setup LoRa Server and our end-devices. The devices use IBM lmic lib. Basically, we send simple frame every 5s – I can “see” sent frames using SDR-RTL.

The problem is that I can see just few frames sent from end device. Could some verify if may transmission parameters are correct?

This is log of received frame:

15:10:40:421|DEBUG| Received packet
================================
000 40 b5 20 01 00 80 38 00
008 01 df dd 8d b7 a6 66 a4
010 bf f7 66 7e b0 b8 89 bc
018 f1 1c 3e 25 a9

15:10:40:421|DEBUG| Rx on 868100000, rssi: -37 snr: 88
15:10:40:421|DEBUG| Received frame: type: Unconfirmed Up
15:10:40:422|DEBUG| Packet received from Node 00:01:20:b5 GW 00:80:00:00:00:00:bf:a3 (127.0.0.1) Seq# 38
15:10:40:423|DEBUG| Set node active: 1
15:10:40:424|DEBUG| Expecting packet SeqNo: 0000002e
15:10:40:424|DEBUG| Check for dup: 0038 == 002e
15:10:40:424|TRACE| Checking SeqNo: 00000038
15:10:40:425|TRACE| AUTH KEY: 2b.7e.15.16.28.ae.d2.a6.ab.f7.15.88.09.cf.4f.3c
15:10:40:425|DEBUG| MIC Check: 1c3e25a9 == 1c3e25a9
15:10:40:426|INFO| SeqNo: 00000038 PrevSeqNo: 0000002e Duplicate: no

15:10:40:426|INFO| Addr: 00:01:20:b5 MIC Check: passed
15:10:40:426|DEBUG| update bestGateway
15:10:40:427|DEBUG| Time: 402110548
15:10:40:427|DEBUG| App Queue Length: 0
15:10:40:428|DEBUG| NewFrame
15:10:40:428|DEBUG| 0 : 00:80:00:00:00:00:bf:a3 == 00:80:00:00:00:00:bf:a3 1
15:10:40:428|DEBUG| update gateway 00:80:00:00:00:00:bf:a3
15:10:40:429|DEBUG| DR Index sf: 12 bw: 125 index: 0
15:10:40:429|DEBUG| Rx Frame SEQ 56 SNR 88 SF 0
15:10:40:429|DEBUG| ADR FindDR : snr : 88 step : 30
15:10:40:430|DEBUG| ADR PRE MIN/MAX DR: 9
15:10:40:430|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:431|DEBUG| ADR update avgSnr store size: 8 snr: 88 avg: -250
15:10:40:431|DEBUG| ADR update avgSnr store size: 8 snr: 88 avg: -208
15:10:40:431|DEBUG| ADR FindDR : snr : -250 step : 30
15:10:40:432|DEBUG| ADR PRE MIN/MAX DR: 255
15:10:40:432|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:433|DEBUG| ADR update avgSnr store size: 8 snr: -250 avg: -208
15:10:40:433|DEBUG| ADR update avgSnr store size: 8 snr: -250 avg: -213
15:10:40:433|DEBUG| ADR FindDR : snr : -250 step : 30
15:10:40:434|DEBUG| ADR PRE MIN/MAX DR: 255
15:10:40:434|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:434|DEBUG| ADR update avgSnr store size: 9 snr: -250 avg: -213
15:10:40:435|DEBUG| ADR update avgSnr store size: 9 snr: -250 avg: -217
15:10:40:435|DEBUG| ADR FindDR : snr : -250 step : 30
15:10:40:436|DEBUG| ADR PRE MIN/MAX DR: 255
15:10:40:436|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:436|DEBUG| ADR update avgSnr store size: 10 snr: -250 avg: -217
15:10:40:436|DEBUG| ADR update avgSnr store size: 10 snr: -250 avg: -221
15:10:40:437|DEBUG| ADR FindDR : snr : -250 step : 30
15:10:40:437|DEBUG| ADR PRE MIN/MAX DR: 255
15:10:40:438|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:438|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -221
15:10:40:438|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -223
15:10:40:439|DEBUG| ADR FindDR : snr : -250 step : 30
15:10:40:439|DEBUG| ADR PRE MIN/MAX DR: 255
15:10:40:440|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:440|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -223
15:10:40:441|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -225
15:10:40:441|DEBUG| ADR FindDR : snr : -250 step : 30
15:10:40:441|DEBUG| ADR PRE MIN/MAX DR: 255
15:10:40:442|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:442|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -225
15:10:40:442|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -227
15:10:40:443|DEBUG| ADR FindDR : snr : -250 step : 30
15:10:40:443|DEBUG| ADR PRE MIN/MAX DR: 255
15:10:40:443|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:444|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -227
15:10:40:444|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -229
15:10:40:444|DEBUG| ADR FindDR : snr : -250 step : 30
15:10:40:445|DEBUG| ADR PRE MIN/MAX DR: 255
15:10:40:445|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:446|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -229
15:10:40:446|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -231
15:10:40:446|DEBUG| ADR FindDR : snr : -250 step : 30
15:10:40:447|DEBUG| ADR PRE MIN/MAX DR: 255
15:10:40:447|DEBUG| ADR NEW DR: 5 MIN: 0 MAX: 5 STEP: 30
15:10:40:448|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -231
15:10:40:448|DEBUG| ADR update avgSnr store size: 11 snr: -250 avg: -232
15:10:40:448|DEBUG| FCtrl 80 00 0
15:10:40:449|DEBUG| RX frame is class A
15:10:40:449|DEBUG| FCtrl 80 00 0
15:10:40:450|TRACE| Updating last recv'd packet info
15:10:40:450|TRACE| SQL query = UPDATE packets SET port = 1, seqno = 56, gateway = "008000000000bfa3", time = "2017-01-17T15:10:40Z", microseconds = 416633, rssi = -37, channel = 0, lsnr_cB = 88, spread = 12, modulationbandwidth = 125, data = "41424344313233343132333431323334" WHERE node = "000120b5"
15:10:40:452|DEBUG| Send MQTT message
15:10:40:452|DEBUG| UDP message: lora/01-23-45-67-89-ab-cd-ef/packet_recv {"chan":0,"codr":"4/5","data":"QLUgAQCAOAAB392Nt6ZmpL/3Zn6wuIm88Rw+Jak=","datr":"SF12BW125","freq":868.10000000000002,"lsnr":8.8000000000000007,"modu":"LORA","rfch":0,"rssi":-37,"size":29,"stat":1,"time":"2017-01-17T15:10:40.416633Z","tmst":402110548}
15:10:40:453|DEBUG| UDP port: 1784
15:10:40:456|TRACE| Published message: 33
15:10:40:460|TRACE| SQL query = UPDATE nodes SET lastuppacketid = 1, lastmessagems = 39 WHERE address = "000120b5";
15:10:40:463|TRACE| SQL query = UPDATE gateways SET lastuppacketid = 1 WHERE address = "008000000000bfa3";
15:10:40:468|DEBUG| FCtrl 80 00 0
15:10:40:470|TRACE| Packet placed in MQTT message queue
15:10:40:471|INFO| Packet accepted from Node 00:01:20:b5 GW 00:80:00:00:00:00:bf:a3 (127.0.0.1) Seq# 38 ADR enabled SF12BW125
15:10:40:471|DEBUG| Schedule slot in tx queue
15:10:40:472|DEBUG| getTimeOnAirMs dr: 12 bw: 0 pl: 8 sz: 13 toa: 1155
15:10:40:472|INFO| Schedule TX Time on air: 1165 ms
15:10:40:472|DEBUG| BestGatewayChannel::scheduleAt 1056032463 1165 868100000
15:10:40:473|DEBUG| 0 : 00:80:00:00:00:00:bf:a3
15:10:40:473|DEBUG| now: 1056031766
15:10:40:473|DEBUG| time: 1056032463 duration: 1165 freq: 868100000
15:10:40:474|DEBUG| returning 00:80:00:00:00:00:bf:a3
15:10:40:479|DEBUG| Send MQTT message
15:10:40:480|DEBUG| UDP message: lora/01-23-45-67-89-ab-cd-ef/up {"chan":0,"cls":0,"codr":"4/5","data":"QUJDRDEyMzQxMjM0MTIzNA==","datr":"SF12BW125","freq":"868.1","lsnr":"8.8","mhdr":"40b5200100803800","modu":"LORA","opts":"","port":1,"rfch":0,"rssi":-37,"seqn":56,"size":24,"timestamp":"2017-01-17T15:10:40.416633Z","tmst":402110548}
15:10:40:480|DEBUG| UDP port: 1784
15:10:40:497|TRACE| MQTT message: lora/01-23-45-67-89-ab-cd-ef/packet_recv
15:10:40:498|TRACE| SQL query = SELECT address FROM nodes WHERE deveui = "0123456789abcdef";
15:10:40:499|DEBUG| Mosquitto command received 'packet_recv'
15:10:40:500|TRACE| Unknown command 'packet_recv
15:10:40:508|TRACE| Published message: 34
15:10:40:509|TRACE| MQTT message: lora/01-23-45-67-89-ab-cd-ef/up
15:10:40:510|TRACE| SQL query = SELECT address FROM nodes WHERE deveui = "0123456789abcdef";
15:10:40:512|DEBUG| Mosquitto command received 'up'
15:10:40:512|TRACE| Unknown command 'up
15:10:41:174|DEBUG| is frame ready?
15:10:41:175|DEBUG| App Queue Length: 0
15:10:41:175|DEBUG| ADR rxDR : 0 stDR : 5 avgSnr: -232 pwr: 14
15:10:41:175|DEBUG| Frame is not ready
15:10:41:175|DEBUG| Set node active: 0
15:10:48:908|TRACE| Parse downstream message 12 bytes
15:10:48:909|TRACE| Gateway 00:80:00:00:00:00:bf:a3 seen IP address 127.0.0.1:33320


Kamil,

[Jason Reiss]

Did you enable public compatibility mode?

“network”: { “public”: true, … }

[Kamil Gardziejczyk]

Yes I did.


{"udp": {"appPortUp": 1784, "appPortDown": 1786, "downstreamPort": 1782, "upstreamPort": 1780}, "log": {"syslog": false, "path": "/var/log/lora-network-server.log", "console": true, "level": 100}, "whitelist": {"enabled": true, "devices": []}, "addressRange": {"start": "00:00:00:01", "end": "FF:FF:FF:FE"}, "db": "/var/run/lora/lora-net-server.db", "mqtt": {"host": "127.0.0.1", "enabled": true, "port": 1883}, "__v": 1, "lora": {"rx1DatarateOffset": 0, "frequencyBand": 868, "nodeQueueSize": 16, "enabled": true, "rx2Datarate": 12, "packetForwarderConfig": "", "frequencySubBand": 1, "maxTxPower": 26, "packetForwarderMode": false, "frequencyEU": 869500000}, "network": {"key": "2b7e151628aed2a6abf7158809cf4f3c", "leasetime": 86400, "name": "", "passphrase": "", "eui": "0123456789ABCDEF", "public": true}}

[Jason Reiss]

What channels is your client set up to use? Do you use OTA join?

Conduit will listen on 868.1,868.3,868.5, 869.1,869.3,869.5,869.7,869.9 with your settings.

[Jason Reiss]

From LMIC code EU868 has the following channels defined.
If these channels are not modified there will be a mismatch causing packet loss.

// Default frequency plan for EU 868MHz ISM band
// Bands:
// g1 : 1% 14dBm
// g2 : 0.1% 14dBm
// g3 : 10% 27dBm
// freq band datarates
enum { EU868_F1 = 868100000, // g1 SF7-12
EU868_F2 = 868300000, // g1 SF7-12 FSK SF7/250
EU868_F3 = 868500000, // g1 SF7-12
EU868_F4 = 868850000, // g2 SF7-12
EU868_F5 = 869050000, // g2 SF7-12
EU868_F6 = 869525000, // g3 SF7-12
EU868_J4 = 864100000, // g2 SF7-12 used during join
EU868_J5 = 864300000, // g2 SF7-12 ditto
EU868_J6 = 864500000, // g2 SF7-12 ditto
};

[Kamil Gardziejczyk]

Hi,

Sorry for a delay.

I have modified channel list to:
enum { EU868_F1 = 868100000, // g1 SF7-12
EU868_F2 = 868300000, // g1 SF7-12 FSK SF7/250
EU868_F3 = 868500000, // g1 SF7-12
EU868_F4 = 869100000, // g2 SF7-12
EU868_F5 = 869300000, // g2 SF7-12
EU868_F6 = 869500000, // g3 SF7-12
EU868_J4 = 869700000, // g2 SF7-12 used during join
EU868_J5 = 869900000, // g2 SF7-12 ditto
EU868_J6 = 868100000, // g2 SF7-12 ditto

Wasn`t sure what should but under EU868_J6.
I still observe this issue. Is there anything more I need to check?

[lorawan2016]

Hi Kamil,

I don’t know if you already have solved the problem.
What’s the distance between the end-device and the gateway?

[Kamil Gardziejczyk]

Hi,

Sorry for not marking this done.
The GW was set to work as public, but the endpoint preamble was set to private.

Big Thanks for support.

Kamil,

[Author]

Posts