Raspberry Pi 3 (RPI 3) + MQTT gateway NACK and triple messages



  • Hi all,

    Following a previous post on my transition to a RPI gateway + MQTT, I'm now experiencing two problems:

    • Significant NACK ocurrencies, specially when connecting to gateway
    • messages to MQTT gateway tripled

    As per the logs below, node and gateway are able to connect (they are 4m away), RSSI seems to be very good (-30 to -40), but there number of connection attempts with NACK are not justified.

    Also, for each message sent from node, there are three (sometimes 2, sometimes 4) messages sent by gateway to MQTT server.

    I've already changed the antenna on the gateway radio. I haven't tried another radio as this one seems to be working well.

    Both logs were taken in paralell (see timestamp)

    Many thanks for any help!!

    Node Log

    08:02:11.829 ->  __  __       ____
    08:02:11.829 -> |  \/  |_   _/ ___|  ___ _ __  ___  ___  _ __ ___
    08:02:11.829 -> | |\/| | | | \___ \ / _ \ `_ \/ __|/ _ \| `__/ __|
    08:02:11.829 -> | |  | | |_| |___| |  __/ | | \__ \  _  | |  \__ \
    08:02:11.829 -> |_|  |_|\__, |____/ \___|_| |_|___/\___/|_|  |___/
    08:02:11.863 ->         |___/                      2.3.2
    08:02:11.863 -> 
    08:02:11.863 -> 16 MCO:BGN:INIT NODE,CP=RPNNA---,FQ=16,REL=255,VER=2.3.2
    08:02:11.863 -> 26 TSM:INIT
    08:02:11.863 -> 28 TSF:WUR:MS=0
    08:02:11.863 -> 29 TSM:INIT:TSP OK
    08:02:11.863 -> 31 TSM:INIT:STATID=2
    08:02:11.863 -> 33 TSF:SID:OK,ID=2
    08:02:11.863 -> 35 TSM:FPAR
    08:02:12.361 -> 540 ?TSF:MSG:SEND,2-2-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK:
    08:02:14.355 -> 2511 TSF:MSG:READ,0-0-2,s=255,c=3,t=8,pt=1,l=1,sg=0:0
    08:02:14.355 -> 2516 TSF:MSG:FPAR OK,ID=0,D=1
    08:02:14.388 -> 2547 TSM:FPAR:OK
    08:02:14.388 -> 2548 TSM:ID
    08:02:14.388 -> 2549 TSM:ID:OK
    08:02:14.388 -> 2551 TSM:UPL
    08:02:17.909 -> 6069 !TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=NACK:1
    08:02:19.204 -> 7389 TSF:MSG:READ,0-0-2,s=255,c=3,t=25,pt=1,l=1,sg=0:1
    08:02:19.204 -> 7394 TSF:MSG:PONG RECV,HP=1
    08:02:19.238 -> 7396 TSM:UPL:OK
    08:02:19.238 -> 7398 TSM:READY:ID=2,PAR=0,DIS=1
    08:02:22.560 -> 10728 !TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=NACK:0100
    08:02:23.723 -> 11901 TSF:MSG:READ,0-0-2,s=255,c=3,t=15,pt=6,l=2,sg=0:0100
    08:02:27.246 -> 15421 !TSF:MSG:SEND,2-2-0-0,s=255,c=0,t=17,pt=0,l=5,sg=0,ft=1,st=NACK:2.3.2
    08:02:30.768 -> 18941 !TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=6,pt=1,l=1,sg=0,ft=2,st=NACK:0
    08:02:36.318 -> 24473 !TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=11,pt=0,l=22,sg=0,ft=3,st=NACK:TemperatureAndHumidity
    08:02:39.841 -> 27993 !TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=12,pt=0,l=3,sg=0,ft=4,st=NACK:1.1
    08:02:43.364 -> 31519 !TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=11,pt=0,l=13,sg=0,ft=5,st=NACK:Battery Meter
    08:02:46.887 -> 35038 !TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=12,pt=0,l=3,sg=0,ft=6,st=NACK:1.0
    08:02:50.409 -> 38559 !TSF:MSG:SEND,2-2-0-0,s=0,c=0,t=7,pt=0,l=0,sg=0,ft=7,st=NACK:
    08:02:53.898 -> 42077 !TSF:MSG:SEND,2-2-0-0,s=1,c=0,t=6,pt=0,l=0,sg=0,ft=8,st=NACK:
    08:02:57.419 -> 45599 !TSF:MSG:SEND,2-2-0-0,s=2,c=0,t=23,pt=0,l=0,sg=0,ft=9,st=NACK:
    08:02:57.453 -> 45605 MCO:REG:REQ
    08:03:00.943 -> 49121 !TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=26,pt=1,l=1,sg=0,ft=10,st=NACK:2
    08:03:00.976 -> 49127 !TSM:READY:UPL FAIL,SNP
    08:03:00.976 -> 49130 TSM:FPAR
    08:03:01.474 -> 49635 ?TSF:MSG:SEND,2-2-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=11,st=OK:
    08:03:02.869 -> 51050 TSF:MSG:READ,0-0-2,s=255,c=3,t=27,pt=1,l=1,sg=0:1
    08:03:02.903 -> 51055 MCO:PIM:NODE REG=1
    08:03:04.898 -> 53058 !TSM:FPAR:NO REPLY
    08:03:04.898 -> 53060 TSM:FPAR
    08:03:05.396 -> 53565 ?TSF:MSG:SEND,2-2-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK:
    08:03:07.157 -> 55321 TSF:MSG:READ,0-0-2,s=255,c=3,t=8,pt=1,l=1,sg=0:0
    08:03:07.157 -> 55326 TSF:MSG:FPAR OK,ID=0,D=1
    08:03:07.423 -> 55572 TSM:FPAR:OK
    08:03:07.423 -> 55573 TSM:ID
    08:03:07.423 -> 55575 TSM:ID:OK
    08:03:07.423 -> 55576 TSM:UPL
    08:03:10.946 -> 59095 !TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=NACK:1
    08:03:12.242 -> 60406 TSF:MSG:READ,0-0-2,s=255,c=3,t=25,pt=1,l=1,sg=0:1
    08:03:12.242 -> 60411 TSF:MSG:PONG RECV,HP=1
    08:03:12.242 -> 60414 TSM:UPL:OK
    08:03:12.242 -> 60416 TSM:READY:ID=2,PAR=0,DIS=1
    08:03:12.242 -> 60419 MCO:BGN:STP
    08:03:12.242 -> 60422 MCO:SLP:MS=1000,SMS=0,I1=255,M1=255,I2=255,M2=255
    08:03:12.276 -> 60427 TSF:TDI:TSL
    08:03:13.339 -> 60429 MCO:SLP:WUP=-1
    08:03:13.339 -> 60431 TSF:TRI:TSB
    08:03:13.372 -> 60433 MCO:BGN:INIT OK,TSP=1
    08:03:16.894 -> 63969 !TSF:MSG:SEND,2-2-0-0,s=1,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=NACK:22.0
    08:03:16.894 -> T: 22.00
    08:03:20.450 -> 67511 !TSF:MSG:SEND,2-2-0-0,s=0,c=1,t=1,pt=7,l=5,sg=0,ft=1,st=NACK:95.0
    08:03:20.450 -> H: 95.00
    08:03:23.940 -> 71028 !TSF:MSG:SEND,2-2-0-0,s=2,c=1,t=28,pt=2,l=2,sg=0,ft=2,st=NACK:-32
    08:03:23.973 -> RSSI: -32
    08:03:23.973 -> 71034 MCO:SLP:MS=60000,SMS=0,I1=255,M1=255,I2=255,M2=255
    08:03:23.973 -> 71041 TSF:TDI:TSL
    08:04:28.339 -> 71043 MCO:SLP:WUP=-1
    08:04:28.339 -> 71045 TSF:TRI:TSB
    08:04:31.893 -> 74582 !TSF:MSG:SEND,2-2-0-0,s=2,c=1,t=28,pt=2,l=2,sg=0,ft=3,st=NACK:-32
    08:04:31.893 -> RSSI: -32
    08:04:31.893 -> 74588 MCO:SLP:MS=60000,SMS=0,I1=255,M1=255,I2=255,M2=255
    08:04:31.893 -> 74594 TSF:TDI:TSL
    08:05:36.231 -> 74596 MCO:SLP:WUP=-1
    08:05:36.231 -> 74598 TSF:TRI:TSB
    08:05:39.785 -> 78135 !TSF:MSG:SEND,2-2-0-0,s=2,c=1,t=28,pt=2,l=2,sg=0,ft=4,st=NACK:-32
    08:05:39.785 -> RSSI: -32
    08:05:39.785 -> 78141 MCO:SLP:MS=60000,SMS=0,I1=255,M1=255,I2=255,M2=255
    08:05:39.785 -> 78147 TSF:TDI:TSL
    08:06:44.132 -> 78149 MCO:SLP:WUP=-1
    08:06:44.132 -> 78151 TSF:TRI:TSB
    08:06:47.683 -> 81691 !TSF:MSG:SEND,2-2-0-0,s=2,c=1,t=28,pt=2,l=2,sg=0,ft=5,st=NACK:-32
    08:06:47.683 -> RSSI: -32
    08:06:47.683 -> 81697 MCO:SLP:MS=60000,SMS=0,I1=255,M1=255,I2=255,M2=255
    08:06:47.683 -> 81703 TSF:TDI:TSL
    08:07:52.039 -> 81705 MCO:SLP:WUP=-1
    08:07:52.039 -> 81708 TSF:TRI:TSB
    08:07:52.039 -> 81709 !TSM:READY:UPL FAIL,SNP
    08:07:52.039 -> 81712 TSM:FPAR
    08:07:52.537 -> 82219 ?TSF:MSG:SEND,2-2-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=6,st=OK:
    08:07:52.570 -> 82247 !TSF:SND:TNR
    08:07:52.570 -> RSSI: -32
    08:07:52.570 -> 82249 MCO:SLP:MS=60000,SMS=0,I1=255,M1=255,I2=255,M2=255
    08:07:52.570 -> 82254 !MCO:SLP:TNR
    08:07:53.700 -> 83374 TSF:MSG:READ,0-0-2,s=255,c=3,t=8,pt=1,l=1,sg=0:0
    08:07:53.700 -> 83379 TSF:MSG:FPAR OK,ID=0,D=1
    08:07:54.564 -> 84226 TSM:FPAR:OK
    08:07:54.564 -> 84227 TSM:ID
    08:07:54.564 -> 84229 TSM:ID:OK
    08:07:54.564 -> 84230 TSM:UPL
    08:07:57.188 -> 86844 TSF:MSG:SEND,2-2-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=OK:1
    08:07:58.882 -> 88552 TSF:MSG:READ,0-0-2,s=255,c=3,t=25,pt=1,l=1,sg=0:1
    08:07:58.882 -> 88557 TSF:MSG:PONG RECV,HP=1
    08:07:58.882 -> 88560 TSM:UPL:OK
    08:07:58.882 -> 88561 TSM:READY:ID=2,PAR=0,DIS=1
    08:07:58.882 -> 88564 MCO:SLP:MS=53692
    08:07:58.882 -> 88566 TSF:TDI:TSL
    08:08:56.465 -> 88568 MCO:SLP:WUP=-1
    08:08:56.465 -> 88570 TSF:TRI:TSB
    08:09:00.018 -> 92109 !TSF:MSG:SEND,2-2-0-0,s=0,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=NACK:94.0
    08:09:00.018 -> H: 94.00
    08:09:01.946 -> 94031 TSF:MSG:SEND,2-2-0-0,s=2,c=1,t=28,pt=2,l=2,sg=0,ft=1,st=OK:-32
    08:09:01.946 -> RSSI: -32
    08:09:01.946 -> 94038 MCO:SLP:MS=60000,SMS=0,I1=255,M1=255,I2=255,M2=255
    08:09:01.946 -> 94044 TSF:TDI:TSL
    08:10:06.293 -> 94046 MCO:SLP:WUP=-1
    08:10:06.293 -> 94048 TSF:TRI:TSB
    08:10:09.848 -> 97585 !TSF:MSG:SEND,2-2-0-0,s=1,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=NACK:23.0
    08:10:09.848 -> T: 23.00
    08:10:13.370 -> 101126 !TSF:MSG:SEND,2-2-0-0,s=0,c=1,t=1,pt=7,l=5,sg=0,ft=1,st=NACK:95.0
    08:10:13.370 -> H: 95.00
    08:10:16.890 -> 104649 !TSF:MSG:SEND,2-2-0-0,s=2,c=1,t=28,pt=2,l=2,sg=0,ft=2,st=NACK:-33
    08:10:16.924 -> RSSI: -33
    08:10:16.924 -> 104655 MCO:SLP:MS=60000,SMS=0,I1=255,M1=255,I2=255,M2=255
    08:10:16.924 -> 104662 TSF:TDI:TSL
    08:11:21.281 -> 104664 MCO:SLP:WUP=-1
    08:11:21.281 -> 104666 TSF:TRI:TSB
    08:11:24.800 -> 108204 !TSF:MSG:SEND,2-2-0-0,s=1,c=1,t=0,pt=7,l=5,sg=0,ft=3,st=NACK:22.0
    08:11:24.800 -> T: 22.00
    08:11:28.355 -> 111744 !TSF:MSG:SEND,2-2-0-0,s=2,c=1,t=28,pt=2,l=2,sg=0,ft=4,st=NACK:-33
    08:11:28.355 -> RSSI: -33
    08:11:28.355 -> 111750 MCO:SLP:MS=60000,SMS=0,I1=255,M1=255,I2=255,M2=255
    08:11:28.355 -> 111756 TSF:TDI:TSL
    08:12:32.710 -> 111758 MCO:SLP:WUP=-1
    08:12:32.710 -> 111760 TSF:TRI:TSB
    
    

    Gateway Log

    Sep 03 08:01:53 INFO  Starting gateway...
    Sep 03 08:01:53 INFO  Protocol version - 2.3.2
    Sep 03 08:01:53 DEBUG MCO:BGN:INIT GW,CP=RPNGL---,FQ=NA,REL=255,VER=2.3.2
    Sep 03 08:01:53 DEBUG TSF:LRT:OK
    Sep 03 08:01:53 DEBUG TSM:INIT
    Sep 03 08:01:53 DEBUG TSF:WUR:MS=0
    Sep 03 08:01:53 DEBUG TSM:INIT:TSP OK
    Sep 03 08:01:53 DEBUG TSM:INIT:GW MODE
    Sep 03 08:01:53 DEBUG TSM:READY:ID=0,PAR=0,DIS=0
    Sep 03 08:01:53 DEBUG MCO:REG:NOT NEEDED
    Sep 03 08:01:53 DEBUG MCO:BGN:STP
    Sep 03 08:01:53 DEBUG MCO:BGN:INIT OK,TSP=1
    Sep 03 08:01:53 DEBUG GWT:RMQ:CONNECTING...
    Sep 03 08:01:53 DEBUG connected to 127.0.0.1
    Sep 03 08:01:53 DEBUG GWT:RMQ:OK
    Sep 03 08:01:53 DEBUG GWT:TPS:TOPIC=mygateway1-out/0/255/0/0/18,MSG SENT
    Sep 03 08:01:53 DEBUG TSM:READY:NWD REQ
    Sep 03 08:01:54 DEBUG ?TSF:MSG:SEND,0-0-255-255,s=255,c=3,t=20,pt=0,l=0,sg=0,ft=0,st=OK:
    Sep 03 08:02:12 DEBUG TSF:MSG:READ,2-2-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
    Sep 03 08:02:12 DEBUG TSF:MSG:BC
    Sep 03 08:02:12 DEBUG TSF:MSG:FPAR REQ,ID=2
    Sep 03 08:02:12 DEBUG TSF:PNG:SEND,TO=0
    Sep 03 08:02:12 DEBUG TSF:CKU:OK
    Sep 03 08:02:12 DEBUG TSF:MSG:GWL OK
    Sep 03 08:02:16 DEBUG !TSF:MSG:SEND,0-0-2-2,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=NACK:0
    Sep 03 08:02:17 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
    Sep 03 08:02:17 DEBUG TSF:MSG:PINGED,ID=2,HP=1
    Sep 03 08:02:21 DEBUG !TSF:MSG:SEND,0-0-2-2,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=NACK:1
    Sep 03 08:02:21 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0100
    Sep 03 08:02:24 DEBUG !TSF:MSG:SEND,0-0-2-2,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=NACK:0100
    Sep 03 08:02:25 DEBUG TSF:MSG:READ,2-2-0,s=255,c=0,t=17,pt=0,l=5,sg=0:2.3.2
    Sep 03 08:02:25 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/0/0/17,MSG SENT
    Sep 03 08:02:26 DEBUG TSF:MSG:READ,2-2-0,s=255,c=0,t=17,pt=0,l=5,sg=0:2.3.2
    Sep 03 08:02:26 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/0/0/17,MSG SENT
    Sep 03 08:02:28 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=6,pt=1,l=1,sg=0:0
    Sep 03 08:02:28 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/6,MSG SENT
    Sep 03 08:02:29 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=6,pt=1,l=1,sg=0:0
    Sep 03 08:02:29 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/6,MSG SENT
    Sep 03 08:02:31 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=6,pt=1,l=1,sg=0:0
    Sep 03 08:02:31 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/6,MSG SENT
    Sep 03 08:02:33 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=11,pt=0,l=22,sg=0:TemperatureAndHumidity
    Sep 03 08:02:33 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/11,MSG SENT
    Sep 03 08:02:35 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=11,pt=0,l=22,sg=0:TemperatureAndHumidity
    Sep 03 08:02:35 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/11,MSG SENT
    Sep 03 08:02:36 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=11,pt=0,l=22,sg=0:TemperatureAndHumidity
    Sep 03 08:02:36 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/11,MSG SENT
    Sep 03 08:02:38 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=12,pt=0,l=3,sg=0:1.1
    Sep 03 08:02:38 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/12,MSG SENT
    Sep 03 08:02:39 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=12,pt=0,l=3,sg=0:1.1
    Sep 03 08:02:39 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/12,MSG SENT
    Sep 03 08:02:40 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=11,pt=0,l=13,sg=0:Battery Meter
    Sep 03 08:02:40 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/11,MSG SENT
    Sep 03 08:02:42 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=11,pt=0,l=13,sg=0:Battery Meter
    Sep 03 08:02:42 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/11,MSG SENT
    Sep 03 08:02:43 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=11,pt=0,l=13,sg=0:Battery Meter
    Sep 03 08:02:43 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/11,MSG SENT
    Sep 03 08:02:45 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=12,pt=0,l=3,sg=0:1.0
    Sep 03 08:02:45 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/12,MSG SENT
    Sep 03 08:02:46 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=12,pt=0,l=3,sg=0:1.0
    Sep 03 08:02:46 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/255/3/0/12,MSG SENT
    Sep 03 08:02:47 DEBUG TSF:MSG:READ,2-2-0,s=0,c=0,t=7,pt=0,l=0,sg=0:
    Sep 03 08:02:47 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/0/0/7,MSG SENT
    Sep 03 08:02:49 DEBUG TSF:MSG:READ,2-2-0,s=0,c=0,t=7,pt=0,l=0,sg=0:
    Sep 03 08:02:49 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/0/0/7,MSG SENT
    Sep 03 08:02:50 DEBUG TSF:MSG:READ,2-2-0,s=0,c=0,t=7,pt=0,l=0,sg=0:
    Sep 03 08:02:50 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/0/0/7,MSG SENT
    Sep 03 08:02:52 DEBUG TSF:MSG:READ,2-2-0,s=1,c=0,t=6,pt=0,l=0,sg=0:
    Sep 03 08:02:52 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/0/0/6,MSG SENT
    Sep 03 08:02:53 DEBUG TSF:MSG:READ,2-2-0,s=1,c=0,t=6,pt=0,l=0,sg=0:
    Sep 03 08:02:53 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/0/0/6,MSG SENT
    Sep 03 08:02:54 DEBUG TSF:MSG:READ,2-2-0,s=2,c=0,t=23,pt=0,l=0,sg=0:
    Sep 03 08:02:54 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/0/0/23,MSG SENT
    Sep 03 08:02:56 DEBUG TSF:MSG:READ,2-2-0,s=2,c=0,t=23,pt=0,l=0,sg=0:
    Sep 03 08:02:56 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/0/0/23,MSG SENT
    Sep 03 08:02:57 DEBUG TSF:MSG:READ,2-2-0,s=2,c=0,t=23,pt=0,l=0,sg=0:
    Sep 03 08:02:57 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/0/0/23,MSG SENT
    Sep 03 08:02:59 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=26,pt=1,l=1,sg=0:2
    Sep 03 08:03:02 DEBUG !TSF:MSG:SEND,0-0-2-2,s=255,c=3,t=27,pt=1,l=1,sg=0,ft=0,st=NACK:1
    Sep 03 08:03:05 DEBUG TSF:MSG:READ,2-2-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
    Sep 03 08:03:05 DEBUG TSF:MSG:BC
    Sep 03 08:03:05 DEBUG TSF:MSG:FPAR REQ,ID=2
    Sep 03 08:03:05 DEBUG TSF:PNG:SEND,TO=0
    Sep 03 08:03:05 DEBUG TSF:CKU:OK
    Sep 03 08:03:05 DEBUG TSF:MSG:GWL OK
    Sep 03 08:03:09 DEBUG !TSF:MSG:SEND,0-0-2-2,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=NACK:0
    Sep 03 08:03:10 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
    Sep 03 08:03:10 DEBUG TSF:MSG:PINGED,ID=2,HP=1
    Sep 03 08:03:14 DEBUG !TSF:MSG:SEND,0-0-2-2,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=NACK:1
    Sep 03 08:03:15 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:22.0
    Sep 03 08:03:15 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:03:16 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:22.0
    Sep 03 08:03:16 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:03:17 DEBUG TSF:MSG:READ,2-2-0,s=0,c=1,t=1,pt=7,l=5,sg=0:95.0
    Sep 03 08:03:17 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/1/0/1,MSG SENT
    Sep 03 08:03:19 DEBUG TSF:MSG:READ,2-2-0,s=0,c=1,t=1,pt=7,l=5,sg=0:95.0
    Sep 03 08:03:19 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/1/0/1,MSG SENT
    Sep 03 08:03:20 DEBUG TSF:MSG:READ,2-2-0,s=0,c=1,t=1,pt=7,l=5,sg=0:95.0
    Sep 03 08:03:20 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/1/0/1,MSG SENT
    Sep 03 08:03:22 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:03:22 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:03:23 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:03:23 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:04:29 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:04:29 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:04:30 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:04:30 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:04:32 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:04:32 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:05:37 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:05:37 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:05:38 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:05:38 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:05:40 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:05:40 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:06:45 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:06:45 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:06:46 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:06:46 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:06:47 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:06:47 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:07:52 DEBUG TSF:MSG:READ,2-2-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
    Sep 03 08:07:52 DEBUG TSF:MSG:BC
    Sep 03 08:07:52 DEBUG TSF:MSG:FPAR REQ,ID=2
    Sep 03 08:07:52 DEBUG TSF:PNG:SEND,TO=0
    Sep 03 08:07:52 DEBUG TSF:CKU:OK
    Sep 03 08:07:52 DEBUG TSF:MSG:GWL OK
    Sep 03 08:07:56 DEBUG !TSF:MSG:SEND,0-0-2-2,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=NACK:0
    Sep 03 08:07:57 DEBUG TSF:MSG:READ,2-2-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
    Sep 03 08:07:57 DEBUG TSF:MSG:PINGED,ID=2,HP=1
    Sep 03 08:08:00 DEBUG !TSF:MSG:SEND,0-0-2-2,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=NACK:1
    Sep 03 08:08:58 DEBUG TSF:MSG:READ,2-2-0,s=0,c=1,t=1,pt=7,l=5,sg=0:94.0
    Sep 03 08:08:58 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/1/0/1,MSG SENT
    Sep 03 08:08:59 DEBUG TSF:MSG:READ,2-2-0,s=0,c=1,t=1,pt=7,l=5,sg=0:94.0
    Sep 03 08:08:59 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/1/0/1,MSG SENT
    Sep 03 08:09:01 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:09:01 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:09:01 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-32
    Sep 03 08:09:01 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:10:07 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:23.0
    Sep 03 08:10:07 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:10:08 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:23.0
    Sep 03 08:10:08 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:10:10 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:23.0
    Sep 03 08:10:10 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:10:11 DEBUG TSF:MSG:READ,2-2-0,s=0,c=1,t=1,pt=7,l=5,sg=0:95.0
    Sep 03 08:10:11 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/1/0/1,MSG SENT
    Sep 03 08:10:12 DEBUG TSF:MSG:READ,2-2-0,s=0,c=1,t=1,pt=7,l=5,sg=0:95.0
    Sep 03 08:10:12 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/0/1/0/1,MSG SENT
    Sep 03 08:10:14 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-33
    Sep 03 08:10:14 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:10:15 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-33
    Sep 03 08:10:15 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:10:17 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-33
    Sep 03 08:10:17 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:11:22 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:22.0
    Sep 03 08:11:22 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:11:23 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:22.0
    Sep 03 08:11:23 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:11:25 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:22.0
    Sep 03 08:11:25 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:11:26 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-33
    Sep 03 08:11:26 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:11:27 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-33
    Sep 03 08:11:27 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:12:33 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:23.0
    Sep 03 08:12:33 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:12:35 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:23.0
    Sep 03 08:12:35 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:12:36 DEBUG TSF:MSG:READ,2-2-0,s=1,c=1,t=0,pt=7,l=5,sg=0:23.0
    Sep 03 08:12:36 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/1/1/0/0,MSG SENT
    Sep 03 08:12:37 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-33
    Sep 03 08:12:37 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    Sep 03 08:12:39 DEBUG TSF:MSG:READ,2-2-0,s=2,c=1,t=28,pt=2,l=2,sg=0:-33
    Sep 03 08:12:39 DEBUG GWT:TPS:TOPIC=mygateway1-out/2/2/1/0/28,MSG SENT
    

    Gateway config

    ./configure --my-gateway=mqtt --my-controller-ip-address=127.0.0.1 --my-mqtt-user=user --my-mqtt-password=pswd --my-mqtt-publish-topic-prefix=mygateway1-out --my-mqtt-subscribe-topic-prefix=mygateway1-in --my-mqtt-client-id=gw_02 --my-transport=rfm69 --my-rfm69-frequency=433 --my-is-rfm69hw --extra-cxxflags="-DMY_DEBUG_VERBOSE_GATEWAY"
    

    Node config

    
    /**
     * The MySensors Arduino library handles the wireless radio link and protocol
     * between your home built sensors/actuators and HA controller of choice.
     * The sensors forms a self healing radio network with optional repeaters. Each
     * repeater and gateway builds a routing tables in EEPROM which keeps track of the
     * network topology allowing messages to be routed to nodes.
     *
     * Created by Henrik Ekblad <henrik.ekblad@mysensors.org>
     * Copyright (C) 2013-2015 Sensnology AB
     * Full contributor list: https://github.com/mysensors/Arduino/graphs/contributors
     *
     * Documentation: http://www.mysensors.org
     * Support Forum: http://forum.mysensors.org
     *
     * This program is free software; you can redistribute it and/or
     * modify it under the terms of the GNU General Public License
     * version 2 as published by the Free Software Foundation.
     *
     *******************************
     *
     * REVISION HISTORY
     * Version 1.0: Henrik EKblad
     * Version 1.1 - 2016-07-20: Converted to MySensors v2.0 and added various improvements - Torben Woltjen (mozzbozz)
     * 
     * DESCRIPTION
     * This sketch provides an example of how to implement a humidity/temperature
     * sensor using a DHT11/DHT-22.
     *  
     * For more information, please visit:
     * http://www.mysensors.org/build/humidity
     * 
     */
    
    // Enable debug prints
    #define MY_DEBUG
    
    // Atualizar para nó esperado
    #define MY_NODE_ID 2
    
    // Novo driver para gateway RPI
    #define MY_RFM69_NEW_DRIVER
    
    // Enable and select radio type attached 
    //#define MY_RADIO_RF24
    #define MY_RADIO_RFM69
    //#define MY_RS485
    
    #define MY_RFM69_FREQUENCY RFM69_433MHZ  // Define for frequency setting. Needed if you're radio module isn't 868Mhz (868Mhz is default in lib)
    #define MY_IS_RFM69HW  // Mandatory if you radio module is the high power version (RFM69HW and RFM69HCW), Comment it if it's not the case
    
    
    #include <SPI.h>
    #include <MySensors.h>  
    #include <DHT.h>
    
    // Set this to the pin you connected the DHT's data pin to
    #define DHT_DATA_PIN 3
    
    // select the input pin for the battery sense point
    int BATTERY_SENSE_PIN = A0;
    int oldBatteryPcnt = 0;
    
    // Set this offset if the sensor has a permanent small offset to the real temperatures.
    // In Celsius degrees (as measured by the device)
    #define SENSOR_TEMP_OFFSET 0
    
    // Sleep time between sensor updates (in milliseconds)
    // Must be >1000ms for DHT22 and >2000ms for DHT11
    static const uint64_t UPDATE_INTERVAL = 60000;
    
    // Force sending an update of the temperature after n sensor reads, so a controller showing the
    // timestamp of the last update doesn't show something like 3 hours in the unlikely case, that
    // the value didn't change since;
    // i.e. the sensor would force sending an update every UPDATE_INTERVAL*FORCE_UPDATE_N_READS [ms]
    static const uint8_t FORCE_UPDATE_N_READS = 5;
    
    #define CHILD_ID_HUM 0
    #define CHILD_ID_TEMP 1
    #define CHILD_ID_RSSI 2               //RSSI
    #define CHILD_ID_BAT 3               //BATTERY
    
    
    float lastTemp;
    float lastHum;
    uint8_t nNoUpdatesTemp;
    uint8_t nNoUpdatesHum;
    bool metric = true;
    int16_t rssiVal;               //RSSI
    char rssiStr[10];             //RSSI
    
    
    
    MyMessage msgHum(CHILD_ID_HUM, V_HUM);
    MyMessage msgTemp(CHILD_ID_TEMP, V_TEMP);
    MyMessage msgRSSI(CHILD_ID_RSSI,V_VAR5);
    DHT dht;
    
    
    
    
    
    void presentation()  
    { 
      // Send the sketch version information to the gateway
      sendSketchInfo("TemperatureAndHumidity", "1.1");
      sendSketchInfo("Battery Meter", "1.0");
    ///  sendSketchInfo("DHT11-v05");
    
      // Register all sensors to gw (they will be created as child devices)
      present(CHILD_ID_HUM, S_HUM);
      present(CHILD_ID_TEMP, S_TEMP);
      present(CHILD_ID_RSSI, S_CUSTOM);
    
    
      metric = getControllerConfig().isMetric;
    }
    
    
    void setup()
    {
      // Battery
      // use the 1.1 V internal reference
      #if defined(__AVR_ATmega2560__)
        analogReference(INTERNAL1V1);
      #else
        analogReference(INTERNAL);
      #endif
      
      dht.setup(DHT_DATA_PIN); // set data pin of DHT sensor
      if (UPDATE_INTERVAL <= dht.getMinimumSamplingPeriod()) {
        Serial.println("Warning: UPDATE_INTERVAL is smaller than supported by the sensor!");
      }
      // Sleep for the time of the minimum sampling period to give the sensor time to power up
      // (otherwise, timeout errors might occure for the first reading)
      sleep(dht.getMinimumSamplingPeriod());
    }
    
    
    void loop()      
    {  
      // Force reading sensor, so it works also after sleep()
      dht.readSensor(true);
    
      // Get temperature from DHT library
      float temperature = dht.getTemperature();
      if (isnan(temperature)) {
        Serial.println("Failed reading temperature from DHT!");
      } else if (temperature != lastTemp || nNoUpdatesTemp == FORCE_UPDATE_N_READS) {
        // Only send temperature if it changed since the last measurement or if we didn't send an update for n times
        lastTemp = temperature;
    
        // apply the offset before converting to something different than Celsius degrees
        temperature += SENSOR_TEMP_OFFSET;
    
        if (!metric) {
          temperature = dht.toFahrenheit(temperature);
        }
        // Reset no updates counter
        nNoUpdatesTemp = 0;
        send(msgTemp.set(temperature, 1));
    
        #ifdef MY_DEBUG
        Serial.print("T: ");
        Serial.println(temperature);
        #endif
      } else {
        // Increase no update counter if the temperature stayed the same
        nNoUpdatesTemp++;
      }
    
      // Get humidity from DHT library
      float humidity = dht.getHumidity();
      if (isnan(humidity)) {
        Serial.println("Failed reading humidity from DHT");
      } else if (humidity != lastHum || nNoUpdatesHum == FORCE_UPDATE_N_READS) {
        // Only send humidity if it changed since the last measurement or if we didn't send an update for n times
        lastHum = humidity;
        // Reset no updates counter
        nNoUpdatesHum = 0;
        send(msgHum.set(humidity, 1));
    
        #ifdef MY_DEBUG
        Serial.print("H: ");
        Serial.println(humidity);
        #endif
      } else {
        // Increase no update counter if the humidity stayed the same
        nNoUpdatesHum++;
      }
    
    //  rssiVal = _radio.readRSSI();  // Old Driver
      rssiVal = RFM69_getReceivingRSSI();  // New Driver
      send(msgRSSI.set(rssiVal));
      #ifdef MY_DEBUG
      Serial.print("RSSI: ");
      Serial.println(rssiVal);
      #endif
    
      // Sleep for a while to save energy
      sleep(UPDATE_INTERVAL); 
    }
    

  • Mod

    Nice work on the troubleshooting @Oumuamua

    The reason the node sends multiple times (which causes the gateway to publish multiple times to mqtt) is that the node does not receive ack from the gateway, and therefore assumes that the message got lost and retransmits.

    So the question is why the node has trouble hearing the ack. Maybe the power supply to the gateway's radio isn't sufficiently stable. Imagine trying to hear a podcast while twiddling the volume knob up and down all the time. Are you using a capacitor near the radio? The rfm radios usually don't need one though.

    Maybe the signal is too strong. The rssi values are pretty high. Imagine standing next to a megaphone blasting in your ear. See if omitting --my-is-rfm69hw and re-running make and make install helps (iirc, this will result in lower output power). You could also try moving the node further away from the gateway.

    Maybe the antennas are bad. Could you post photos of the antennas? Maybe there is a solder bridge somewhere?



  • @Oumuamua I have a non-MySensors nRF24 network and I discovered that a significant number of ACKs don't get received and is more so on child and child-of-child nodes. It is my understanding that ACKs are a programable part of the nRF24 and not part of your code nor of the MySensors code.

    More often than not, the message has been received, even though the ACK didn't make it back. Thus, I added an acknowledge to my code.

    For a reason I don't understand, the MySensors code is more robust when it comes to ACKs

    Perhaps the MySensor code employ a messageID strategy to avoid duplicate messages?

    I concur with @mfalkvidd that noise and/or overdriving the antenna and/or bad connections are a source of many problems.



  • Thanks @mfalkvidd @OldSurferDude !

    The problem really was the antenna. I was using the helical antenna below and I welded in the "wrong" part of it the first time. The second I welded it on the "right" place and it work. The RSSI is still below that the old and good wire... am I welding it on the really right place?

    Screenshot from 2022-09-10 17-28-57.png

    Are helical antennas good?

    Thanks again for the great support you provide.


Log in to reply
 

Suggested Topics

  • 8
  • 8
  • 9
  • 2
  • 2
  • 23
  • 20
  • 5

114
Online

10.7k
Users

10.9k
Topics

111.8k
Posts