MQTT GW with RFM69 on RPi
-
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=softwareThe 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 SENTgit 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.
-
@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
-- )
-
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=mysensorspasswordI'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
-
@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.
-
@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?
-
@Yveaux Sure, here it is: https://github.com/mysensors/MySensors/issues/1458
-
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.
-
@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.
-
@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
-
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
-
@frits and without signing?
-
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
-
same results on EthernetGateway instead of MQTT Gateway: MCO:PRO only with signing, only on gateway
-
-
@TimO @frits could one of you build & run with MY_DEBUG_VERBOSE_SIGNING defined, and post the output?
-
@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 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 SENTnode 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 releasednode 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 SENTgw 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:1gw 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
-
I've disabled the signing feature and the !MCO:PRO:RC=1 issn't occuring anymore.
-
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
-
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.
-
@Yveaux thank you for digging in!
-
@joaoabs said in MQTT GW with RFM69 on RPi:
Also happening in nodes:
are you setting #define MY_TRANSPORT_WAIT_READY_MS ([timeout])? This causes MCO:PRO in my test cases as well.
-
Don't think so, unless it is set by default.
The code corresponding to the log:/* * 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-2019 Sensnology AB * Full contributor list: https://github.com/mysensors/MySensors/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. * ******************************* */ /** * @ingroup MySigninggrp * @{ * @file SecureActuator.ino * @brief Example sketch showing how to securely control locks. * * This example will remember lock state even after power failure. * * REVISION HISTORY * - See git log (git log libraries/MySensors/examples/SecureActuator/SecureActuator.ino) */ /** * @example SecureActuator.ino * This example implements a secure actuator in the form of a IO controlled electrical lock.<br> * Multiple locks are supported as long as they are on subsequent IO pin indices. The first lock pin * is defined by @ref LOCK_1. The number of locks is controlled by @ref NOF_LOCKS .<br> * The sketch will require incoming messages to be signed and the use of signing backend is selected * by @ref MY_SIGNING_ATSHA204 or @ref MY_SIGNING_SOFT. Hard or soft ATSHA204 signing is supported.<br> * Whitelisting can be enabled through @ref MY_SIGNING_NODE_WHITELISTING in which case a single entry * is provided in this example which typically should map to the gateway of the network. */ #define MY_DEBUG //!< Enable debug prints to serial monitor //#define MY_NODE_LOCK_FEATURE //!< Enable lockdown of node if suspicious activity is detected // RADIO STUFF #define MY_RADIO_RFM69 //!< RFM69 radio driver #define MY_RFM69_NEW_DRIVER #define MY_RFM69_FREQUENCY RFM69_433MHZ //#define MY_IS_RFM69HW #define MY_RFM69_ENABLE_ENCRYPTION //#define MY_DEBUG_VERBOSE_RFM69 // SIGNING STUFF // Select soft/hardware signing method //#define MY_SIGNING_SOFT //!< Software signing #define MY_SIGNING_ATSHA204 //!< Hardware signing using ATSHA204A // Enable node whitelisting //#define MY_SIGNING_NODE_WHITELISTING {{.nodeId = GATEWAY_ADDRESS,.serial = {0x09,0x08,0x07,0x06,0x05,0x04,0x03,0x02,0x01}}} // Enable this if you want destination node to sign all messages sent to this node. #define MY_SIGNING_REQUEST_SIGNATURES //!< destination node signs all messages sent to this node // SETTINGS FOR MY_SIGNING_SOFT //#define MY_SIGNING_SOFT_RANDOMSEED_PIN 7 //!< Unconnected analog pin for random seed // SETTINGS FOR MY_SIGNING_ATSHA204 #ifndef MY_SIGNING_ATSHA204_PIN #define MY_SIGNING_ATSHA204_PIN 17 //!< A3 - pin where ATSHA204 is attached #endif #define MY_DEBUG_VERBOSE_SIGNING //!< Enable signing related debug prints to serial monitor #define MY_NODE_ID 33 #include <MySensors.h> #define LOCK_1 3 //!< Arduino Digital I/O pin number for first lock (second on pin+1 etc) #define NOF_LOCKS 2 //!< Total number of attached locks #define LOCK_LOCK 1 //!< GPIO value to write to lock attached lock #define LOCK_UNLOCK 0 //!< GPIO value to write to unlock attached lock void setup() { for (int lock=1, pin=LOCK_1; lock<=NOF_LOCKS; lock++, pin++) { // Set lock pins in output mode pinMode(pin, OUTPUT); // Set lock to last known state (using eeprom storage) digitalWrite(pin, loadState(lock)?LOCK_LOCK:LOCK_UNLOCK); } } void presentation() { // Send the sketch version information to the gateway and Controller sendSketchInfo("Secure Lock", "1.0"); // Fetch lock status for (int lock=1, pin=LOCK_1; lock<=NOF_LOCKS; lock++, pin++) { // Register all locks to gw (they will be created as child devices) present(lock, S_LOCK, "SecureActuator", false); } } /** @brief Sketch execution code */ void loop() { } /** * @brief Incoming message handler * * @param message The message to handle. */ void receive(const MyMessage &message) { // We only expect one type of message from controller. But we better check anyway. // And echoed messages are not accepted as control messages if (message.getType()==V_LOCK_STATUS && message.getSensor()<=NOF_LOCKS && !message.isEcho()) { // Change relay state digitalWrite(message.getSensor()-1+LOCK_1, message.getBool()?LOCK_LOCK:LOCK_UNLOCK); // Store state in eeprom saveState(message.getSensor(), message.getBool()); // Write some debug info Serial.print("Incoming change for lock:"); Serial.print(message.getSensor()); Serial.print(", New status: "); Serial.println(message.getBool()); } }
-
@Yveaux Hello, Any update if this is an issue or just a cosmetic log thing? Cheers
-
@joaoabs no, sorry. I didn't investigate any deeper.
!
