MQTT GW with RFM69 on RPi

TimO

Hello!

I've a strange problem with my RPi gateway that happens with my Wemo D1 gateway too. Both gateways are MQTT gateways with an attached RFM69.

For simplicity I focus on the RPi gateway. The gateway uses version 2.3.2 with the configure parameters set:

./configure --my-transport=rfm69 --my-rfm69-frequency=868 --my-is-rfm69hw --my-gateway=mqtt --my-controller-ip-address=192.168.2.13 --my-mqtt-publish-topic-prefix=mysensors-out --my-mqtt-subscribe-topic-prefix=mysensors-in --my-mqtt-client-id=mygateway1 --my-signing=software

The gateway itself works but fires a lot Dec 02 16:09:11 DEBUG !MCO:PRO:RC=1 messages before a message is passed to the mysensors network. Receiving messages works fine.

Here's a debug output from the gateway:

Dec 02 16:08:08 INFO  Starting gateway...
Dec 02 16:08:08 INFO  Protocol version - 2.3.2
Dec 02 16:08:08 DEBUG MCO:BGN:INIT GW,CP=RPNGLS--,FQ=NA,REL=255,VER=2.3.2
Dec 02 16:08:08 DEBUG TSF:LRT:OK
Dec 02 16:08:08 DEBUG TSM:INIT
Dec 02 16:08:08 DEBUG TSF:WUR:MS=0
Dec 02 16:08:08 DEBUG TSM:INIT:TSP OK
Dec 02 16:08:08 DEBUG TSM:INIT:GW MODE
Dec 02 16:08:08 DEBUG TSM:READY:ID=0,PAR=0,DIS=0
Dec 02 16:08:08 DEBUG MCO:REG:NOT NEEDED
Dec 02 16:08:08 DEBUG MCO:BGN:STP
Dec 02 16:08:08 DEBUG MCO:BGN:INIT OK,TSP=1
Dec 02 16:08:08 DEBUG GWT:RMQ:CONNECTING...
Dec 02 16:08:08 DEBUG connected to 192.168.2.13
Dec 02 16:08:08 DEBUG GWT:RMQ:OK
Dec 02 16:08:08 DEBUG GWT:TPS:TOPIC=mysensors-out/0/255/0/0/18,MSG SENT
Dec 02 16:08:08 DEBUG TSM:READY:NWD REQ
Dec 02 16:08:08 DEBUG ?TSF:MSG:SEND,0-0-255-255,s=255,c=3,t=20,pt=0,l=0,sg=0,ft=0,st=OK:
Dec 02 16:08:08 DEBUG TSF:MSG:READ,99-99-0,s=255,c=3,t=21,pt=1,l=1,sg=0:0
Dec 02 16:08:08 DEBUG GWT:TPS:TOPIC=mysensors-out/99/255/3/0/21,MSG SENT
Dec 02 16:08:08 DEBUG TSF:MSG:READ,0-99-255,s=255,c=3,t=20,pt=0,l=0,sg=0:
Dec 02 16:08:08 DEBUG TSF:MSG:BC
Dec 02 16:08:09 DEBUG TSF:MSG:READ,0-98-255,s=255,c=3,t=20,pt=0,l=0,sg=0:
Dec 02 16:08:09 DEBUG TSF:MSG:BC
Dec 02 16:08:10 DEBUG TSF:MSG:READ,98-99-0,s=255,c=3,t=21,pt=1,l=1,sg=0:99
Dec 02 16:08:10 DEBUG GWT:TPS:TOPIC=mysensors-out/98/255/3/0/21,MSG SENT
Dec 02 16:08:10 DEBUG TSF:MSG:READ,0-97-255,s=255,c=3,t=20,pt=0,l=0,sg=0:
Dec 02 16:08:10 DEBUG TSF:MSG:BC
Dec 02 16:08:10 DEBUG TSF:MSG:READ,97-99-0,s=255,c=3,t=21,pt=1,l=1,sg=0:98
Dec 02 16:08:10 DEBUG GWT:TPS:TOPIC=mysensors-out/97/255/3/0/21,MSG SENT
Dec 02 16:08:39 DEBUG TSF:MSG:READ,98-99-0,s=255,c=3,t=22,pt=5,l=4,sg=0:181035246
Dec 02 16:08:39 DEBUG GWT:TPS:TOPIC=mysensors-out/98/255/3/0/22,MSG SENT
Dec 02 16:08:42 DEBUG GWT:IMQ:TOPIC=mysensors-in/98/4/1/0/2, MSG RECEIVED
Dec 02 16:08:42 DEBUG TSF:MSG:SEND,0-0-99-98,s=4,c=3,t=16,pt=0,l=0,sg=0,ft=0,st=OK:
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:42 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG !MCO:PRO:RC=1
Dec 02 16:08:43 DEBUG TSF:MSG:READ,98-99-0,s=255,c=3,t=17,pt=6,l=25,sg=0:<NONCE>
Dec 02 16:08:43 DEBUG TSF:MSG:SEND,0-0-99-98,s=4,c=1,t=2,pt=0,l=1,sg=1,ft=0,st=OK:1
Dec 02 16:08:46 DEBUG TSF:MSG:READ,97-99-0,s=255,c=3,t=22,pt=5,l=4,sg=0:181033345
Dec 02 16:08:46 DEBUG GWT:TPS:TOPIC=mysensors-out/97/255/3/0/22,MSG SENT

git status ensures that there are no changes to the code.

Any idea what is happening here?

With the Wemos D1 MQTT gateway I've seen the same behaviour, and after a time it escalates to RC=2 and becomes less responsive.

The same happens with the current development version.

Yveaux

@TimO said in MQTT GW with RFM69 on RPi:

The gateway itself works but fires a lot Dec 02 16:09:11 DEBUG !MCO:PRO:RC=1 messages

This message is printed when a recursive call of the MySensors stack is detected (see https://github.com/mysensors/MySensors/blob/01d8d10153d11cd064d3c7ace637bd9baac32bdb/core/MySensorsCore.cpp#L61 if you're into code).
This happens when you're e.g. calling send(), wait() or sleep() from a receive() function.
Are you running vanilla gateway code, or did you make some modifications? (I mean -- post the code ;-) -- )

joaoabs

Hi,

I also have the same "!MCO:PRO:RC=1" behavior and have a very similar setup: Also running MySensors GW MQTT from a RPI4 with RFM96. The Mysensorg GW don't have any modifications and was obtained from master branch.

My configuration command is this:

./configure --my-gateway=mqtt --my-controller-ip-address=192.168.2.180 --my-mqtt-publish-topic-prefix=mysensors-out --my-mqtt-subscribe-topic-prefix=mysensors-in --my-mqtt-client-id=MySensorsGW --my-transport=rfm69 --my-signing=software --my-signing-request-signatures --my-rfm69-frequency=433 --my-is-rfm69hw --my-signing-debug --my-rfm69-encryption-enabled --my-mqtt-user=mysensorsuser --my-mqtt-password=mysensorspassword

I've this setup since the weekend, so not sure about long-term behaviour, but my RPI seems to be restarting from time to time. Could it be related?

My log:

