search

things4u / ESP-1ch-Gateway

Version 6 of the single channel gateway
MIT License
364 stars 147 forks source link

ack_lost, uplinks_missed and uplinks_lost with lorawan-server #34

Open MihaMarkic opened 4 years ago

MihaMarkic commented 4 years ago

Hi guys,

I'm seeing a lot of ack_lost, uplinks_missed and uplinks_lost events with lorawan_server. Both gateway and server are located in the same room on the same network. How do I even start to figure out why the errors? Note: the data seem coming through (from device to server via gateway).

I've set debug to 3 via web ui and looking at serial monitor I don't see any obvious problem. Typical output is below:

Hi guys,

I'm seeing a lot of ack_lost, uplinks_missed and uplinks_lost events with lorawan_server. Both gateway and server are located in the same room on the same network. How do I even start to figure out why the errors? Note: the data seem coming through (from device to server via gateway).

I've set debug to 3 via web ui and looking at serial monitor I don't see any obvious problem. Typical output is below (first three IP bytes are represented as MYSUBNET):

Thu 14-05-2020 17:04:27- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=592.976816 Thu 14-05-2020 17:04:27- loop:: readUdp available Thu 14-05-2020 17:04:27- Dwn readUdp:: message ident=4 Thu 14-05-2020 17:04:27- Dwn readUdp PKT_PULL_ACK: micr=593.493082, size=4 From MYSUBNET.5, port 1680 , data: 1:F:BD:4: Thu 14-05-2020 17:04:27- Dwn readUdp:: ret=4, data=1 15 189 4 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- S_RX no INTR:: 0 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- RX HEADER:: 16 Thu 14-05-2020 17:04:28- R rxPkt:: CRC used Thu 14-05-2020 17:04:28- UP receivePkt:: rxPkt: t=Thu 14-05-2020 17:04:28, f=0, sf=10, a=56:ed:0c:fb:, flags=50, addr=0, len=26 Thu 14-05-2020 17:04:28- RXDONE:: dT=388169I=RXDONE HEADER , F=0, SF=10, E=0, S=RX , eT=9339533, dT=417994 Thu 14-05-2020 17:04:28- UP receivePacket:: start: micr=594.846694, tmst=406.992917 R buildPacket:: pRSSI=-83 RSSI: -129 SNR: 12 Length: 26 -> 40 FB C ED 56 80 30 0 1 E4 D A5 12 3 F A5 80 28 C9 54 D7 48 C7 B1 4F 66 Thu 14-05-2020 17:04:28- UP RXPK:: {"rxpk":[{"chan":0,"rfch":0,"freq":868.099975,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":12,"rssi":-83,"size":26,"data":"QPsM7VaAMAAB5A2lEgMPpYAoyVTXSMexT2Y=","tmst":594866936}]} , length=209 Thu 14-05-2020 17:04:28- sendUdp: WlanConnect connected to=MYSSID. Server IP=MYSUBNET.103 Thu 14-05-2020 17:04:28- UP receivePacket:: sendUdp: micr=594.898051, tmst=594.866936 Thu 14-05-2020 17:04:28- receivePacket:: No Index Thu 14-05-2020 17:04:28- loop:: readUdp available Thu 14-05-2020 17:04:28- Dwn readUdp:: message ident=1 Thu 14-05-2020 17:04:28- Dwn PKT_PUSH_ACK:: size=4, From MYSUBNET.5 Thu 14-05-2020 17:04:28- Dwn readUdp:: ret=4, data=1 47 161 1 Thu 14-05-2020 17:04:29- loop:: readUdp available Thu 14-05-2020 17:04:29- Dwn readUdp:: message ident=3 Thu 14-05-2020 17:04:29- Dwn PKT_PULL_RESP:: sendPacket: micros=595486920 Thu 14-05-2020 17:04:29- Dwn sendPacket:: {"txpk":{"imme":false,"tmst":595866936,"freq":868.099975,"rfch":0,"powe":10,"modu":"LORA","datr":"SF10BW125","codr":"4/5","ipol":true,"size":13,"data":"YPsM7VaBCgAGAWhZlg=="}}< Thu 14-05-2020 17:04:29- sendPacket:: data=YPsM7VaBCgAGAWhZlg== Thu 14-05-2020 17:04:29- loraPacket:: RX1: micros=595516147 Thu 14-05-2020 17:04:29- Dwn sendPacket:: TX tmst=595866936 T Payload=60:FB:C:ED:56:81:A:0:6:1:68:59:96: Thu 14-05-2020 17:04:29- sendPacket:: STRICT=1 Thu 14-05-2020 17:04:29- Dwn txLoraModem:: end: micr=595.867404, tmst=595.866936, wait=(468), SF=10, Freq=868100000, a=56:ed:0c:fb: Thu 14-05-2020 17:04:29- Dwn PKT_PULL_RESP:: txLoraModem done: micr=595.868491, tmst=595.866936, wait=(1555), SF=10, Freq=868100000, a=56:ed:0c:fb: Thu 14-05-2020 17:04:29- Dwn txLoraModem fini:: I= -- , F=0, SF=7, E=1, S=TX , eT=974481, dT=396640 Thu 14-05-2020 17:04:29- Dwn readUdp:: PKT_PULL_RESP from IP=MYSUBNET.5, micros=595890665, wait=399538 Thu 14-05-2020 17:04:29- UP readUdp:: PKT_TX_ACK: protocol version 1 Thu 14-05-2020 17:04:29- Dwn readUdp:: ret=179, data=1 86 110 3 116 120 112 107 0 105 109 109 101 0 116 109 115 116 0 102 114 101 113 0 114 102 99 104 0 112 111 119 101 0 109 111 100 117 0 76 79 82 65 0 100 97 116 114 0 83 70 49 48 66 87 49 50 53 0 99 111 100 114 0 52 47 53 0 105 112 111 108 0 115 105 122 101 0 100 97 116 97 0 89 80 115 77 55 86 97 66 67 103 65 71 65 87 104 90 108 103 61 61 0 49 48 66 87 49 50 53 34 44 34 99 111 100 114 34 58 34 52 47 53 34 44 34 105 112 111 108 34 58 116 114 117 101 44 34 115 105 122 101 34 58 49 51 44 34 100 97 116 97 34 58 34 89 80 115 77 55 86 97 66 67 103 65 71 65 87 104 90 108 103 61 61 34 125 125 Thu 14-05-2020 17:04:30- Dwns TXDONE:: OK: rcvd=596.156352, diff=0.289474 Thu 14-05-2020 17:04:30- TXDONE:: done OK Thu 14-05-2020 17:04:42- SCAN:: I=CDDONE CDDETD , F=0, SF=7, E=0, S=RX , eT=13414520, dT=2241 Thu 14-05-2020 17:04:42- S_RX no INTR:: 0 Thu 14-05-2020 17:04:42- S_RX no INTR:: 0 Thu 14-05-2020 17:04:42- S_RX no INTR:: 0 Thu 14-05-2020 17:04:42- RXTOUT:: I=RXTOUT , F=0, SF=7, E=0, S=RX , eT=13423856, dT=11551 Thu 14-05-2020 17:04:47- sendUdp: WlanConnect connected to=MYSSID. Server IP=MYSUBNET.103 Thu 14-05-2020 17:04:47- M PKT_PULL_DATA request, len=12 1:F7:F:2:24:6F:28:FF:FF:B1:BF:84: Thu 14-05-2020 17:04:47- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=612.976953 Thu 14-05-2020 17:04:47- loop:: readUdp available Thu 14-05-2020 17:04:47- Dwn readUdp:: message ident=4 Thu 14-05-2020 17:04:47- Dwn readUdp PKT_PULL_ACK: micr=612.993816, size=4 From MYSUBNET.5, port 1680 , data: 1:F7:F:4: Thu 14-05-2020 17:04:47- Dwn readUdp:: ret=4, data=1 247 15 4 Thu 14-05-2020 17:05:00- REINIT:: 31 I= -- , F=0, SF=7, E=1, S=SCAN , eT=17632933, dT=2301 Thu 14-05-2020 17:05:07- sendUdp: WlanConnect connected to=MYSSID. Server IP=MYSUBNET.103 Thu 14-05-2020 17:05:07- M PKT_PULL_DATA request, len=12 1:D9:68:2:24:6F:28:FF:FF:B1:BF:84: Thu 14-05-2020 17:05:07- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=632.977030 Thu 14-05-2020 17:05:07- loop:: readUdp available Thu 14-05-2020 17:05:07- Dwn readUdp:: message ident=4 Thu 14-05-2020 17:05:07- Dwn readUdp PKT_PULL_ACK: micr=632.993875, size=4 From MYSUBNET.5, port 1680 , data: 1:D9:68:4: Thu 14-05-2020 17:05:07- Dwn readUdp:: ret=4, data=1 217 104 4 Thu 14-05-2020 17:05:11- SCAN:: I=CDDONE CDDETD , F=0, SF=7, E=0, S=RX , eT=28807010, dT=2370 Thu 14-05-2020 17:05:11- S_RX no INTR:: 0 Thu 14-05-2020 17:05:11- S_RX no INTR:: 0 Thu 14-05-2020 17:05:11- S_RX no INTR:: 0 Thu 14-05-2020 17:05:11- RXTOUT:: I=RXTOUT , F=0, SF=7, E=0, S=RX , eT=28816403, dT=11727 Thu 14-05-2020 17:05:20- UP ESP-sc-gway:: RST_DATA message sent: micr=645.993085 Thu 14-05-2020 17:05:23- SCAN:: I=CDDONE CDDETD , F=0, SF=7, E=0, S=RX , eT=12484708, dT=2283 Thu 14-05-2020 17:05:23- S_RX no INTR:: 0 Thu 14-05-2020 17:05:23- S_RX no INTR:: 0 Thu 14-05-2020 17:05:23- S_RX no INTR:: 0 Thu 14-05-2020 17:05:23- RXTOUT:: I=RXTOUT , F=0, SF=7, E=0, S=RX , eT=12494021, dT=11585 Thu 14-05-2020 17:05:27- sendUdp: WlanConnect connected to=MYSSID. Server IP=MYSUBNET.103 Thu 14-05-2020 17:05:27- M PKT_PULL_DATA request, len=12 1:F4:D9:2:24:6F:28:FF:FF:B1:BF:84: Thu 14-05-2020 17:05:27- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=652.976872 Thu 14-05-2020 17:05:27- loop:: readUdp available Thu 14-05-2020 17:05:27- Dwn readUdp:: message ident=4 Thu 14-05-2020 17:05:27- Dwn readUdp PKT_PULL_ACK: micr=653.493085, size=4 From MYSUBNET.5, port 1680 , data: 1:F4:D9:4: Thu 14-05-2020 17:05:27- Dwn readUdp:: ret=4, data=1 244 217 4 Thu 14-05-2020 17:05:28- M stat update: <224> {"stat":{"time":"2020-05-14 17:05:28 CET","lati":52.23736,"long":5.97865,"alti":14,"rxnb":4,"rxok":4,"rxfw":4,"ackr":0.0,"dwnb":0,"txnb":0,"pfrm":"ESP32","mail":"My e-mail","desc":"Mihies Gateway to Hell"}} Thu 14-05-2020 17:05:28- sendUdp: WlanConnect connected to=MYSSID. Server IP=MYSUBNET.103 Thu 14-05-2020 17:05:28- Send Pushdata sendstat Thu 14-05-2020 17:05:28- loop:: readUdp available Thu 14-05-2020 17:05:28- Dwn readUdp:: message ident=1 Thu 14-05-2020 17:05:28- Dwn PKT_PUSH_ACK:: size=4, From MYSUBNET.5 Thu 14-05-2020 17:05:28- Dwn readUdp:: ret=4, data=1 60 168 1 Thu 14-05-2020 17:05:30- SCAN:: I=CDDONE CDDETD , F=0, SF=7, E=0, S=RX , eT=6700312, dT=2376 Thu 14-05-2020 17:05:30- S_RX no INTR:: 0 Thu 14-05-2020 17:05:30- S_RX no INTR:: 0 Thu 14-05-2020 17:05:30- S_RX no INTR:: 0 Thu 14-05-2020 17:05:30- S_RX no INTR:: 0 Thu 14-05-2020 17:05:30- RXTOUT:: I=RXTOUT , F=0, SF=7, E=0, S=RX , eT=6713261, dT=15290 Thu 14-05-2020 17:05:47- sendUdp: WlanConnect connected to=MYSSID. Server IP=MYSUBNET.103 Thu 14-05-2020 17:05:47- M PKT_PULL_DATA request, len=12 1:35:E5:2:24:6F:28:FF:FF:B1:BF:84: Thu 14-05-2020 17:05:47- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=672.976849 Thu 14-05-2020 17:05:47- loop:: readUdp available Thu 14-05-2020 17:05:47- Dwn readUdp:: message ident=4 Thu 14-05-2020 17:05:47- Dwn readUdp PKT_PULL_ACK: micr=672.993680, size=4 From MYSUBNET.5, port 1680 , data: 1:35:E5:4: Thu 14-05-2020 17:05:47- Dwn readUdp:: ret=4, data=1 53 229 4 Thu 14-05-2020 17:05:49- SCAN:: I=CDDONE CDDETD , F=0, SF=7, E=0, S=RX , eT=18823241, dT=2351 Thu 14-05-2020 17:05:49- S_RX no INTR:: 0 Thu 14-05-2020 17:05:49- S_RX no INTR:: 0 Thu 14-05-2020 17:05:49- S_RX no INTR:: 0 Thu 14-05-2020 17:05:49- RXTOUT:: I=RXTOUT , F=0, SF=7, E=0, S=RX , eT=18832644, dT=11718

platenspeler commented 4 years ago

Better not use debug levels larger than 1, since the timing of the gateway becomes influenced. Sometimes use 2 if you are debugging but normally not level 3.

What version of the gateway are you using (listed on the top of the webpage)? And are you using Arduino IDE or PlatformIO? What debug patterns did you select on the GUI?

Maarten

MihaMarkic commented 4 years ago

OK, no more level > 1. It's Version: V.6.2.4.EU868; PlatformIO 200428n with PlatformIO (obviously). For the given listing I used level 2 with almost everything except SCN and possible PRE I think.

platenspeler commented 4 years ago

OK,

Try debug==level 1, and select RX and MAIN. Can you send a small part of the output to Serial? With debug==3 a lot seems to be right.

Maarten

MihaMarkic commented 4 years ago

This is on gateway (write failures happen when I change debug settings, shouldn't matter I guess)

[E][WiFiClient.cpp:392] write(): fail on fd 56, errno: 104, "Connection reset by peer"
[E][WiFiClient.cpp:392] write(): fail on fd 58, errno: 104, "Connection reset by peer"
[E][WiFiClient.cpp:392] write(): fail on fd 60, errno: 104, "Connection reset by peer"
Tue 19-05-2020 15:03:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=76.48058
Tue 19-05-2020 15:04:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=96.49193
Tue 19-05-2020 15:04:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=116.49228
Tue 19-05-2020 15:04:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=136.49599
Tue 19-05-2020 15:05:05- UP receivePkt:: rxPkt: t=Tue 19-05-2020 15:05:05, f=0, sf=7, a=d0:02:ef:4e:, flags=50, addr=0, len=23
Tue 19-05-2020 15:05:05- UP receivePacket:: start: micr=146.237306, tmst=0.0
Tue 19-05-2020 15:05:05- UP receivePacket:: sendUdp: micr=146.246412, tmst=146.245311
Tue 19-05-2020 15:05:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=156.49213
Tue 19-05-2020 15:05:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=176.49259
Tue 19-05-2020 15:05:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=196.47841
Tue 19-05-2020 15:06:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=216.49028
Tue 19-05-2020 15:06:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=236.47945
Tue 19-05-2020 15:06:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=256.47709
Tue 19-05-2020 15:07:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=276.47537
Tue 19-05-2020 15:07:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=296.49265
Tue 19-05-2020 15:07:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=316.47777
Tue 19-05-2020 15:08:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=336.49099
Tue 19-05-2020 15:08:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=356.49286
Tue 19-05-2020 15:08:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=376.47790
Tue 19-05-2020 15:09:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=396.49036
Tue 19-05-2020 15:09:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=416.48068
Tue 19-05-2020 15:09:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=436.47853
Tue 19-05-2020 15:10:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=456.48036
Tue 19-05-2020 15:10:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=476.49188
Tue 19-05-2020 15:10:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=496.47828
Tue 19-05-2020 15:11:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=516.47979
Tue 19-05-2020 15:11:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=536.48158
Tue 19-05-2020 15:11:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=556.47756
Tue 19-05-2020 15:12:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=576.49168
Tue 19-05-2020 15:12:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=596.48585
Tue 19-05-2020 15:12:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=616.49018
Tue 19-05-2020 15:13:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=636.49298
Tue 19-05-2020 15:13:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=656.47946
Tue 19-05-2020 15:13:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=676.47799
Tue 19-05-2020 15:14:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=696.47974
Tue 19-05-2020 15:14:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=716.49286
Tue 19-05-2020 15:14:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=736.47767
Tue 19-05-2020 15:15:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=756.49046
Tue 19-05-2020 15:15:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=776.48108
Tue 19-05-2020 15:15:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=796.47885
Tue 19-05-2020 15:16:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=816.48050
Tue 19-05-2020 15:16:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=836.49277
Tue 19-05-2020 15:16:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=856.48950
Tue 19-05-2020 15:17:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=876.47848
Tue 19-05-2020 15:17:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=896.48148
Tue 19-05-2020 15:17:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=916.47741
Tue 19-05-2020 15:18:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=936.49283
Tue 19-05-2020 15:18:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=956.50291
Tue 19-05-2020 15:18:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=976.47858
Tue 19-05-2020 15:19:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=996.47831
Tue 19-05-2020 15:19:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1016.48755
Tue 19-05-2020 15:19:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1036.49046
Tue 19-05-2020 15:20:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1056.49221
Tue 19-05-2020 15:20:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1076.48075
Tue 19-05-2020 15:20:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1096.49105
Tue 19-05-2020 15:21:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1116.47870
Tue 19-05-2020 15:21:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1136.49242
Tue 19-05-2020 15:21:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1156.49057
Tue 19-05-2020 15:22:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1176.47882
Tue 19-05-2020 15:22:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1196.49234
Tue 19-05-2020 15:22:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1216.48980
Tue 19-05-2020 15:23:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1236.49100
Tue 19-05-2020 15:23:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1256.48019
Tue 19-05-2020 15:23:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1276.49000
Tue 19-05-2020 15:24:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1296.49189
Tue 19-05-2020 15:24:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1316.48024
Tue 19-05-2020 15:24:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1336.49002
Tue 19-05-2020 15:25:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1356.47894
Tue 19-05-2020 15:25:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1376.49283
Tue 19-05-2020 15:25:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1396.48019
Tue 19-05-2020 15:26:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1416.49122
Tue 19-05-2020 15:26:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1436.48096
Tue 19-05-2020 15:26:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1456.49352
Tue 19-05-2020 15:27:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1476.47943
Tue 19-05-2020 15:27:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1496.48035
Tue 19-05-2020 15:27:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1516.49074
Tue 19-05-2020 15:28:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1536.48100
Tue 19-05-2020 15:28:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1556.48112
Tue 19-05-2020 15:28:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1576.47826
Tue 19-05-2020 15:29:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1596.47861
Tue 19-05-2020 15:29:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1616.48155
Tue 19-05-2020 15:29:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1636.48979
Tue 19-05-2020 15:30:15- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1656.49214
Tue 19-05-2020 15:30:35- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1676.49287
Tue 19-05-2020 15:30:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1696.49144

this is on server (where INTERNAL_IP is an internal IP value)

warning | 2020-05-19 15:28:56 | 2020-05-19 15:28:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
-- | -- | -- | -- | -- | -- | -- | -- | --
  | warning | 2020-05-19 15:26:56 | 2020-05-19 15:26:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:24:56 | 2020-05-19 15:24:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:22:56 | 2020-05-19 15:22:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:20:56 | 2020-05-19 15:20:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:18:56 | 2020-05-19 15:18:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:16:56 | 2020-05-19 15:16:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:14:56 | 2020-05-19 15:14:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:12:56 | 2020-05-19 15:12:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:10:56 | 2020-05-19 15:10:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:08:56 | 2020-05-19 15:08:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:06:56 | 2020-05-19 15:06:56 | 1 | gateway | 246F28FFFFB1BF84 | ack_lost | 100.0 |  
  | warning | 2020-05-19 15:05:06 | 2020-05-19 15:05:06 | 1 | node | 56ED0CFB | repeated_reset | 1 |  
  | info | 2020-05-19 15:05:06 | 2020-05-19 15:05:06 | 1 | device | 0031E81A65A01F92 | join | <<"56ED0CFB">> |  
  | info | 2020-05-19 15:02:57 | 2020-05-19 15:02:57 | 1 | gateway | 246F28FFFFB1BF84 | connected | {{INTERNAL_IP},1700} |  
  | info | 2020-05-18 17:03:01 | 2020-05-18 17:03:01 | 1 | server | lorawan@77a881b8acfc | started
platenspeler commented 4 years ago

Are you getting real errors? Because I changed the output of the monitor process such that the warnings/output in your file do not occurr in debug==1. And the message included above: Tue "19-05-2020 15:30:55- UP ESP-sc-gway:: PKT_PULL_DATA message sent: micr=1696.49144" is just a message that the gateway did send a PULL_DATA message to the server (but not an error).

Maarten

platenspeler commented 4 years ago

Ok, the message from the server is more serious.I'll look into it. Can you see what ack this is probably a Poll ack for connection?

antonio-emilio commented 4 years ago

I'm also getting a lot of messages like this one: "Thu 14-05-2020 17:04:42- SCAN:: I=CDDONE CDDETD , F=0, SF=7, E=0, S=RX , eT=13414520, dT=2241" And then, eventually I get the message: "Thu 14-05-2020 17:04:28- UP RXPK:: {"rxpk":[{"chan":0,"rfch":0,"freq":868.099975,"stat":1,"modu":"LORA","datr":"SF10BW125","codr":"4/5","lsnr":12,"rssi":-83,"size":26,"data":"QPsM7VaAMAAB5A2lEgMPpYAoyVTXSMexT2Y=","tmst":594866936}]} , length=209"" It's working, but I don't know why this is happening.