Dec 02 17:51:14 INFO  Starting gateway...
Dec 02 17:51:14 INFO  Protocol version - 2.3.2
Dec 02 17:51:14 DEBUG MCO:BGN:INIT GW,CP=RPNGLS-X,FQ=NA,REL=255,VER=2.3.2
Dec 02 17:51:14 DEBUG SGN:PER:OK
Dec 02 17:51:14 DEBUG SGN:INI:BND OK
Dec 02 17:51:14 DEBUG TSF:LRT:OK
Dec 02 17:51:14 DEBUG TSM:INIT
Dec 02 17:51:14 DEBUG TSF:WUR:MS=0
Dec 02 17:51:14 DEBUG TSM:INIT:TSP OK
Dec 02 17:51:14 DEBUG TSM:INIT:GW MODE
Dec 02 17:51:14 DEBUG TSM:READY:ID=0,PAR=0,DIS=0
Dec 02 17:51:14 DEBUG MCO:REG:NOT NEEDED
Dec 02 17:51:14 DEBUG MCO:BGN:STP
Dec 02 17:51:14 DEBUG MCO:BGN:INIT OK,TSP=1
Dec 02 17:51:14 DEBUG GWT:RMQ:CONNECTING...
Dec 02 17:51:14 DEBUG connected to 192.168.2.180
Dec 02 17:51:14 DEBUG GWT:RMQ:OK
Dec 02 17:51:14 DEBUG GWT:TPS:TOPIC=mysensors-out/0/255/0/0/18,MSG SENT
Dec 02 17:51:14 DEBUG TSM:READY:NWD REQ
Dec 02 17:51:14 DEBUG SGN:SGN:NREQ=255
Dec 02 17:51:14 DEBUG ?TSF:MSG:SEND,0-0-255-255,s=255,c=3,t=20,pt=0,l=0,sg=0,ft=0,st=OK:
Dec 02 18:06:14 DEBUG TSF:SAN:OK
Dec 02 18:11:14 DEBUG TSM:READY:NWD REQ
Dec 02 18:11:14 DEBUG SGN:SGN:NREQ=255
Dec 02 18:11:14 DEBUG ?TSF:MSG:SEND,0-0-255-255,s=255,c=3,t=20,pt=0,l=0,sg=0,ft=0,st=OK:
Dec 02 18:15:09 DEBUG TSF:MSG:READ,50-50-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
Dec 02 18:15:09 DEBUG TSF:MSG:BC
Dec 02 18:15:09 DEBUG TSF:MSG:FPAR REQ,ID=50
Dec 02 18:15:09 DEBUG TSF:PNG:SEND,TO=0
Dec 02 18:15:09 DEBUG TSF:CKU:OK
Dec 02 18:15:09 DEBUG TSF:MSG:GWL OK
Dec 02 18:15:09 DEBUG SGN:SKP:MSG CMD=3,TYPE=8
Dec 02 18:15:13 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=NACK:0
Dec 02 18:15:14 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
Dec 02 18:15:14 DEBUG SGN:SKP:MSG CMD=3,TYPE=24
Dec 02 18:15:14 DEBUG TSF:MSG:PINGED,ID=50,HP=1
Dec 02 18:15:14 DEBUG SGN:SKP:MSG CMD=3,TYPE=25
Dec 02 18:15:17 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=NACK:1
Dec 02 18:15:18 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0101
Dec 02 18:15:18 DEBUG SGN:SKP:MSG CMD=3,TYPE=15
Dec 02 18:15:18 DEBUG SGN:PRE:SGN REQ,FROM=50
Dec 02 18:15:18 DEBUG SGN:PRE:SGN REQ,TO=50
Dec 02 18:15:18 DEBUG SGN:PRE:WHI NREQ,TO=50
Dec 02 18:15:18 DEBUG SGN:SKP:MSG CMD=3,TYPE=15
Dec 02 18:15:21 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=NACK:0101
Dec 02 18:15:21 DEBUG !SGN:PRE:XMT,TO=50 FAIL
Dec 02 18:15:22 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=16,pt=0,l=0,sg=0:
Dec 02 18:15:22 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:15:22 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:15:25 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=0,ft=0,st=NACK:<NONCE>
Dec 02 18:15:25 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:15:26 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=16,pt=0,l=0,sg=0:
Dec 02 18:15:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:15:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:15:27 DEBUG TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=0,ft=0,st=OK:<NONCE>
Dec 02 18:15:27 DEBUG SGN:NCE:XMT,TO=0
Dec 02 18:15:31 DEBUG !SGN:BND:TMR
Dec 02 18:15:31 DEBUG TSF:MSG:READ,50-50-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
Dec 02 18:15:31 DEBUG TSF:MSG:BC
Dec 02 18:15:31 DEBUG TSF:MSG:FPAR REQ,ID=50
Dec 02 18:15:31 DEBUG TSF:PNG:SEND,TO=0
Dec 02 18:15:31 DEBUG TSF:CKU:OK
Dec 02 18:15:31 DEBUG TSF:MSG:GWL OK
Dec 02 18:15:31 DEBUG SGN:SKP:MSG CMD=3,TYPE=8
Dec 02 18:15:33 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=NACK:0
Dec 02 18:15:35 DEBUG TSF:MSG:READ,50-50-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
Dec 02 18:15:35 DEBUG TSF:MSG:BC
Dec 02 18:15:35 DEBUG TSF:MSG:FPAR REQ,ID=50
Dec 02 18:15:35 DEBUG TSF:CKU:OK,FCTRL
Dec 02 18:15:35 DEBUG TSF:MSG:GWL OK
Dec 02 18:15:36 DEBUG SGN:SKP:MSG CMD=3,TYPE=8
Dec 02 18:15:36 DEBUG TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=OK:0
Dec 02 18:15:38 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
Dec 02 18:15:38 DEBUG SGN:SKP:MSG CMD=3,TYPE=24
Dec 02 18:15:38 DEBUG TSF:MSG:PINGED,ID=50,HP=1
Dec 02 18:15:38 DEBUG SGN:SKP:MSG CMD=3,TYPE=25
Dec 02 18:15:39 DEBUG TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=OK:1
Dec 02 18:15:39 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0101
Dec 02 18:15:39 DEBUG SGN:SKP:MSG CMD=3,TYPE=15
Dec 02 18:15:39 DEBUG SGN:PRE:SGN REQ,FROM=50
Dec 02 18:15:39 DEBUG SGN:PRE:SGN REQ,TO=50
Dec 02 18:15:39 DEBUG SGN:PRE:WHI NREQ,TO=50
Dec 02 18:15:39 DEBUG SGN:SKP:MSG CMD=3,TYPE=15
Dec 02 18:15:40 DEBUG TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0101
Dec 02 18:15:40 DEBUG SGN:PRE:XMT,TO=50
Dec 02 18:15:40 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=16,pt=0,l=0,sg=0:
Dec 02 18:15:40 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:15:40 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:15:41 DEBUG TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=0,ft=0,st=OK:<NONCE>
Dec 02 18:15:41 DEBUG SGN:NCE:XMT,TO=0
Dec 02 18:15:42 DEBUG TSF:MSG:READ,50-50-0,s=255,c=0,t=17,pt=0,l=5,sg=1:2.3.2
Dec 02 18:15:42 DEBUG SGN:BND:NONCE=B9AB7866E554F6B44039BD2DD1A8A222747B4E23A4B23B883BAAAAAAAAAAAAAA
Dec 02 18:15:42 DEBUG SGN:BND:HMAC=339E6D7A45C0C70867AB03E724DCE7DEBD4BF750F1E288B05E46FA0FFB57CF4B
Dec 02 18:15:42 DEBUG SGN:VER:OK
Dec 02 18:15:42 DEBUG GWT:TPS:TOPIC=mysensors-out/50/255/0/0/17,MSG SENT
Dec 02 18:15:42 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:15:42 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:15:42 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:15:43 DEBUG TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 02 18:15:43 DEBUG SGN:NCE:XMT,TO=0
Dec 02 18:15:44 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=6,pt=1,l=1,sg=1:0
Dec 02 18:15:44 DEBUG SGN:BND:NONCE=EC09E7D125DDEC13FCD49CC5536102C512F1917419CC073D67AAAAAAAAAAAAAA
Dec 02 18:15:44 DEBUG SGN:BND:HMAC=C2D4B378CCC38F5E9AF63668476F81BBE6CF6505AC51A1E2EDF5F8E8CDE1AFCD
Dec 02 18:15:44 DEBUG SGN:VER:OK
Dec 02 18:15:44 DEBUG GWT:TPS:TOPIC=mysensors-out/50/255/3/0/6,MSG SENT
Dec 02 18:15:46 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:15:46 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:15:46 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:15:49 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:15:49 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:15:50 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:15:50 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:15:50 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:15:53 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:15:53 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:15:54 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=12,pt=0,l=9,sg=1:JS 2018V1
Dec 02 18:15:54 DEBUG SGN:BND:NONCE=182B237ED9621FB9A1E781E4A148B2BA268A2F05D7F6B3FC28AAAAAAAAAAAAAA
Dec 02 18:15:54 DEBUG SGN:BND:HMAC=A5093EA3A989D6F9FFEDDEBABF0FB0F879B7319799BA79996B55E3457D56458F
Dec 02 18:15:54 DEBUG SGN:VER:OK
Dec 02 18:15:54 DEBUG GWT:TPS:TOPIC=mysensors-out/50/255/3/0/12,MSG SENT
Dec 02 18:15:56 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=12,pt=0,l=9,sg=1:JS 2018V1
Dec 02 18:15:56 DEBUG !SGN:BND:VER ONGOING
Dec 02 18:15:56 DEBUG !SGN:VER:FAIL
Dec 02 18:15:56 DEBUG !TSF:MSG:SIGN VERIFY FAIL
Dec 02 18:15:57 DEBUG TSF:MSG:READ,50-50-0,s=20,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:15:57 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:15:57 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:16:00 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:16:00 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:16:01 DEBUG TSF:MSG:READ,50-50-0,s=40,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:16:01 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:16:01 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:16:02 DEBUG TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 02 18:16:02 DEBUG SGN:NCE:XMT,TO=0
Dec 02 18:16:02 DEBUG TSF:MSG:READ,50-50-0,s=40,c=0,t=7,pt=0,l=0,sg=1:
Dec 02 18:16:02 DEBUG SGN:BND:NONCE=5712908BE02C29E1D93CE33EDB1BE6FFFF1BB453B6A970C679AAAAAAAAAAAAAA
Dec 02 18:16:02 DEBUG SGN:BND:HMAC=1F143BBD8EB040F1733443251F5CF2CF282C64C287043851EE8AE7A3EAFC3068
Dec 02 18:16:02 DEBUG SGN:VER:OK
Dec 02 18:16:02 DEBUG GWT:TPS:TOPIC=mysensors-out/50/40/0/0/7,MSG SENT
Dec 02 18:16:04 DEBUG TSF:MSG:READ,50-50-0,s=40,c=0,t=7,pt=0,l=0,sg=1:
Dec 02 18:16:04 DEBUG !SGN:BND:VER ONGOING
Dec 02 18:16:04 DEBUG !SGN:VER:FAIL
Dec 02 18:16:04 DEBUG !TSF:MSG:SIGN VERIFY FAIL
Dec 02 18:16:05 DEBUG TSF:MSG:READ,50-50-0,s=40,c=0,t=7,pt=0,l=0,sg=1:
Dec 02 18:16:05 DEBUG !SGN:BND:VER ONGOING
Dec 02 18:16:05 DEBUG !SGN:VER:FAIL
Dec 02 18:16:05 DEBUG !TSF:MSG:SIGN VERIFY FAIL
Dec 02 18:16:06 DEBUG TSF:MSG:READ,50-50-0,s=199,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:16:06 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:16:06 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:16:10 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:16:10 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:16:10 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=26,pt=1,l=1,sg=1:2
Dec 02 18:16:10 DEBUG SGN:SKP:MSG CMD=3,TYPE=26
Dec 02 18:16:10 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:16:12 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=16,pt=0,l=0,sg=0,ft=0,st=NACK:
Dec 02 18:16:12 DEBUG !SGN:SGN:NCE REQ,TO=50 FAIL
Dec 02 18:16:12 DEBUG !TSF:MSG:SIGN FAIL
Dec 02 18:16:12 DEBUG !SGN:BND:TMR
Dec 02 18:16:12 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=17,pt=6,l=25,sg=0:<NONCE>
Dec 02 18:16:12 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:16:12 DEBUG SGN:NCE:FROM=50
Dec 02 18:16:12 DEBUG SGN:BND:NONCE=5CE4D0A8133FD9236FCDD14E5942DF2275139361A707364C6EAAAAAAAAAAAAAA
Dec 02 18:16:12 DEBUG SGN:BND:HMAC=72E25B0399F24BD0673FE2631274C4F69E3940F9D52C99A752379708A8008699
Dec 02 18:16:13 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=26,pt=1,l=1,sg=1:2
Dec 02 18:16:13 DEBUG SGN:SKP:MSG CMD=3,TYPE=26
Dec 02 18:16:13 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:16:13 DEBUG TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=16,pt=0,l=0,sg=1,ft=0,st=OK:
Dec 02 18:16:13 DEBUG SGN:SGN:NCE REQ,TO=50
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:13 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:14 DEBUG !MCO:PRO:RC=1
Dec 02 18:16:15 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=17,pt=6,l=25,sg=1:<NONCE>
Dec 02 18:16:15 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:16:15 DEBUG SGN:NCE:FROM=50
Dec 02 18:16:15 DEBUG SGN:BND:NONCE=43E1AFF0F305BD763984D88C5F49E8FF47831FDD21E3ECB549AAAAAAAAAAAAAA
Dec 02 18:16:15 DEBUG SGN:BND:HMAC=05F3FF84848C38FD67CC257CB38BC16BE2F4D9DBC714C08846B9FE701F0CA224
Dec 02 18:16:15 DEBUG SGN:SGN:SGN
Dec 02 18:16:16 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=27,pt=1,l=1,sg=1,ft=0,st=NACK:1
Dec 02 18:16:17 DEBUG TSF:MSG:READ,50-50-0,s=20,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:16:17 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:16:17 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:16:17 DEBUG TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 02 18:16:17 DEBUG SGN:NCE:XMT,TO=0
Dec 02 18:16:18 DEBUG TSF:MSG:READ,50-50-0,s=20,c=1,t=0,pt=7,l=5,sg=1:19.5
Dec 02 18:16:18 DEBUG SGN:BND:NONCE=1D8189E0C5B8E85A0A7E58B1DA5F02B2A0231B5560F810FB07AAAAAAAAAAAAAA
Dec 02 18:16:18 DEBUG SGN:BND:HMAC=291F56D50D99E5070C360ABE46D0C6E24ACF2775A691FBA68ECDE62B58D8BBA2
Dec 02 18:16:18 DEBUG SGN:VER:OK
Dec 02 18:16:18 DEBUG GWT:TPS:TOPIC=mysensors-out/50/20/1/0/0,MSG SENT
Dec 02 18:16:19 DEBUG TSF:MSG:READ,50-50-0,s=40,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:16:19 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:16:19 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:16:20 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:16:20 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:16:24 DEBUG TSF:MSG:READ,50-50-0,s=199,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:16:24 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:16:24 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:16:26 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:16:26 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:16:26 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:16:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:16:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:16:28 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:16:28 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:16:29 DEBUG TSF:MSG:READ,50-50-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
Dec 02 18:16:29 DEBUG TSF:MSG:BC
Dec 02 18:16:29 DEBUG TSF:MSG:FPAR REQ,ID=50
Dec 02 18:16:29 DEBUG TSF:PNG:SEND,TO=0
Dec 02 18:16:29 DEBUG TSF:CKU:OK
Dec 02 18:16:29 DEBUG TSF:MSG:GWL OK
Dec 02 18:16:30 DEBUG SGN:SKP:MSG CMD=3,TYPE=8
Dec 02 18:16:33 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=8,pt=1,l=1,sg=1,ft=0,st=NACK:0
Dec 02 18:16:33 DEBUG !SGN:BND:TMR
Dec 02 18:16:33 DEBUG TSF:MSG:READ,50-50-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
Dec 02 18:16:33 DEBUG SGN:SKP:MSG CMD=3,TYPE=24
Dec 02 18:16:33 DEBUG TSF:MSG:PINGED,ID=50,HP=1
Dec 02 18:16:33 DEBUG SGN:SKP:MSG CMD=3,TYPE=25
Dec 02 18:16:37 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=25,pt=1,l=1,sg=1,ft=0,st=NACK:1
Dec 02 18:17:27 DEBUG TSF:MSG:READ,50-50-0,s=20,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:17:27 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:17:27 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:17:30 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:17:30 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:17:31 DEBUG TSF:MSG:READ,50-50-0,s=40,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:17:31 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:17:31 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:17:33 DEBUG !TSF:MSG:SEND,0-0-50-50,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:17:33 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:17:45 DEBUG !SGN:BND:TMR
Dec 02 18:17:45 DEBUG TSF:MSG:READ,80-80-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
Dec 02 18:17:45 DEBUG TSF:MSG:BC
Dec 02 18:17:45 DEBUG TSF:MSG:FPAR REQ,ID=80
Dec 02 18:17:45 DEBUG TSF:PNG:SEND,TO=0
Dec 02 18:17:45 DEBUG TSF:CKU:OK
Dec 02 18:17:45 DEBUG TSF:MSG:GWL OK
Dec 02 18:17:45 DEBUG SGN:SKP:MSG CMD=3,TYPE=8
Dec 02 18:17:45 DEBUG TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=8,pt=1,l=1,sg=1,ft=0,st=OK:0
Dec 02 18:17:49 DEBUG TSF:MSG:READ,80-80-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
Dec 02 18:17:49 DEBUG SGN:SKP:MSG CMD=3,TYPE=24
Dec 02 18:17:49 DEBUG TSF:MSG:PINGED,ID=80,HP=1
Dec 02 18:17:49 DEBUG SGN:SKP:MSG CMD=3,TYPE=25
Dec 02 18:17:51 DEBUG TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=25,pt=1,l=1,sg=1,ft=0,st=OK:1
Dec 02 18:17:51 DEBUG TSF:MSG:READ,80-80-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0101
Dec 02 18:17:51 DEBUG SGN:SKP:MSG CMD=3,TYPE=15
Dec 02 18:17:51 DEBUG SGN:PRE:SGN REQ,FROM=80
Dec 02 18:17:51 DEBUG SGN:PRE:SGN REQ,TO=80
Dec 02 18:17:51 DEBUG SGN:PRE:WHI NREQ,TO=80
Dec 02 18:17:51 DEBUG SGN:SKP:MSG CMD=3,TYPE=15
Dec 02 18:17:53 DEBUG TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0101
Dec 02 18:17:53 DEBUG SGN:PRE:XMT,TO=80
Dec 02 18:17:53 DEBUG TSF:MSG:READ,80-80-0,s=255,c=3,t=16,pt=0,l=0,sg=0:
Dec 02 18:17:53 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:17:53 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:17:54 DEBUG TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=17,pt=6,l=25,sg=0,ft=0,st=OK:<NONCE>
Dec 02 18:17:54 DEBUG SGN:NCE:XMT,TO=0
Dec 02 18:17:55 DEBUG TSF:MSG:READ,80-80-0,s=255,c=0,t=17,pt=0,l=5,sg=1:2.3.2
Dec 02 18:17:55 DEBUG SGN:BND:NONCE=AA53BD3EC4C43E31C0DD2CF59DAA4FEEE100CC72B338773F46AAAAAAAAAAAAAA
Dec 02 18:17:55 DEBUG SGN:BND:HMAC=2665DDD335E2DE3EBC594585C69106A743ECDDC0784AA674BA118E7F1F83E39B
Dec 02 18:17:55 DEBUG SGN:VER:OK
Dec 02 18:17:55 DEBUG GWT:TPS:TOPIC=mysensors-out/80/255/0/0/17,MSG SENT
Dec 02 18:17:55 DEBUG TSF:MSG:READ,80-80-0,s=255,c=0,t=17,pt=0,l=5,sg=1:2.3.2
Dec 02 18:17:55 DEBUG !SGN:BND:VER ONGOING
Dec 02 18:17:55 DEBUG !SGN:VER:FAIL
Dec 02 18:17:55 DEBUG !TSF:MSG:SIGN VERIFY FAIL
Dec 02 18:17:56 DEBUG TSF:MSG:READ,80-80-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:17:56 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:17:56 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:17:56 DEBUG TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 02 18:17:56 DEBUG SGN:NCE:XMT,TO=0
Dec 02 18:17:57 DEBUG TSF:MSG:READ,80-80-0,s=255,c=3,t=6,pt=1,l=1,sg=1:0
Dec 02 18:17:57 DEBUG SGN:BND:NONCE=E847B8BAFEC872B06EE6E5F9A5763963AB58CFE6396FCF4C43AAAAAAAAAAAAAA
Dec 02 18:17:57 DEBUG SGN:BND:HMAC=72304FE2A42262DD6A02C39774D1BA3007D2FFFB775DBC5E5AE53728E6415214
Dec 02 18:17:57 DEBUG SGN:VER:OK
Dec 02 18:17:57 DEBUG GWT:TPS:TOPIC=mysensors-out/80/255/3/0/6,MSG SENT
Dec 02 18:18:01 DEBUG TSF:MSG:READ,80-80-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:18:01 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:18:01 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:18:03 DEBUG !TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:18:03 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:18:03 DEBUG TSF:MSG:READ,80-80-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:18:03 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:18:03 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:18:06 DEBUG !TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:18:06 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:18:06 DEBUG TSF:MSG:READ,80-80-0,s=20,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:18:06 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:18:06 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:18:09 DEBUG !TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=NACK:<NONCE>
Dec 02 18:18:09 DEBUG !SGN:NCE:XMT,TO=0 FAIL
Dec 02 18:18:09 DEBUG TSF:MSG:READ,80-80-0,s=21,c=3,t=16,pt=0,l=0,sg=1:
Dec 02 18:18:09 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:18:09 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:18:10 DEBUG TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 02 18:18:10 DEBUG SGN:NCE:XMT,TO=0
Dec 02 18:18:11 DEBUG TSF:MSG:READ,80-80-0,s=21,c=0,t=6,pt=0,l=0,sg=1:
Dec 02 18:18:11 DEBUG SGN:BND:NONCE=859AB432BF7F3B7A36FEF9B2B25A95417590EDD7935DC61C42AAAAAAAAAAAAAA
Dec 02 18:18:11 DEBUG SGN:BND:HMAC=8A526390C16A90E7B927720158F2809A48874D415DF8B447F9AE76ED5A61A29D
Dec 02 18:18:11 DEBUG SGN:VER:OK
Dec 02 18:18:11 DEBUG GWT:TPS:TOPIC=mysensors-out/80/21/0/0/6,MSG SENT
Dec 02 18:18:11 DEBUG TSF:MSG:READ,80-80-0,s=255,c=3,t=26,pt=1,l=1,sg=1:2
Dec 02 18:18:11 DEBUG SGN:SKP:MSG CMD=3,TYPE=26
Dec 02 18:18:11 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 02 18:18:11 DEBUG TSF:MSG:SEND,0-0-80-80,s=255,c=3,t=16,pt=0,l=0,sg=1,ft=0,st=OK:
Dec 02 18:18:11 DEBUG SGN:SGN:NCE REQ,TO=80
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:11 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG !MCO:PRO:RC=1
Dec 02 18:18:12 DEBUG TSF:MSG:READ,80-80-0,s=255,c=3,t=17,pt=6,l=25,sg=1:<NONCE>
Dec 02 18:18:12 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 02 18:18:12 DEBUG SGN:NCE:FROM=80

TimO

@Yveaux I'm simply running the stock code from github:

https://github.com/mysensors/MySensors/blob/development/examples_linux/mysgw.cpp

I've only configured (./configure) the make process and that's it. No changes to the code.

Happens with 2.3.2 and 2.4-alpha.

Yveaux

@TimO I'm unsure then if this recursive call is 'intended' behavior by the stack or not. The check has been added not too long ago, so maybe it has not been noticed before.
Looks MQTT gateway related, as it appears on both esp and rpi.
Could you file a bug report on github to follow up?

TimO

@Yveaux Sure, here it is: https://github.com/mysensors/MySensors/issues/1458

frits

Hi,
I'm getting those MCO:PRO's on the node, the AVR gateway, the raspberry pi gateway, with NRF24 and RFM69 and I'm pretty sure not only on MQTT gateway but also Ethernet gateway. Could test it.

https://forum.mysensors.org/topic/11310

For me it seems to be related to a) signing and b) MY_TRANSPORT_WAIT_READY_MS.

The log looks bad, but node and gateway are working, after all.
I got used to it.

TimO

@frits Thank you for your input. My gateway definitely becomes unreliable after a while.

Maybe your hint regarding signing is worth some investigation. My sensors (temperature / humidity) which don't use signing seem to work fine over time. My actuators are using signing and become unreliable after a time. Currently I'm using a 2.2.0 gateway with 2.3.2 nodes and the suspicious debug message hasn't appeared yet. Maybe I will disable signing at all and test again.

Yveaux

@TimO you are both using rfm69, only one uses signing. I'm using nrf24 mostly with mqtt gw, no signing, and can't recollect seeing it.
I would start looking in the direction of the nrf69 driver /transport

frits

just a quick test with nrf24 node and nrf24 mqtt gateway with signing:

0 MCO:BGN:INIT GW,CP=RNNGAA--,FQ=8,REL=255,VER=2.3.2
38 TSM:INIT
40 TSF:WUR:MS=0
47 TSM:INIT:TSP OK
49 TSM:INIT:GW MODE
51 TSM:READY:ID=0,PAR=0,DIS=0
55 MCO:REG:NOT NEEDED
618 GWT:TPC:IP=192.168.99.13
1622 MCO:BGN:STP
***********************************************
MySensors W5100 Gateway
Version 0.2
own mac address: DE:AD:BA:BE:99:13
own network address: 192.168.99.13
Gateway type: mqtt
mqtt controller address: 192.168.99.20
mqtt controller port: 1883
mqtt client id: mysgw1
mqtt publish prefix: mysgw1-out
mqtt subscribe prefix: mysgw1-in
Radio type: NRF24
signing: yes
2150 MCO:BGN:INIT OK,TSP=1
2160 GWT:TPC:IP=192.168.99.13
3164 GWT:RMQ:CONNECTING...
3373 GWT:RMQ:OK
3375 GWT:TPS:TOPIC=mysgw1-out/0/255/0/0/18,MSG SENT
3383 TSM:READY:NWD REQ
3387 ?TSF:MSG:SEND,0-0-255-255,s=255,c=3,t=20,pt=0,l=0,sg=0,ft=0,st=OK:
8976 TSF:MSG:READ,123-123-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
8982 TSF:MSG:BC
8984 TSF:MSG:FPAR REQ,ID=123
8988 TSF:CKU:OK,FCTRL
8990 TSF:MSG:GWL OK
9795 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=OK:0
11005 TSF:MSG:READ,123-123-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
11012 TSF:MSG:PINGED,ID=123,HP=1
11018 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=OK:1
11044 TSF:MSG:READ,123-123-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0101
11053 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0101
11075 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=0:
11159 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=0,ft=0,st=OK:<NONCE>
11331 TSF:MSG:READ,123-123-0,s=255,c=0,t=17,pt=0,l=5,sg=1:2.3.2
11446 GWT:TPS:TOPIC=mysgw1-out/123/255/0/0/17,MSG SENT
11454 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
11542 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
11712 TSF:MSG:READ,123-123-0,s=255,c=3,t=6,pt=1,l=1,sg=1:0
11827 GWT:TPS:TOPIC=mysgw1-out/123/255/3/0/6,MSG SENT
13703 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
13787 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
13957 TSF:MSG:READ,123-123-0,s=255,c=3,t=11,pt=0,l=20,sg=1:Hello MySensors 328P
14077 GWT:TPS:TOPIC=mysgw1-out/123/255/3/0/11,MSG SENT
14086 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
14170 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
14340 TSF:MSG:READ,123-123-0,s=255,c=3,t=12,pt=0,l=3,sg=1:1.3
14452 GWT:TPS:TOPIC=mysgw1-out/123/255/3/0/12,MSG SENT
14460 TSF:MSG:READ,123-123-0,s=0,c=3,t=16,pt=0,l=0,sg=1:
14546 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
14716 TSF:MSG:READ,123-123-0,s=0,c=0,t=36,pt=0,l=6,sg=1:system
14833 GWT:TPS:TOPIC=mysgw1-out/123/0/0/0/36,MSG SENT
14841 TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
14925 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
15095 TSF:MSG:READ,123-123-0,s=1,c=0,t=36,pt=0,l=9,sg=1:timestamp
15212 GWT:TPS:TOPIC=mysgw1-out/123/1/0/0/36,MSG SENT
15220 TSF:MSG:READ,123-123-0,s=2,c=3,t=16,pt=0,l=0,sg=1:
15302 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
15472 TSF:MSG:READ,123-123-0,s=2,c=0,t=3,pt=0,l=3,sg=1:led
15589 GWT:TPS:TOPIC=mysgw1-out/123/2/0/0/3,MSG SENT
15597 TSF:MSG:READ,123-123-0,s=255,c=3,t=26,pt=1,l=1,sg=1:2
15605 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=16,pt=0,l=0,sg=0,ft=0,st=OK:
15613 !MCO:PRO:RC=1
15616 !MCO:PRO:RC=1
15618 !MCO:PRO:RC=1
15620 !MCO:PRO:RC=1
15624 !MCO:PRO:RC=1
15626 !MCO:PRO:RC=1
15628 !MCO:PRO:RC=1
15630 !MCO:PRO:RC=1
15634 !MCO:PRO:RC=1
15636 !MCO:PRO:RC=1
15638 !MCO:PRO:RC=1
15640 !MCO:PRO:RC=1
15644 !MCO:PRO:RC=1
15646 !MCO:PRO:RC=1
15648 !MCO:PRO:RC=1
15650 !MCO:PRO:RC=1
15654 !MCO:PRO:RC=1
15656 !MCO:PRO:RC=1
15659 !MCO:PRO:RC=1
15661 !MCO:PRO:RC=1
15665 !MCO:PRO:RC=1
15667 !MCO:PRO:RC=1
15669 !MCO:PRO:RC=1
15671 !MCO:PRO:RC=1
15673 !MCO:PRO:RC=1
15677 !MCO:PRO:RC=1
15679 !MCO:PRO:RC=1
15681 !MCO:PRO:RC=1
15683 !MCO:PRO:RC=1
15687 !MCO:PRO:RC=1
15689 !MCO:PRO:RC=1
15691 !MCO:PRO:RC=1
15693 !MCO:PRO:RC=1
15697 !MCO:PRO:RC=1
15699 !MCO:PRO:RC=1
15702 !MCO:PRO:RC=1
15704 !MCO:PRO:RC=1
15708 !MCO:PRO:RC=1
15710 !MCO:PRO:RC=1
15712 !MCO:PRO:RC=1
15714 !MCO:PRO:RC=1
15718 !MCO:PRO:RC=1
15720 !MCO:PRO:RC=1
15722 !MCO:PRO:RC=1
15724 !MCO:PRO:RC=1
15728 !MCO:PRO:RC=1
15730 !MCO:PRO:RC=1
15732 !MCO:PRO:RC=1
15734 TSF:MSG:READ,123-123-0,s=255,c=3,t=17,pt=6,l=25,sg=0:<NONCE>
15855 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=27,pt=1,l=1,sg=1,ft=0,st=OK:1

Yveaux

@frits and without signing?

frits

not a single MCO:PRO

0 MCO:BGN:INIT GW,CP=RNNGA---,FQ=8,REL=255,VER=2.3.2
4 TSM:INIT
6 TSF:WUR:MS=0
12 TSM:INIT:TSP OK
14 TSM:INIT:GW MODE
16 TSM:READY:ID=0,PAR=0,DIS=0
20 MCO:REG:NOT NEEDED
583 GWT:TPC:IP=192.168.99.13
1587 MCO:BGN:STP
***********************************************
MySensors W5100 Gateway
Version 0.2
own mac address: DE:AD:BA:BE:99:13
own network address: 192.168.99.13
Gateway type: mqtt
mqtt controller address: 192.168.99.20
mqtt controller port: 1883
mqtt client id: mysgw1
mqtt publish prefix: mysgw1-out
mqtt subscribe prefix: mysgw1-in
Radio type: NRF24
signing: no
2115 MCO:BGN:INIT OK,TSP=1
2125 GWT:TPC:IP=192.168.99.13
3129 GWT:RMQ:CONNECTING...
3338 GWT:RMQ:OK
3340 GWT:TPS:TOPIC=mysgw1-out/0/255/0/0/18,MSG SENT
3348 TSM:READY:NWD REQ
3352 ?TSF:MSG:SEND,0-0-255-255,s=255,c=3,t=20,pt=0,l=0,sg=0,ft=0,st=OK:
11919 TSF:MSG:READ,123-123-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
11925 TSF:MSG:BC
11927 TSF:MSG:FPAR REQ,ID=123
11931 TSF:PNG:SEND,TO=0
11933 TSF:CKU:OK
11935 TSF:MSG:GWL OK
12613 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=OK:0
13942 TSF:MSG:READ,123-123-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
13948 TSF:MSG:PINGED,ID=123,HP=1
13955 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=OK:1
13971 TSF:MSG:READ,123-123-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0100
13981 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0100
13989 TSF:MSG:READ,123-123-0,s=255,c=0,t=17,pt=0,l=5,sg=0:2.3.2
13998 GWT:TPS:TOPIC=mysgw1-out/123/255/0/0/17,MSG SENT
14006 TSF:MSG:READ,123-123-0,s=255,c=3,t=6,pt=1,l=1,sg=0:0
14012 GWT:TPS:TOPIC=mysgw1-out/123/255/3/0/6,MSG SENT
16017 TSF:MSG:READ,123-123-0,s=255,c=3,t=11,pt=0,l=20,sg=0:Hello MySensors 328P
16027 GWT:TPS:TOPIC=mysgw1-out/123/255/3/0/11,MSG SENT
16033 TSF:MSG:READ,123-123-0,s=255,c=3,t=12,pt=0,l=3,sg=0:1.3
16041 GWT:TPS:TOPIC=mysgw1-out/123/255/3/0/12,MSG SENT
16050 TSF:MSG:READ,123-123-0,s=0,c=0,t=36,pt=0,l=6,sg=0:system
16056 GWT:TPS:TOPIC=mysgw1-out/123/0/0/0/36,MSG SENT
16064 TSF:MSG:READ,123-123-0,s=1,c=0,t=36,pt=0,l=9,sg=0:timestamp
16072 GWT:TPS:TOPIC=mysgw1-out/123/1/0/0/36,MSG SENT
16078 TSF:MSG:READ,123-123-0,s=2,c=0,t=3,pt=0,l=3,sg=0:led
16087 GWT:TPS:TOPIC=mysgw1-out/123/2/0/0/3,MSG SENT
16093 TSF:MSG:READ,123-123-0,s=255,c=3,t=26,pt=1,l=1,sg=0:2
16101 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=27,pt=1,l=1,sg=0,ft=0,st=OK:1
16117 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=10,sg=0:Hello 6258
16125 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
18132 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=10,sg=0:Hello 8269
18141 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
20152 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 10283
20160 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
22169 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 12294
22177 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
24186 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 14305
24195 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
26202 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 16316
26210 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
28219 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 18327
28227 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
30236 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 20338
30244 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
32251 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 22349
32260 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
34271 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 24363
34279 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
36288 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 26374
36294 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
38303 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 28385
38311 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
39231 GWT:IMQ:TOPIC=mysgw1-in/123/3/0/0/1, MSG RECEIVED
39239 TSF:MSG:SEND,0-0-123-123,s=3,c=0,t=1,pt=0,l=0,sg=0,ft=0,st=OK:
40321 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 30396
40329 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
42338 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 32407
42346 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
44353 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 34418
44361 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
46370 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 36429
46379 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
48390 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 38443
48398 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
50405 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 40454
50413 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
51738 GWT:IMQ:TOPIC=mysgw1-in/123/3/0/0/1, MSG RECEIVED
51744 TSF:MSG:SEND,0-0-123-123,s=3,c=0,t=1,pt=0,l=0,sg=0,ft=0,st=OK:
52422 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 42465
52430 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
53186 GWT:IMQ:TOPIC=mysgw1-in/123/3/0/0/1, MSG RECEIVED
53194 TSF:MSG:SEND,0-0-123-123,s=3,c=0,t=1,pt=0,l=0,sg=0,ft=0,st=OK:
54439 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 44476
54448 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
56455 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 46487
56463 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT
58472 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=0:Hello 48498
58480 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT

frits

same results on EthernetGateway instead of MQTT Gateway: MCO:PRO only with signing, only on gateway

Yveaux

@frits ah, now I see @TimO is also using singing!
Found the smoking :gun: !

Yveaux

@TimO @frits could one of you build & run with MY_DEBUG_VERBOSE_SIGNING defined, and post the output?

frits

@Yveaux I have a raspi gw with debug signatures ready:

pi@zerogwtest:~/MySensors $ sudo ./mysgw.nrf24
Dec 04 05:02:10 INFO  Starting gateway...
Dec 04 05:02:10 INFO  Protocol version - 2.3.2
Dec 04 05:02:10 DEBUG MCO:BGN:INIT GW,CP=RNNGLS--,FQ=NA,REL=255,VER=2.3.2
Dec 04 05:02:10 DEBUG SGN:PER:OK
Dec 04 05:02:10 DEBUG SGN:INI:BND OK
Dec 04 05:02:10 DEBUG TSF:LRT:OK
Dec 04 05:02:10 DEBUG TSM:INIT
Dec 04 05:02:10 DEBUG TSF:WUR:MS=0
Dec 04 05:02:10 DEBUG TSM:INIT:TSP OK
Dec 04 05:02:10 DEBUG TSM:INIT:GW MODE
Dec 04 05:02:10 DEBUG TSM:READY:ID=0,PAR=0,DIS=0
Dec 04 05:02:10 DEBUG MCO:REG:NOT NEEDED
Dec 04 05:02:10 DEBUG MCO:BGN:STP
MY_BAUD_RATE=(115200ul)
MY_CONTROLLER_IP_ADDRESS=127.0.0.1
MY_DEBUG=true
MY_DEBUG_VERBOSE_SIGNING=true
MY_GATEWAY_LINUX=true
MY_GATEWAY_MQTT_CLIENT=true
MY_MQTT_CLIENT_ID="mys-nrf-gw"
MY_MQTT_PASSWORD=__null
MY_MQTT_PUBLISH_TOPIC_PREFIX="mys-nrf-out"
MY_MQTT_SUBSCRIBE_TOPIC_PREFIX="mys-nrf-in"
MY_MQTT_USER=__null
MY_LINUX_CONFIG_FILE="/etc/mysensors.conf"
MY_NODE_ID=((255u))
MY_PORT=1883
MY_RADIO_RF24=true
MY_RF24_CE_PIN=((22))
MY_RF24_CHANNEL=(76)
MY_RF24_CS_PIN=((SS))
MY_RF24_PA_LEVEL=((2))
MY_SIGNING_REQUEST_SIGNATURES=true
MY_SIGNING_SOFT=true
MY_VERIFICATION_TIMEOUT_MS=(5*1000ul)
SPI_SPIDEV_DEVICE="/dev/spidev1.1"
Dec 04 05:02:10 DEBUG MCO:BGN:INIT OK,TSP=1
Dec 04 05:02:10 DEBUG GWT:RMQ:CONNECTING...
Dec 04 05:02:10 DEBUG connected to 127.0.0.1
Dec 04 05:02:10 DEBUG GWT:RMQ:OK
Dec 04 05:02:10 DEBUG GWT:TPS:TOPIC=mys-nrf-out/0/255/0/0/18,MSG SENT
Dec 04 05:02:10 DEBUG TSM:READY:NWD REQ
Dec 04 05:02:10 DEBUG SGN:SGN:NREQ=255
Dec 04 05:02:10 DEBUG ?TSF:MSG:SEND,0-0-255-255,s=255,c=3,t=20,pt=0,l=0,sg=0,ft=0,st=OK:
Dec 04 05:02:21 DEBUG TSF:MSG:READ,123-123-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
Dec 04 05:02:21 DEBUG TSF:MSG:BC
Dec 04 05:02:21 DEBUG TSF:MSG:FPAR REQ,ID=123
Dec 04 05:02:21 DEBUG TSF:PNG:SEND,TO=0
Dec 04 05:02:21 DEBUG TSF:CKU:OK
Dec 04 05:02:21 DEBUG TSF:MSG:GWL OK
Dec 04 05:02:21 DEBUG SGN:SKP:MSG CMD=3,TYPE=8
Dec 04 05:02:21 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=OK:0
Dec 04 05:02:23 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
Dec 04 05:02:23 DEBUG SGN:SKP:MSG CMD=3,TYPE=24
Dec 04 05:02:23 DEBUG TSF:MSG:PINGED,ID=123,HP=1
Dec 04 05:02:23 DEBUG SGN:SKP:MSG CMD=3,TYPE=25
Dec 04 05:02:23 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=OK:1
Dec 04 05:02:23 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0101
Dec 04 05:02:23 DEBUG SGN:SKP:MSG CMD=3,TYPE=15
Dec 04 05:02:23 DEBUG SGN:PRE:SGN REQ,FROM=123
Dec 04 05:02:23 DEBUG SGN:PRE:SGN REQ,TO=123
Dec 04 05:02:23 DEBUG SGN:PRE:WHI NREQ,TO=123
Dec 04 05:02:23 DEBUG SGN:SKP:MSG CMD=3,TYPE=15
Dec 04 05:02:23 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0101
Dec 04 05:02:23 DEBUG SGN:PRE:XMT,TO=123
Dec 04 05:02:23 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=0:
Dec 04 05:02:23 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:23 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:23 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=0,ft=0,st=OK:<NONCE>
Dec 04 05:02:23 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:23 DEBUG TSF:MSG:READ,123-123-0,s=255,c=0,t=17,pt=0,l=5,sg=1:2.3.2
Dec 04 05:02:23 DEBUG SGN:BND:NONCE=4B6126A21920FCBC12BE70183533C27C49AE3CD55094914800AAAAAAAAAAAAAA
Dec 04 05:02:23 DEBUG SGN:BND:HMAC=ECDD094309264B891BBA3E2D142B2E08B79784B79A1816D53B26361EBABCF446
Dec 04 05:02:23 DEBUG SGN:VER:OK
Dec 04 05:02:23 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/255/0/0/17,MSG SENT
Dec 04 05:02:23 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:23 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:23 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:23 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:23 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:23 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=6,pt=1,l=1,sg=1:0
Dec 04 05:02:23 DEBUG SGN:BND:NONCE=F07B9A2B93327E5130177F095042D6954B202A130B2A72B9E5AAAAAAAAAAAAAA
Dec 04 05:02:23 DEBUG SGN:BND:HMAC=C34C02E0990FE19990C11FA97D9A55931AF2D99A5890186E877C3C1669FCBDA7
Dec 04 05:02:23 DEBUG SGN:VER:OK
Dec 04 05:02:23 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/255/3/0/6,MSG SENT
Dec 04 05:02:25 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:25 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:25 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:25 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:25 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=11,pt=0,l=20,sg=1:Hello MySensors 328P
Dec 04 05:02:26 DEBUG SGN:BND:NONCE=4A08E36D23F76E6A0940B8C980652ECF7C335F4144209DF1E1AAAAAAAAAAAAAA
Dec 04 05:02:26 DEBUG SGN:BND:HMAC=001176474590821EE608440B7639881860DC1ECA9C17868B947D06756C71B105
Dec 04 05:02:26 DEBUG SGN:VER:OK
Dec 04 05:02:26 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/255/3/0/11,MSG SENT
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:26 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:26 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=12,pt=0,l=3,sg=1:1.3
Dec 04 05:02:26 DEBUG SGN:BND:NONCE=A542EE79921BC3744AD323286768428EE8D7FDED63887EC112AAAAAAAAAAAAAA
Dec 04 05:02:26 DEBUG SGN:BND:HMAC=A4E56BED2C58CA5A332B7CC03EE08556550A804F8A0F042CDB58C012779276B6
Dec 04 05:02:26 DEBUG SGN:VER:OK
Dec 04 05:02:26 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/255/3/0/12,MSG SENT
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=0,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:26 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:26 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=0,c=0,t=36,pt=0,l=6,sg=1:system
Dec 04 05:02:26 DEBUG SGN:BND:NONCE=172B88ACF73CC49618472C3A3AA83FF5A6E0D8805211F8D965AAAAAAAAAAAAAA
Dec 04 05:02:26 DEBUG SGN:BND:HMAC=5D6E3EE95F86B335218C5FC90CB11D4EC141301DA071AEBEA16F4D6FD5038CFE
Dec 04 05:02:26 DEBUG SGN:VER:OK
Dec 04 05:02:26 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/0/0/0/36,MSG SENT
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:26 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:26 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=1,c=0,t=36,pt=0,l=9,sg=1:timestamp
Dec 04 05:02:26 DEBUG SGN:BND:NONCE=A621A9A841141437693FA157CC6D115EB1EA732165F7FF1958AAAAAAAAAAAAAA
Dec 04 05:02:26 DEBUG SGN:BND:HMAC=42CBC8BF5AEB45E21DE5FE86482F6A9CDF5349A6960E407844BF2E06509B80A6
Dec 04 05:02:26 DEBUG SGN:VER:OK
Dec 04 05:02:26 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/1/0/0/36,MSG SENT
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=2,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:26 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:26 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=2,c=0,t=3,pt=0,l=3,sg=1:led
Dec 04 05:02:26 DEBUG SGN:BND:NONCE=4B1909C8A7DBDABFAF157943AB0AAB1D322123A2B8177F2732AAAAAAAAAAAAAA
Dec 04 05:02:26 DEBUG SGN:BND:HMAC=0F31776B1B67825DA94409AF8B220FE9BCB5E4BB9FB1CD618FBD086D51C4D598
Dec 04 05:02:26 DEBUG SGN:VER:OK
Dec 04 05:02:26 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/2/0/0/3,MSG SENT
Dec 04 05:02:26 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=26,pt=1,l=1,sg=1:2
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=26
Dec 04 05:02:26 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:26 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=16,pt=0,l=0,sg=0,ft=0,st=OK:
Dec 04 05:02:26 DEBUG SGN:SGN:NCE REQ,TO=123
Dec 04 05:02:26 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG !MCO:PRO:RC=1
Dec 04 05:02:27 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=17,pt=6,l=25,sg=0:<NONCE>
Dec 04 05:02:27 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:27 DEBUG SGN:NCE:FROM=123
Dec 04 05:02:27 DEBUG SGN:BND:NONCE=E38476176C1B713F454358240E2C6B5829BAD90CAFA6338EBDAAAAAAAAAAAAAA
Dec 04 05:02:27 DEBUG SGN:BND:HMAC=4FEBFCAC5421A7C4DEDD52420E613A7FB2A90BB1E241EF432339ADDE659E4DE3
Dec 04 05:02:27 DEBUG SGN:SGN:SGN
Dec 04 05:02:27 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=27,pt=1,l=1,sg=1,ft=0,st=OK:1
Dec 04 05:02:27 DEBUG TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:27 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:27 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:27 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:27 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:27 DEBUG TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=1:Hello 43943
Dec 04 05:02:27 DEBUG SGN:BND:NONCE=2FE7F6139888428C3569C3CBFE107F05BC536100175C1EAAD7AAAAAAAAAAAAAA
Dec 04 05:02:27 DEBUG SGN:BND:HMAC=B438045B3C7E2D079455F57A41E8E4B1843E1092ABA7C5FA7EF3F41EFC839553
Dec 04 05:02:27 DEBUG SGN:VER:OK
Dec 04 05:02:27 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/1/1/0/2,MSG SENT
Dec 04 05:02:29 DEBUG TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:29 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:29 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:29 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:29 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:29 DEBUG TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=1:Hello 46123
Dec 04 05:02:29 DEBUG SGN:BND:NONCE=E1F6BDB4CA7CE0D4F22C87B5048315DF07DE183E8C8D63EA75AAAAAAAAAAAAAA
Dec 04 05:02:29 DEBUG SGN:BND:HMAC=642100E6D0897056526EC99F89D789C0484DAAAD0AF2FD5060B66F3E8F32595A
Dec 04 05:02:29 DEBUG SGN:VER:OK
Dec 04 05:02:29 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/1/1/0/2,MSG SENT
Dec 04 05:02:31 DEBUG TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:31 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:31 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:31 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:31 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:31 DEBUG TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=1:Hello 48289
Dec 04 05:02:31 DEBUG SGN:BND:NONCE=412EE0D22B09C2FE6E249A6C5003C0B43EE85404CBFD8EF401AAAAAAAAAAAAAA
Dec 04 05:02:31 DEBUG SGN:BND:HMAC=B170CB1D43214203A4EDF0AFD7BB137C7D4104E715E1A2632E79E9768FE1CBDF
Dec 04 05:02:31 DEBUG SGN:VER:OK
Dec 04 05:02:31 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/1/1/0/2,MSG SENT
Dec 04 05:02:33 DEBUG TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:33 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:33 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:33 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:33 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:34 DEBUG TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=1:Hello 50462
Dec 04 05:02:34 DEBUG SGN:BND:NONCE=83B523911127AC20C1DDFAFFD9A497A55A2A6796B500696B67AAAAAAAAAAAAAA
Dec 04 05:02:34 DEBUG SGN:BND:HMAC=D9E09B4F4075AA96C5DAAF507A59D5C9654E704081CCFFFA954B09608DA1A2CA
Dec 04 05:02:34 DEBUG SGN:VER:OK
Dec 04 05:02:34 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/1/1/0/2,MSG SENT
Dec 04 05:02:36 DEBUG TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:36 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:36 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:36 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:36 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:36 DEBUG TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=1:Hello 52633
Dec 04 05:02:36 DEBUG SGN:BND:NONCE=E8082AB2925ACA106C9678E6996D6E73CF02A1774AC79B2832AAAAAAAAAAAAAA
Dec 04 05:02:36 DEBUG SGN:BND:HMAC=743DC22EDFC15ADB6050A2DD29E011D826F2F037F531A4D656837EB1DB2294F6
Dec 04 05:02:36 DEBUG SGN:VER:OK
Dec 04 05:02:36 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/1/1/0/2,MSG SENT
Dec 04 05:02:38 DEBUG TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:38 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:38 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:38 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:38 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:38 DEBUG TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=1:Hello 54804
Dec 04 05:02:38 DEBUG SGN:BND:NONCE=E7C42737D780B3319EB7819458767BFDE334A2D51F920DA0C6AAAAAAAAAAAAAA
Dec 04 05:02:38 DEBUG SGN:BND:HMAC=1C4DECD661E66CC435798821B45994D74791AEECD0672F5CF7D84614D5A889C0
Dec 04 05:02:38 DEBUG SGN:VER:OK
Dec 04 05:02:38 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/1/1/0/2,MSG SENT
Dec 04 05:02:40 DEBUG TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
Dec 04 05:02:40 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Dec 04 05:02:40 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Dec 04 05:02:40 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
Dec 04 05:02:40 DEBUG SGN:NCE:XMT,TO=0
Dec 04 05:02:40 DEBUG TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=1:Hello 56973
Dec 04 05:02:40 DEBUG SGN:BND:NONCE=9C249B35BC8ADDC86AC91ACDFE417520340D6E09DE5B21C965AAAAAAAAAAAAAA
Dec 04 05:02:40 DEBUG SGN:BND:HMAC=D213FB0411871CE08611BF608FF2A490403C2A4CDAB73D38589FC5E16CD59DA3
Dec 04 05:02:40 DEBUG SGN:VER:OK
Dec 04 05:02:40 DEBUG GWT:TPS:TOPIC=mys-nrf-out/123/1/1/0/2,MSG SENT
^CDec 04 05:02:44 NOTICE Received SIGINT

frits

@frits said in MQTT GW with RFM69 on RPi:

@Yveaux I have a raspi gw with debug signatures ready:

here's the logging on arduino hardware (both gateway and node on arduino pro micro + nrf24).

Summary:

MCO:PRO on gateway only
only after node boot
or message sent from gateway to node

gw boot:

0 MCO:BGN:INIT GW,CP=RNNGAA--,FQ=8,REL=255,VER=2.3.2
16 SGN:PER:OK
40 SGN:INI:BND OK
43 TSM:INIT
43 TSF:WUR:MS=0
51 TSM:INIT:TSP OK
53 TSM:INIT:GW MODE
55 TSM:READY:ID=0,PAR=0,DIS=0
57 MCO:REG:NOT NEEDED
622 GWT:TPC:IP=192.168.99.13
1626 MCO:BGN:STP
2127 MCO:BGN:INIT OK,TSP=1
2129 GWT:TPC:IP=192.168.99.13
3133 GWT:RMQ:CONNECTING...
3342 GWT:RMQ:OK
3344 GWT:TPS:TOPIC=mysgw1-out/0/255/0/0/18,MSG SENT
3352 TSM:READY:NWD REQ
3354 SGN:SGN:NREQ=255
3360 ?TSF:MSG:SEND,0-0-255-255,s=255,c=3,t=20,pt=0,l=0,sg=0,ft=0,st=OK:

node boot (gw log):

40278 TSF:MSG:READ,123-123-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
40284 TSF:MSG:BC
40286 TSF:MSG:FPAR REQ,ID=123
40290 TSF:PNG:SEND,TO=0
40292 TSF:CKU:OK
40294 TSF:MSG:GWL OK
40656 SGN:SKP:MSG CMD=3,TYPE=8
40660 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=OK:0
42303 TSF:MSG:READ,123-123-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
42311 SGN:SKP:MSG CMD=3,TYPE=24
42313 TSF:MSG:PINGED,ID=123,HP=1
42317 SGN:SKP:MSG CMD=3,TYPE=25
42323 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=OK:1
42350 TSF:MSG:READ,123-123-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0101
42356 SGN:SKP:MSG CMD=3,TYPE=15
42360 SGN:PRE:SGN REQ,FROM=123
42364 SGN:PRE:SGN REQ,TO=123
42366 SGN:PRE:WHI NREQ,TO=123
42371 SGN:SKP:MSG CMD=3,TYPE=15
42375 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0101
42383 SGN:PRE:XMT,TO=123
42393 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=0:
42399 SGN:SKP:MSG CMD=3,TYPE=16
42475 SGN:SKP:MSG CMD=3,TYPE=17
42481 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=0,ft=0,st=OK:<NONCE>
42491 SGN:NCE:XMT,TO=0
42653 TSF:MSG:READ,123-123-0,s=255,c=0,t=17,pt=0,l=5,sg=1:2.3.2
42659 SGN:BND:NONCE=5D05EE8DE2E5DB1DF14E37C1927A97E1415B5A171E0A64E5DFAAAAAAAAAAAAAA
42774 SGN:BND:HMAC=F03795009783A00B1CBA3AB0CABCCE11BA9F5F6050B407B3B50FAADE5B558925
42782 SGN:VER:OK
42786 GWT:TPS:TOPIC=mysgw1-out/123/255/0/0/17,MSG SENT
42792 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
42801 SGN:SKP:MSG CMD=3,TYPE=16
42878 SGN:SKP:MSG CMD=3,TYPE=17
42885 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
42893 SGN:NCE:XMT,TO=0
43055 TSF:MSG:READ,123-123-0,s=255,c=3,t=6,pt=1,l=1,sg=1:0
43061 SGN:BND:NONCE=6A5BC212192AAC2D25FDAFE2B626B8216A01D1FA44E1032D42AAAAAAAAAAAAAA
43180 SGN:BND:HMAC=FFF58B3C02F477E9ACCF36614E05769E8952C76692035368A8A41604F6459073
43188 SGN:VER:OK
43190 GWT:TPS:TOPIC=mysgw1-out/123/255/3/0/6,MSG SENT
45049 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
45056 SGN:SKP:MSG CMD=3,TYPE=16
45131 SGN:SKP:MSG CMD=3,TYPE=17
45137 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
45148 SGN:NCE:XMT,TO=0
45309 TSF:MSG:READ,123-123-0,s=255,c=3,t=11,pt=0,l=20,sg=1:Hello MySensors 328P
45318 SGN:BND:NONCE=736CF35C52747BC27A10F246D0E5162640F4843111755C3A1CAAAAAAAAAAAAAA
45432 SGN:BND:HMAC=2D5615BD24252A9F704CE90933F7B6ED89EB891ECCC0BF7EB6021CCFB3B6432D
45441 SGN:VER:OK
45445 GWT:TPS:TOPIC=mysgw1-out/123/255/3/0/11,MSG SENT
45453 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
45459 SGN:SKP:MSG CMD=3,TYPE=16
45537 SGN:SKP:MSG CMD=3,TYPE=17
45543 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
45551 SGN:NCE:XMT,TO=0
45713 TSF:MSG:READ,123-123-0,s=255,c=3,t=12,pt=0,l=3,sg=1:1.3
45721 SGN:BND:NONCE=7394AB63F58F7181E65175A747357A73B72DA775ECD44D795BAAAAAAAAAAAAAA
45836 SGN:BND:HMAC=7913D96974B91F551F698BBAB914AE79BC871569DF05977FF18A07836CFDDA2B
45844 SGN:VER:OK
45848 GWT:TPS:TOPIC=mysgw1-out/123/255/3/0/12,MSG SENT
45854 TSF:MSG:READ,123-123-0,s=0,c=3,t=16,pt=0,l=0,sg=1:
45860 SGN:SKP:MSG CMD=3,TYPE=16
45942 SGN:SKP:MSG CMD=3,TYPE=17
45948 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
45957 SGN:NCE:XMT,TO=0
46118 TSF:MSG:READ,123-123-0,s=0,c=0,t=36,pt=0,l=6,sg=1:system
46125 SGN:BND:NONCE=369A5D4D44C839C07EF2041459B7AF64B8322FC35570B09EE2AAAAAAAAAAAAAA
46243 SGN:BND:HMAC=2E022D2A34A359DF46021E5A7D197B92ABBA56B1B6DB1D9FC2661FFBA8B031EE
46252 SGN:VER:OK
46254 GWT:TPS:TOPIC=mysgw1-out/123/0/0/0/36,MSG SENT
46262 TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
46268 SGN:SKP:MSG CMD=3,TYPE=16
46344 SGN:SKP:MSG CMD=3,TYPE=17
46350 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
46360 SGN:NCE:XMT,TO=0
46522 TSF:MSG:READ,123-123-0,s=1,c=0,t=36,pt=0,l=9,sg=1:timestamp
46528 SGN:BND:NONCE=16E88FB58D621C0047CD38142BDCA4303719F0F8D670C6BEA5AAAAAAAAAAAAAA
46643 SGN:BND:HMAC=1AF56FBB3CF5C705B4C3E5210DE5CB912FF25F84F8152C51CA053A78ABEA63FC
46653 SGN:VER:OK
46655 GWT:TPS:TOPIC=mysgw1-out/123/1/0/0/36,MSG SENT
46663 TSF:MSG:READ,123-123-0,s=2,c=3,t=16,pt=0,l=0,sg=1:
46669 SGN:SKP:MSG CMD=3,TYPE=16
46747 SGN:SKP:MSG CMD=3,TYPE=17
46753 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
46761 SGN:NCE:XMT,TO=0
46923 TSF:MSG:READ,123-123-0,s=2,c=0,t=3,pt=0,l=3,sg=1:led
46931 SGN:BND:NONCE=F63BD3325089CAC4DA5F679EA9E334D19E07FB52DFC54C7901AAAAAAAAAAAAAA
47048 SGN:BND:HMAC=CAF49ED58A0F6D78FA8E2C99C24682D83558017E56F147619CFFD5F56C4CF40C
47056 SGN:VER:OK
47058 GWT:TPS:TOPIC=mysgw1-out/123/2/0/0/3,MSG SENT
47067 TSF:MSG:READ,123-123-0,s=3,c=3,t=16,pt=0,l=0,sg=1:
47073 SGN:SKP:MSG CMD=3,TYPE=16
47149 SGN:SKP:MSG CMD=3,TYPE=17
47155 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
47165 SGN:NCE:XMT,TO=0
47325 TSF:MSG:READ,123-123-0,s=3,c=0,t=3,pt=0,l=6,sg=1:button
47333 SGN:BND:NONCE=70E6B1C050BD5A8A2F03F3C345E801FE73237CF22F11196795AAAAAAAAAAAAAA
47448 SGN:BND:HMAC=F5202D42D87CA81DC0D0E032F54189868EEB4FE462598C6AD8B3307A4A043F4D
47456 SGN:VER:OK
47460 GWT:TPS:TOPIC=mysgw1-out/123/3/0/0/3,MSG SENT
47466 TSF:MSG:READ,123-123-0,s=255,c=3,t=26,pt=1,l=1,sg=1:2
47474 SGN:SKP:MSG CMD=3,TYPE=26
47476 SGN:SKP:MSG CMD=3,TYPE=16
47482 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=16,pt=0,l=0,sg=0,ft=0,st=OK:
47491 SGN:SGN:NCE REQ,TO=123
47493 !MCO:PRO:RC=1
47495 !MCO:PRO:RC=1
47499 !MCO:PRO:RC=1
47501 !MCO:PRO:RC=1
47503 !MCO:PRO:RC=1
47505 !MCO:PRO:RC=1
47509 !MCO:PRO:RC=1
47511 !MCO:PRO:RC=1
47513 !MCO:PRO:RC=1
47515 !MCO:PRO:RC=1
47519 !MCO:PRO:RC=1
47521 !MCO:PRO:RC=1
47523 !MCO:PRO:RC=1
47525 !MCO:PRO:RC=1
47529 !MCO:PRO:RC=1
47532 !MCO:PRO:RC=1
47534 !MCO:PRO:RC=1
47536 !MCO:PRO:RC=1
47540 !MCO:PRO:RC=1
47542 !MCO:PRO:RC=1
47544 !MCO:PRO:RC=1
47546 !MCO:PRO:RC=1
47548 !MCO:PRO:RC=1
47552 !MCO:PRO:RC=1
47554 !MCO:PRO:RC=1
47556 !MCO:PRO:RC=1
47558 !MCO:PRO:RC=1
47562 !MCO:PRO:RC=1
47564 !MCO:PRO:RC=1
47566 !MCO:PRO:RC=1
47568 !MCO:PRO:RC=1
47572 !MCO:PRO:RC=1
47575 !MCO:PRO:RC=1
47577 !MCO:PRO:RC=1
47579 !MCO:PRO:RC=1
47583 !MCO:PRO:RC=1
47585 !MCO:PRO:RC=1
47587 !MCO:PRO:RC=1
47589 !MCO:PRO:RC=1
47593 !MCO:PRO:RC=1
47595 !MCO:PRO:RC=1
47597 !MCO:PRO:RC=1
47599 !MCO:PRO:RC=1
47603 !MCO:PRO:RC=1
47605 !MCO:PRO:RC=1
47607 !MCO:PRO:RC=1
47609 !MCO:PRO:RC=1
47613 TSF:MSG:READ,123-123-0,s=255,c=3,t=17,pt=6,l=25,sg=0:<NONCE>
47620 SGN:SKP:MSG CMD=3,TYPE=17
47624 SGN:NCE:FROM=123
47626 SGN:BND:NONCE=52C791A2F3FD084BB05D52CBA6079339BCBC85FC48E952A91DAAAAAAAAAAAAAA
47747 SGN:BND:HMAC=3CCB39326B05220FEED535CC8304FE46A6544C69ED9C2D943725F8E38FA19C50
47755 SGN:SGN:SGN
47761 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=27,pt=1,l=1,sg=1,ft=0,st=OK:1
47935 TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
47943 SGN:SKP:MSG CMD=3,TYPE=16
48023 SGN:SKP:MSG CMD=3,TYPE=17
48029 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
48037 SGN:NCE:XMT,TO=0
48199 TSF:MSG:READ,123-123-0,s=1,c=1,t=2,pt=0,l=11,sg=1:Hello 10031
48207 SGN:BND:NONCE=399F6855334A59FF5A2B512738EF6B8C70BB2EE4A8FAD758E8AAAAAAAAAAAAAA
48322 SGN:BND:HMAC=6CCD4905231C9F55C61B23922DAC166A8B20610B150F491FAA11E1C4B9FBB7D4
48332 SGN:VER:OK
48334 GWT:TPS:TOPIC=mysgw1-out/123/1/1/0/2,MSG SENT

node send message (node log):

58513 SGN:SKP:MSG CMD=3,TYPE=16                                                 
58519 TSF:MSG:SEND,123-123-0-0,s=3,c=3,t=16,pt=0,l=0,sg=1,ft=0,st=OK:           
58525 SGN:SGN:NCE REQ,TO=0                                                      
58650 TSF:MSG:READ,0-0-123,s=255,c=3,t=17,pt=6,l=25,sg=1:<NONCE>                
58656 SGN:SKP:MSG CMD=3,TYPE=17                                                 
58660 SGN:NCE:FROM=0                                                            
58662 SGN:BND:NONCE=B06B498BB6AED7756B7C19D7D3E677464FE8E6D1823E37A818AAAAAAAAAA
AAAA                                                                            
58779 SGN:BND:HMAC=03123A4791D8BD0DB61B6C15604A847FA875D53790F9CAED906E57CF85C95
571                                                                             
58789 SGN:SGN:SGN                                                               
58793 TSF:MSG:SEND,123-123-0-0,s=3,c=1,t=2,pt=2,l=2,sg=1,ft=0,st=OK:1           
button pressed                                                                  
58814 SGN:SKP:MSG CMD=3,TYPE=16                                                 
58820 TSF:MSG:SEND,123-123-0-0,s=3,c=3,t=16,pt=0,l=0,sg=1,ft=0,st=OK:           
58826 SGN:SGN:NCE REQ,TO=0                                                      
59095 TSF:MSG:READ,0-0-123,s=255,c=3,t=17,pt=6,l=25,sg=1:<NONCE>                
59101 SGN:SKP:MSG CMD=3,TYPE=17                                                 
59105 SGN:NCE:FROM=0                                                            
59107 SGN:BND:NONCE=24228D2780F459C3469D1BDD01BAB9128D31C32B3FF806BA2DAAAAAAAAAA
AAAA                                                                            
59222 SGN:BND:HMAC=FF9C0AD707200F63C0A93BABF019B137C3F1FD54AD224291F83C16BFC6644
E8C                                                                             
59232 SGN:SGN:SGN                                                               
59236 TSF:MSG:SEND,123-123-0-0,s=3,c=1,t=2,pt=2,l=2,sg=1,ft=0,st=OK:0           
button released

node send message (gw log):

96430 TSF:MSG:READ,123-123-0,s=3,c=3,t=16,pt=0,l=0,sg=1:
96436 SGN:SKP:MSG CMD=3,TYPE=16
96512 SGN:SKP:MSG CMD=3,TYPE=17
96518 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
96528 SGN:NCE:XMT,TO=0
96690 TSF:MSG:READ,123-123-0,s=3,c=1,t=2,pt=2,l=2,sg=1:1
96696 SGN:BND:NONCE=B06B498BB6AED7756B7C19D7D3E677464FE8E6D1823E37A818AAAAAAAAAAAAAA
96811 SGN:BND:HMAC=03123A4791D8BD0DB61B6C15604A847FA875D53790F9CAED906E57CF85C95571
96821 SGN:VER:OK
96823 GWT:TPS:TOPIC=mysgw1-out/123/3/1/0/2,MSG SENT
96829 TSF:MSG:READ,123-123-0,s=3,c=3,t=16,pt=0,l=0,sg=1:
96837 SGN:SKP:MSG CMD=3,TYPE=16
96917 SGN:SKP:MSG CMD=3,TYPE=17
96923 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
96931 SGN:NCE:XMT,TO=0
97093 TSF:MSG:READ,123-123-0,s=3,c=1,t=2,pt=2,l=2,sg=1:0
97101 SGN:BND:NONCE=24228D2780F459C3469D1BDD01BAB9128D31C32B3FF806BA2DAAAAAAAAAAAAAA
97216 SGN:BND:HMAC=FF9C0AD707200F63C0A93BABF019B137C3F1FD54AD224291F83C16BFC6644E8C
97224 SGN:VER:OK
97228 GWT:TPS:TOPIC=mysgw1-out/123/3/1/0/2,MSG SENT

gw send message (gw log):

206145 GWT:IMQ:TOPIC=mysgw1-in/123/2/1/0/2, MSG RECEIVED
206151 SGN:SKP:MSG CMD=3,TYPE=16
206157 TSF:MSG:SEND,0-0-123-123,s=2,c=3,t=16,pt=0,l=0,sg=1,ft=0,st=OK:
206166 SGN:SGN:NCE REQ,TO=123
206168 !MCO:PRO:RC=1
206172 !MCO:PRO:RC=1
206174 !MCO:PRO:RC=1
206176 !MCO:PRO:RC=1
206180 !MCO:PRO:RC=1
206182 !MCO:PRO:RC=1
206184 !MCO:PRO:RC=1
206186 !MCO:PRO:RC=1
206190 !MCO:PRO:RC=1
206192 !MCO:PRO:RC=1
206194 !MCO:PRO:RC=1
206198 !MCO:PRO:RC=1
206200 !MCO:PRO:RC=1
206202 !MCO:PRO:RC=1
206206 !MCO:PRO:RC=1
206209 !MCO:PRO:RC=1
206211 !MCO:PRO:RC=1
206215 !MCO:PRO:RC=1
206217 !MCO:PRO:RC=1
206219 !MCO:PRO:RC=1
206223 !MCO:PRO:RC=1
206225 !MCO:PRO:RC=1
206227 !MCO:PRO:RC=1
206231 !MCO:PRO:RC=1
206233 !MCO:PRO:RC=1
206235 !MCO:PRO:RC=1
206239 !MCO:PRO:RC=1
206241 !MCO:PRO:RC=1
206243 !MCO:PRO:RC=1
206247 !MCO:PRO:RC=1
206249 !MCO:PRO:RC=1
206252 !MCO:PRO:RC=1
206256 !MCO:PRO:RC=1
206258 !MCO:PRO:RC=1
206260 !MCO:PRO:RC=1
206262 !MCO:PRO:RC=1
206266 !MCO:PRO:RC=1
206268 !MCO:PRO:RC=1
206272 TSF:MSG:READ,123-123-0,s=255,c=3,t=17,pt=6,l=25,sg=1:<NONCE>
206278 SGN:SKP:MSG CMD=3,TYPE=17
206282 SGN:NCE:FROM=123
206284 SGN:BND:NONCE=40E78847070231061F13FBD56014E185E1FC7651EDC52ABFD3AAAAAAAAAAAAAA
206399 SGN:BND:HMAC=4A48E4CF691C24205050B2ECB0C4C9B5F0CB4A67D9E9A43A5FFA03A8F7288F1A
206409 SGN:SGN:SGN
206415 TSF:MSG:SEND,0-0-123-123,s=2,c=1,t=2,pt=0,l=1,sg=1,ft=0,st=OK:1

gw send message (node log):

168019 !SGN:BND:TMR                                                             
168024 TSF:MSG:READ,0-0-123,s=2,c=3,t=16,pt=0,l=0,sg=1:                         
168030 SGN:SKP:MSG CMD=3,TYPE=16                                                
168085 SGN:SKP:MSG CMD=3,TYPE=17                                                
168091 TSF:MSG:SEND,123-123-0-0,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=1,st=OK:<NONCE>
168101 SGN:NCE:XMT,TO=123                                                       
168263 TSF:MSG:READ,0-0-123,s=2,c=1,t=2,pt=0,l=1,sg=1:1                         
168269 SGN:BND:NONCE=40E78847070231061F13FBD56014E185E1FC7651EDC52ABFD3AAAAAAAAA
AAAAA                                                                           
168384 SGN:BND:HMAC=4A48E4CF691C24205050B2ECB0C4C9B5F0CB4A67D9E9A43A5FFA03A8F728
8F1A                                                                            
168394 SGN:VER:OK                                                               
                                                                                
received message for sensor 2, type 2, value 1                                  
switching LED on

TimO

I've disabled the signing feature and the !MCO:PRO:RC=1 issn't occuring anymore.

joaoabs

Also happening in nodes:

 __  __       ____
|  \/  |_   _/ ___|  ___ _ __  ___  ___  _ __ ___
| |\/| | | | \___ \ / _ \ `_ \/ __|/ _ \| `__/ __|
| |  | | |_| |___| |  __/ | | \__ \  _  | |  \__ \
|_|  |_|\__, |____/ \___|_| |_|___/\___/|_|  |___/
        |___/                      2.3.2

16 MCO:BGN:INIT NODE,CP=RPNNAA-X,FQ=8,REL=255,VER=2.3.2
40 SGN:PER:OK
69 SGN:INI:BND OK
71 TSM:INIT
71 TSF:WUR:MS=0
75 TSM:INIT:TSP OK
77 TSM:INIT:STATID=33
79 TSF:SID:OK,ID=33
81 TSM:FPAR
83 SGN:SGN:NREQ=255
90 ?TSF:MSG:SEND,33-33-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK:
212 TSF:MSG:READ,0-0-33,s=255,c=3,t=8,pt=1,l=1,sg=1:0
219 SGN:SKP:MSG CMD=3,TYPE=8
221 TSF:MSG:FPAR OK,ID=0,D=1
2099 TSM:FPAR:OK
2099 TSM:ID
2101 TSM:ID:OK
2103 TSM:UPL
2105 SGN:SKP:MSG CMD=3,TYPE=24
3133 !TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=NACK:1
3811 TSF:MSG:READ,0-0-33,s=255,c=3,t=25,pt=1,l=1,sg=1:1
3817 SGN:SKP:MSG CMD=3,TYPE=25
3821 TSF:MSG:PONG RECV,HP=1
3823 TSM:UPL:OK
3825 TSM:READY:ID=33,PAR=0,DIS=1
3829 SGN:PRE:SGN REQ
3831 SGN:PRE:WHI NREQ
3833 SGN:SKP:MSG CMD=3,TYPE=15
3872 TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0101
3880 SGN:PRE:XMT,TO=0
3883 SGN:PRE:WAIT GW
4837 TSF:MSG:READ,0-0-33,s=255,c=3,t=15,pt=6,l=2,sg=0:0101
4843 SGN:SKP:MSG CMD=3,TYPE=15
4847 SGN:PRE:SGN REQ,FROM=0
4851 SGN:SKP:MSG CMD=3,TYPE=16
4925 TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=16,pt=0,l=0,sg=0,ft=0,st=OK:
4933 SGN:SGN:NCE REQ,TO=0
6141 TSF:MSG:READ,0-0-33,s=255,c=3,t=17,pt=6,l=25,sg=0:<NONCE>
6148 SGN:SKP:MSG CMD=3,TYPE=17
6152 SGN:NCE:FROM=0
6154 SGN:BND:NONCE=F1D60B20DB00FF5950A64E8FFA7DEDE90956E7505DA37EF4DBAAAAAAAAAAAAAA
6273 SGN:BND:HMAC=FC296959CE1E51BF6EB60BD781CF1C003FA4E32AF2160D0F813160CCBF1B7095
6283 SGN:SGN:SGN
6404 TSF:MSG:SEND,33-33-0-0,s=255,c=0,t=17,pt=0,l=5,sg=1,ft=0,st=OK:2.3.2
6412 SGN:SKP:MSG CMD=3,TYPE=16
7460 !TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=16,pt=0,l=0,sg=1,ft=0,st=NACK:
7467 !SGN:SGN:NCE REQ,TO=0 FAIL
7471 !TSF:MSG:SIGN FAIL
8058 TSF:MSG:READ,0-0-33,s=255,c=3,t=17,pt=6,l=25,sg=1:<NONCE>
8065 SGN:SKP:MSG CMD=3,TYPE=17
8069 SGN:NCE:FROM=0
8071 SGN:BND:NONCE=DE60142AA1B91F73CB35274D2B050BB8B51D4578E9F32D75CBAAAAAAAAAAAAAA
8189 SGN:BND:HMAC=AC6BCA42658012BFD9A118AC2F347756D6EB2B3BB5EDB9D6CEE5E5271A136ACB
8513 TSF:MSG:READ,0-0-33,s=255,c=3,t=17,pt=6,l=25,sg=1:<NONCE>
8519 SGN:SKP:MSG CMD=3,TYPE=17
8523 SGN:NCE:FROM=0
8525 SGN:BND:NONCE=DE60142AA1B91F73CB35274D2B050BB8B51D4578E9F32D75CBAAAAAAAAAAAAAA
8644 SGN:BND:HMAC=AC6BCA42658012BFD9A118AC2F347756D6EB2B3BB5EDB9D6CEE5E5271A136ACB
9474 SGN:SKP:MSG CMD=3,TYPE=16
10524 !TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=16,pt=0,l=0,sg=1,ft=2,st=NACK:
10530 !SGN:SGN:NCE REQ,TO=0 FAIL
10534 !TSF:MSG:SIGN FAIL
10536 SGN:SKP:MSG CMD=3,TYPE=16
11587 !TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=16,pt=0,l=0,sg=1,ft=4,st=NACK:
11593 !SGN:SGN:NCE REQ,TO=0 FAIL
11597 !TSF:MSG:SIGN FAIL
11599 SGN:SKP:MSG CMD=3,TYPE=16
12650 !TSF:MSG:SEND,33-33-0-0,s=1,c=3,t=16,pt=0,l=0,sg=1,ft=6,st=NACK:
12656 !SGN:SGN:NCE REQ,TO=0 FAIL
12660 !TSF:MSG:SIGN FAIL
12662 SGN:SKP:MSG CMD=3,TYPE=16
13713 !TSF:MSG:SEND,33-33-0-0,s=2,c=3,t=16,pt=0,l=0,sg=1,ft=8,st=NACK:
13719 !SGN:SGN:NCE REQ,TO=0 FAIL
13723 !TSF:MSG:SIGN FAIL
13725 MCO:REG:REQ
13727 SGN:SKP:MSG CMD=3,TYPE=26
14467 !TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=26,pt=1,l=1,sg=1,ft=10,st=NACK:2
14475 !TSM:READY:UPL FAIL,SNP
14477 TSM:FPAR
14479 SGN:SGN:NREQ=255
14487 ?TSF:MSG:SEND,33-33-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=11,st=OK:
15235 TSF:MSG:READ,0-0-33,s=255,c=3,t=16,pt=0,l=0,sg=1:
15241 SGN:SKP:MSG CMD=3,TYPE=16
15321 !TSF:SND:TNR
15323 !SGN:NCE:XMT,TO=33 FAIL
16476 !TSF:SND:TNR
16496 !TSM:FPAR:NO REPLY
16498 TSM:FPAR
16500 SGN:SGN:NREQ=255
16508 ?TSF:MSG:SEND,33-33-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK:
16805 TSF:MSG:READ,0-0-33,s=255,c=3,t=8,pt=1,l=1,sg=1:0
16812 SGN:SKP:MSG CMD=3,TYPE=8
16816 TSF:MSG:FPAR OK,ID=0,D=1
18479 !TSF:SND:TNR
18518 TSM:FPAR:OK
18520 TSM:ID
18520 TSM:ID:OK
18522 TSM:UPL
18524 SGN:SKP:MSG CMD=3,TYPE=24
19552 !TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=NACK:1
20289 TSF:MSG:READ,0-0-33,s=255,c=3,t=25,pt=1,l=1,sg=1:1
20295 SGN:SKP:MSG CMD=3,TYPE=25
20297 TSF:MSG:PONG RECV,HP=1
20301 TSM:UPL:OK
20303 TSM:READY:ID=33,PAR=0,DIS=1
20307 SGN:PRE:SGN REQ
20310 SGN:PRE:WHI NREQ
20312 SGN:SKP:MSG CMD=3,TYPE=15
20416 TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0101
20424 SGN:PRE:XMT,TO=0
20426 SGN:PRE:WAIT GW
20430 !MCO:WAI:RC=1
20432 !MCO:PRO:RC=1
20434 !MCO:PRO:RC=1
20436 !MCO:PRO:RC=1
20439 !MCO:PRO:RC=1
20441 !MCO:PRO:RC=1
20443 !MCO:PRO:RC=1
20445 !MCO:PRO:RC=1
20447 !MCO:PRO:RC=1
20449 !MCO:PRO:RC=1
20451 !MCO:PRO:RC=1
20455 !MCO:PRO:RC=1
20457 !MCO:PRO:RC=1
20459 !MCO:PRO:RC=1
20461 !MCO:PRO:RC=1
20463 !MCO:PRO:RC=1
20465 !MCO:PRO:RC=1
20467 !MCO:PRO:RC=1
20469 !MCO:PRO:RC=1
20471 !MCO:PRO:RC=1
20473 !MCO:PRO:RC=1
20477 !MCO:PRO:RC=1
20480 !MCO:PRO:RC=1
20482 !MCO:PRO:RC=1
20484 !MCO:PRO:RC=1
20486 !MCO:PRO:RC=1
20488 !MCO:PRO:RC=1
20490 !MCO:PRO:RC=1
20492 !MCO:PRO:RC=1
20494 !MCO:PRO:RC=1
20496 !MCO:PRO:RC=1
20498 !MCO:PRO:RC=1
20502 !MCO:PRO:RC=1
20504 !MCO:PRO:RC=1
20506 !MCO:PRO:RC=1
20508 !MCO:PRO:RC=1
20510 !MCO:PRO:RC=1
20512 !MCO:PRO:RC=1
20514 !MCO:PRO:RC=1
20516 !MCO:PRO:RC=1
20518 !MCO:PRO:RC=1
20520 !MCO:PRO:RC=1
20523 !MCO:PRO:RC=1
20527 !MCO:PRO:RC=1
20529 !MCO:PRO:RC=1
20531 !MCO:PRO:RC=1
20533 !MCO:PRO:RC=1
20535 !MCO:PRO:RC=1
20537 !MCO:PRO:RC=1
20539 !MCO:PRO:RC=1
20541 !MCO:PRO:RC=1
20543 !MCO:PRO:RC=1
20545 !MCO:PRO:RC=1
20549 !MCO:PRO:RC=1
20551 !MCO:PRO:RC=1
20553 !MCO:PRO:RC=1
20555 !MCO:PRO:RC=1
20557 !MCO:PRO:RC=1
20559 !MCO:PRO:RC=1
20561 !MCO:PRO:RC=1
20563 !MCO:PRO:RC=1
20566 !MCO:PRO:RC=1
20568 !MCO:PRO:RC=1
20570 !MCO:PRO:RC=1
20574 !MCO:PRO:RC=1
20576 !MCO:PRO:RC=1
20578 !MCO:PRO:RC=1
20580 !MCO:PRO:RC=1
20582 !MCO:PRO:RC=1
20584 !MCO:PRO:RC=1
20586 !MCO:PRO:RC=1
20588 !MCO:PRO:RC=1
20590 !MCO:PRO:RC=1
20592 !MCO:PRO:RC=1
20594 !MCO:PRO:RC=1
20598 !MCO:PRO:RC=1
20600 !MCO:PRO:RC=1
20602 !MCO:PRO:RC=1
20604 !MCO:PRO:RC=1
20606 !MCO:PRO:RC=1
20609 !MCO:PRO:RC=1
20611 !MCO:PRO:RC=1
20613 !MCO:PRO:RC=1
20615 !MCO:PRO:RC=1
20617 !MCO:PRO:RC=1
20621 !MCO:PRO:RC=1
20623 !MCO:PRO:RC=1
20625 !MCO:PRO:RC=1
20627 !MCO:PRO:RC=1
20629 !MCO:PRO:RC=1
20631 !MCO:PRO:RC=1
20633 !MCO:PRO:RC=1
20635 !MCO:PRO:RC=1
20637 !MCO:PRO:RC=1
20639 !MCO:PRO:RC=1
20641 !MCO:PRO:RC=1
20645 !MCO:PRO:RC=1
20647 !MCO:PRO:RC=1
20649 !MCO:PRO:RC=1
20652 !MCO:PRO:RC=1
20654 !MCO:PRO:RC=1
20656 !MCO:PRO:RC=1
20658 !MCO:PRO:RC=1
20660 !MCO:PRO:RC=1
20662 !MCO:PRO:RC=1
20664 !MCO:PRO:RC=1
20666 !MCO:PRO:RC=1
20670 !MCO:PRO:RC=1
20672 !MCO:PRO:RC=1
20674 !MCO:PRO:RC=1
20676 !MCO:PRO:RC=1
20678 !MCO:PRO:RC=1
20680 !MCO:PRO:RC=1
20682 !MCO:PRO:RC=1
20684 !MCO:PRO:RC=1
20686 !MCO:PRO:RC=1
20688 !MCO:PRO:RC=1
20692 !MCO:PRO:RC=1
20695 !MCO:PRO:RC=1
20697 !MCO:PRO:RC=1
20699 !MCO:PRO:RC=1
20701 !MCO:PRO:RC=1
20703 !MCO:PRO:RC=1
20705 !MCO:PRO:RC=1
20707 !MCO:PRO:RC=1
20709 !MCO:PRO:RC=1
20711 !MCO:PRO:RC=1
20713 !MCO:PRO:RC=1
20717 !MCO:PRO:RC=1
20719 !MCO:PRO:RC=1
20721 !MCO:PRO:RC=1
20723 !MCO:PRO:RC=1
20725 !MCO:PRO:RC=1
20727 !MCO:PRO:RC=1
20729 !MCO:PRO:RC=1
20731 !MCO:PRO:RC=1
20733 !MCO:PRO:RC=1
20736 !MCO:PRO:RC=1
20738 !MCO:PRO:RC=1
20742 !MCO:PRO:RC=1
20744 !MCO:PRO:RC=1
20746 !MCO:PRO:RC=1
20748 !MCO:PRO:RC=1
20750 !MCO:PRO:RC=1
20752 !MCO:PRO:RC=1
20754 !MCO:PRO:RC=1
20756 !MCO:PRO:RC=1
20758 !MCO:PRO:RC=1
20760 !MCO:PRO:RC=1
20764 !MCO:PRO:RC=1
20766 !MCO:PRO:RC=1
20768 !MCO:PRO:RC=1
20770 !MCO:PRO:RC=1
20772 !MCO:PRO:RC=1
20774 !MCO:PRO:RC=1
20776 !MCO:PRO:RC=1
20779 !MCO:PRO:RC=1
20781 !MCO:PRO:RC=1
20783 !MCO:PRO:RC=1
20785 !MCO:PRO:RC=1
20789 !MCO:PRO:RC=1
20791 !MCO:PRO:RC=1
20793 !MCO:PRO:RC=1
20795 !MCO:PRO:RC=1
20797 !MCO:PRO:RC=1
20799 !MCO:PRO:RC=1
20801 !MCO:PRO:RC=1
20803 !MCO:PRO:RC=1
20805 !MCO:PRO:RC=1
20807 !MCO:PRO:RC=1
20809 !MCO:PRO:RC=1
20813 !MCO:PRO:RC=1
20815 !MCO:PRO:RC=1
20817 !MCO:PRO:RC=1
20819 !MCO:PRO:RC=1
20822 !MCO:PRO:RC=1
20824 !MCO:PRO:RC=1
20826 !MCO:PRO:RC=1
20828 !MCO:PRO:RC=1
20830 !MCO:PRO:RC=1
20832 !MCO:PRO:RC=1
20836 !MCO:PRO:RC=1
20838 !MCO:PRO:RC=1
20840 !MCO:PRO:RC=1
20842 !MCO:PRO:RC=1
20844 !MCO:PRO:RC=1
20846 !MCO:PRO:RC=1
20848 !MCO:PRO:RC=1
20850 !MCO:PRO:RC=1
20852 !MCO:PRO:RC=1
20854 !MCO:PRO:RC=1
20856 !MCO:PRO:RC=1
20860 !MCO:PRO:RC=1
20862 !MCO:PRO:RC=1
20865 !MCO:PRO:RC=1
20867 !MCO:PRO:RC=1
20869 !MCO:PRO:RC=1
20871 !MCO:PRO:RC=1
20873 !MCO:PRO:RC=1
20875 !MCO:PRO:RC=1
20877 !MCO:PRO:RC=1
20879 !MCO:PRO:RC=1
20881 !MCO:PRO:RC=1
20885 !MCO:PRO:RC=1
20887 !MCO:PRO:RC=1
20889 !MCO:PRO:RC=1
20891 !MCO:PRO:RC=1
20893 !MCO:PRO:RC=1
20895 !MCO:PRO:RC=1
20897 !MCO:PRO:RC=1
20899 !MCO:PRO:RC=1
20901 !MCO:PRO:RC=1
20903 !MCO:PRO:RC=1
20908 !MCO:PRO:RC=1
20910 !MCO:PRO:RC=1
20912 !MCO:PRO:RC=1
20914 !MCO:PRO:RC=1
20916 !MCO:PRO:RC=1
20918 !MCO:PRO:RC=1
20920 !MCO:PRO:RC=1
20922 !MCO:PRO:RC=1
20924 !MCO:PRO:RC=1
20926 !MCO:PRO:RC=1
20928 !MCO:PRO:RC=1
20932 !MCO:PRO:RC=1
20934 !MCO:PRO:RC=1
20936 !MCO:PRO:RC=1
20938 !MCO:PRO:RC=1
20940 !MCO:PRO:RC=1
20942 !MCO:PRO:RC=1
20944 !MCO:PRO:RC=1
20946 !MCO:PRO:RC=1
20948 !MCO:PRO:RC=1
20951 !MCO:PRO:RC=1
20953 !MCO:PRO:RC=1
20957 !MCO:PRO:RC=1
20959 !MCO:PRO:RC=1
20961 !MCO:PRO:RC=1
20963 !MCO:PRO:RC=1
20965 !MCO:PRO:RC=1
20967 !MCO:PRO:RC=1
20969 !MCO:PRO:RC=1
20971 !MCO:PRO:RC=1
20973 !MCO:PRO:RC=1
20975 !MCO:PRO:RC=1
20979 !MCO:PRO:RC=1
20981 !MCO:PRO:RC=1
20983 !MCO:PRO:RC=1
20985 !MCO:PRO:RC=1
20987 !MCO:PRO:RC=1
20989 !MCO:PRO:RC=1
20992 !MCO:PRO:RC=1
20994 !MCO:PRO:RC=1
20996 !MCO:PRO:RC=1
20998 !MCO:PRO:RC=1
21000 !MCO:PRO:RC=1
21004 !MCO:PRO:RC=1
21006 !MCO:PRO:RC=1
21008 !MCO:PRO:RC=1
21010 !MCO:PRO:RC=1
21012 !MCO:PRO:RC=1
21014 !MCO:PRO:RC=1
21016 !MCO:PRO:RC=1
21018 !MCO:PRO:RC=1
21020 !MCO:PRO:RC=1
21022 !MCO:PRO:RC=1
21024 !MCO:PRO:RC=1
21028 !MCO:PRO:RC=1
21030 !MCO:PRO:RC=1
21032 !MCO:PRO:RC=1
21035 !MCO:PRO:RC=1
21037 !MCO:PRO:RC=1
21039 !MCO:PRO:RC=1
21041 !MCO:PRO:RC=1
21043 !MCO:PRO:RC=1
21045 !MCO:PRO:RC=1
21047 !MCO:PRO:RC=1
21051 !MCO:PRO:RC=1
21053 !MCO:PRO:RC=1
21055 !MCO:PRO:RC=1
21057 !MCO:PRO:RC=1
21059 !MCO:PRO:RC=1
21061 !MCO:PRO:RC=1
21063 !MCO:PRO:RC=1
21065 !MCO:PRO:RC=1
21067 !MCO:PRO:RC=1
21069 !MCO:PRO:RC=1
21071 !MCO:PRO:RC=1
21075 !MCO:PRO:RC=1
21078 !MCO:PRO:RC=1
21080 !MCO:PRO:RC=1
21082 !MCO:PRO:RC=1
21084 !MCO:PRO:RC=1
21086 !MCO:PRO:RC=1
21088 !MCO:PRO:RC=1
21090 !MCO:PRO:RC=1
21092 !MCO:PRO:RC=1
21094 !MCO:PRO:RC=1
21096 !MCO:PRO:RC=1
21100 !MCO:PRO:RC=1
21102 !MCO:PRO:RC=1
21104 !MCO:PRO:RC=1
21106 !MCO:PRO:RC=1
21108 !MCO:PRO:RC=1
21110 !MCO:PRO:RC=1
21112 !MCO:PRO:RC=1
21114 !MCO:PRO:RC=1
21116 !MCO:PRO:RC=1
21118 !MCO:PRO:RC=1
21123 !MCO:PRO:RC=1
21125 !MCO:PRO:RC=1
21127 !MCO:PRO:RC=1
21129 !MCO:PRO:RC=1
21131 !MCO:PRO:RC=1
21133 !MCO:PRO:RC=1
21135 !MCO:PRO:RC=1
21137 !MCO:PRO:RC=1
21139 !MCO:PRO:RC=1
21141 !MCO:PRO:RC=1
21143 !MCO:PRO:RC=1
21147 !MCO:PRO:RC=1
21149 !MCO:PRO:RC=1
21151 !MCO:PRO:RC=1
21153 !SGN:BND:TMR
21161 TSF:MSG:READ,0-0-33,s=255,c=3,t=15,pt=6,l=2,sg=0:0101
21168 SGN:SKP:MSG CMD=3,TYPE=15
21170 SGN:PRE:SGN REQ,FROM=0
21174 SGN:SKP:MSG CMD=3,TYPE=16
22130 !TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=16,pt=0,l=0,sg=1,ft=0,st=NACK:
22136 !SGN:SGN:NCE REQ,TO=0 FAIL
22140 !TSF:MSG:SIGN FAIL
22142 SGN:SKP:MSG CMD=3,TYPE=16
23193 !TSF:MSG:SEND,33-33-0-0,s=255,c=3,t=16,pt=0,l=0,sg=1,ft=2,st=NACK:
23199 !SGN:SGN:NCE REQ,TO=0 FAIL
23203 !TSF:MSG:SIGN FAIL
23205 !MCO:WAI:RC=1
23207 !MCO:PRO:RC=1
23212 !MCO:PRO:RC=1
23214 !MCO:PRO:RC=1
23216 !MCO:PRO:RC=1
23218 !MCO:PRO:RC=1
23220 !MCO:PRO:RC=1
23222 !MCO:PRO:RC=1
23224 !MCO:PRO:RC=1
23226 !MCO:PRO:RC=1
23228 !MCO:PRO:RC=1
23230 !MCO:PRO:RC=1
23234 !MCO:PRO:RC=1
23236 !MCO:PRO:RC=1
23238 !MCO:PRO:RC=1
23240 !MCO:PRO:RC=1
23242 !MCO:PRO:RC=1
23244 !MCO:PRO:RC=1
23246 !MCO:PRO:RC=1
23248 !MCO:PRO:RC=1
23250 !MCO:PRO:RC=1
23252 !MCO:PRO:RC=1
23255 !MCO:PRO:RC=1
23259 !MCO:PRO:RC=1
23261 !MCO:PRO:RC=1
23263 !MCO:PRO:RC=1
23265 !MCO:PRO:RC=1
23267 !MCO:PRO:RC=1
23269 !MCO:PRO:RC=1
23271 !MCO:PRO:RC=1
23273 !MCO:PRO:RC=1
23275 !MCO:PRO:RC=1
23277 !MCO:PRO:RC=1
23279 !MCO:PRO:RC=1
23283 !MCO:PRO:RC=1
23285 !MCO:PRO:RC=1
23287 !MCO:PRO:RC=1
23289 !MCO:PRO:RC=1
23291 !MCO:PRO:RC=1
23293 !MCO:PRO:RC=1
23296 !MCO:PRO:RC=1
23298 !MCO:PRO:RC=1
23300 !MCO:PRO:RC=1
23302 !MCO:PRO:RC=1
23306 !MCO:PRO:RC=1
23308 !MCO:PRO:RC=1
23310 !MCO:PRO:RC=1
23312 !MCO:PRO:RC=1
23314 !MCO:PRO:RC=1
23316 !MCO:PRO:RC=1
23318 !MCO:PRO:RC=1
23320 !MCO:PRO:RC=1
23322 !MCO:PRO:RC=1
23324 !MCO:PRO:RC=1
23326 !MCO:PRO:RC=1
23330 !MCO:PRO:RC=1
23332 !MCO:PRO:RC=1
23334 !MCO:PRO:RC=1
23336 !MCO:PRO:RC=1
23339 !MCO:PRO:RC=1
23341 !MCO:PRO:RC=1
23343 !MCO:PRO:RC=1
23345 !MCO:PRO:RC=1
23347 !MCO:PRO:RC=1
23349 !MCO:PRO:RC=1
23353 !MCO:PRO:RC=1
23355 !MCO:PRO:RC=1
23357 !MCO:PRO:RC=1
23359 !MCO:PRO:RC=1
23361 !MCO:PRO:RC=1
23363 !MCO:PRO:RC=1
23365 !MCO:PRO:RC=1
23367 !MCO:PRO:RC=1
23369 !MCO:PRO:RC=1
23371 !MCO:PRO:RC=1
23373 !MCO:PRO:RC=1
23377 !MCO:PRO:RC=1
23379 !MCO:PRO:RC=1
23382 !MCO:PRO:RC=1
23384 !MCO:PRO:RC=1
23386 !MCO:PRO:RC=1
23388 !MCO:PRO:RC=1
23390 !MCO:PRO:RC=1
23392 !MCO:PRO:RC=1
23394 !MCO:PRO:RC=1
23396 !MCO:PRO:RC=1
23398 !MCO:PRO:RC=1
23402 !MCO:PRO:RC=1
23404 !MCO:PRO:RC=1
23406 !MCO:PRO:RC=1
23408 !MCO:PRO:RC=1
23410 !MCO:PRO:RC=1
23412 !MCO:PRO:RC=1
23414 !MCO:PRO:RC=1
23416 !MCO:PRO:RC=1
23418 !MCO:PRO:RC=1
23420 !MCO:PRO:RC=1
23425 !MCO:PRO:RC=1
23427 !MCO:PRO:RC=1
23429 !MCO:PRO:RC=1
23431 !MCO:PRO:RC=1
23433 !MCO:PRO:RC=1
23435 !MCO:PRO:RC=1
23437 !MCO:PRO:RC=1
23439 !MCO:PRO:RC=1
23441 !MCO:PRO:RC=1
23443 !MCO:PRO:RC=1
23445 !MCO:PRO:RC=1
23449 !MCO:PRO:RC=1
23451 !MCO:PRO:RC=1
23453 !MCO:PRO:RC=1
23455 !MCO:PRO:RC=1
23457 !MCO:PRO:RC=1
23459 !MCO:PRO:RC=1
23461 !MCO:PRO:RC=1
23463 !MCO:PRO:RC=1
23465 !MCO:PRO:RC=1
23468 !MCO:PRO:RC=1
23470 !MCO:PRO:RC=1
23474 !MCO:PRO:RC=1
23476 !MCO:PRO:RC=1
23478 !MCO:PRO:RC=1
23480 !MCO:PRO:RC=1
23482 !MCO:PRO:RC=1
23484 !MCO:PRO:RC=1
23486 !MCO:PRO:RC=1
23488 !MCO:PRO:RC=1
23490 !MCO:PRO:RC=1
23492 !MCO:PRO:RC=1
23496 !MCO:PRO:RC=1
23498 !MCO:PRO:RC=1
23500 !MCO:PRO:RC=1
23502 !MCO:PRO:RC=1
23504 !MCO:PRO:RC=1
23506 !MCO:PRO:RC=1
23508 !MCO:PRO:RC=1
23511 !MCO:PRO:RC=1
23513 !MCO:PRO:RC=1
23517 !MCO:PRO:RC=1
23519 !MCO:PRO:RC=1
23521 !MCO:PRO:RC=1
23523 !MCO:PRO:RC=1
23525 !MCO:PRO:RC=1
23527 !MCO:PRO:RC=1
23529 !MCO:PRO:RC=1
23531 !MCO:PRO:RC=1
23533 !MCO:PRO:RC=1
23535 !MCO:PRO:RC=1
23537 !MCO:PRO:RC=1
23541 !MCO:PRO:RC=1
23543 !MCO:PRO:RC=1
23545 !MCO:PRO:RC=1
23547 !MCO:PRO:RC=1
23549 !MCO:PRO:RC=1
23552 !MCO:PRO:RC=1
23554 !MCO:PRO:RC=1
23556 !MCO:PRO:RC=1
23558 !MCO:PRO:RC=1
23560 !MCO:PRO:RC=1
23564 !MCO:PRO:RC=1
23566 !MCO:PRO:RC=1
23568 !MCO:PRO:RC=1
23570 !MCO:PRO:RC=1
23572 !MCO:PRO:RC=1
23574 !MCO:PRO:RC=1
23576 !MCO:PRO:RC=1
23578 !MCO:PRO:RC=1
23580 !MCO:PRO:RC=1
23582 !MCO:PRO:RC=1
23584 !MCO:PRO:RC=1
23588 !MCO:PRO:RC=1
23590 !MCO:PRO:RC=1
23592 !MCO:PRO:RC=1
23595 !MCO:PRO:RC=1
23597 !MCO:PRO:RC=1
23599 !MCO:PRO:RC=1
23601 !MCO:PRO:RC=1
23603 !MCO:PRO:RC=1
23605 !MCO:PRO:RC=1
23607 !MCO:PRO:RC=1
23611 !MCO:PRO:RC=1
23613 !MCO:PRO:RC=1
23615 !MCO:PRO:RC=1
23617 !MCO:PRO:RC=1
23619 !MCO:PRO:RC=1
23621 !MCO:PRO:RC=1
23623 !MCO:PRO:RC=1
23625 !MCO:PRO:RC=1
23627 !MCO:PRO:RC=1
23629 !MCO:PRO:RC=1
23631 !MCO:PRO:RC=1
23635 !MCO:PRO:RC=1
23638 !MCO:PRO:RC=1
23640 !MCO:PRO:RC=1
23642 !MCO:PRO:RC=1
23644 !MCO:PRO:RC=1
23646 !MCO:PRO:RC=1
23648 !MCO:PRO:RC=1
23650 !MCO:PRO:RC=1
23652 !MCO:PRO:RC=1
23654 !MCO:PRO:RC=1
23656 !MCO:PRO:RC=1
23660 !MCO:PRO:RC=1
23662 !MCO:PRO:RC=1
23664 !MCO:PRO:RC=1
23666 !MCO:PRO:RC=1
23668 !MCO:PRO:RC=1
23670 !MCO:PRO:RC=1
23672 !MCO:PRO:RC=1
23674 !MCO:PRO:RC=1
23676 !MCO:PRO:RC=1
23678 !MCO:PRO:RC=1
23683 !MCO:PRO:RC=1
23685 !MCO:PRO:RC=1
23687 !MCO:PRO:RC=1
23689 !MCO:PRO:RC=1
23691 !MCO:PRO:RC=1
23693 !MCO:PRO:RC=1
23695 !MCO:PRO:RC=1
23697 !MCO:PRO:RC=1
23699 !MCO:PRO:RC=1
23701 !MCO:PRO:RC=1
23703 !MCO:PRO:RC=1
23707 !MCO:PRO:RC=1
23709 !MCO:PRO:RC=1
23711 !MCO:PRO:RC=1
23713 !MCO:PRO:RC=1
23715 !MCO:PRO:RC=1
23717 !MCO:PRO:RC=1
23719 !MCO:PRO:RC=1
23721 !MCO:PRO:RC=1
23724 !MCO:PRO:RC=1
23726 !MCO:PRO:RC=1
23730 !MCO:PRO:RC=1
23732 !MCO:PRO:RC=1
23734 !MCO:PRO:RC=1
23736 !MCO:PRO:RC=1
23738 !MCO:PRO:RC=1
23740 !MCO:PRO:RC=1
23742 !MCO:PRO:RC=1
23744 !MCO:PRO:RC=1
23746 !MCO:PRO:RC=1
23748 !MCO:PRO:RC=1
23750 !MCO:PRO:RC=1
23754 !MCO:PRO:RC=1

Yveaux

I found the root cause: https://github.com/mysensors/MySensors/issues/1458#issuecomment-739139339
It is part of the signing code, so expected to occur on all nodes/gateways that request nonces for signing.
Currently discussing if it is a real issue or not.

MQTT GW with RFM69 on RPi

20

11.7k

11.2k

113.1k