Ethernet Gateway RFM69HW: Lots of !MCO:PRO

[frits]

Dear All,

MCO:PRO issues are sometimes discussed in this forum. They are often caused by a call to send() inside of receive() and easy to solve, but I ran into this issue with no self-written code at all but a plain vanilla ethernet gateway:

#define MY_DEBUG
#define MY_RADIO_RFM69
#define MY_IS_RFM69HW
#define MY_RFM69_NEW_DRIVER
#define MY_RF69_SPI_CS (14)
#define MY_GATEWAY_W5100
#define MY_PORT 5003
#define MY_IP_ADDRESS 192,168,2,73
#define MY_MAC_ADDRESS 0xDE, 0xAD, 0xBA, 0xBE, 0x02, 0x73
#define MY_SIGNING_ATSHA204 //!< Hardware signing using ATSHA204A
#define MY_SIGNING_ATSHA204_PIN 17
#define MY_SIGNING_REQUEST_SIGNATURES
#include <Ethernet.h>
#include <MySensors.h>
void setup() { }
void presentation() { }
void loop() { }

Gateway Log:

10:42:01.720 -> 0 MCO:BGN:INIT GW,CP=RPNGAA--,FQ=8,REL=0,VER=2.4.0-alpha
...
10:45:09.137 -> 170602 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
10:45:09.285 -> 170776 TSF:MSG:READ,123-123-0,s=2,c=0,t=3,pt=0,l=3,sg=1:led
10:45:09.840 -> 171272 TSF:MSG:READ,123-123-0,s=255,c=3,t=26,pt=1,l=1,sg=1:2
10:45:10.041 -> 171489 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=16,pt=0,l=0,sg=0,ft=0,st=OK:
10:45:10.041 -> 171497 !MCO:PRO:RC=1
10:45:10.041 -> 171499 !MCO:PRO:RC=1
... many many MCO:PRO's ...
10:45:10.389 -> 171821 !MCO:PRO:RC=1
10:45:10.389 -> 171827 TSF:MSG:READ,123-123-0,s=255,c=3,t=17,pt=6,l=25,sg=0:<NONCE>
10:45:10.543 -> 171956 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=27,pt=1,l=1,sg=1,ft=0,st=OK:1
10:45:10.744 -> 172136 TSF:MSG:READ,123-123-0,s=1,c=3,t=16,pt=0,l=0,sg=1:
10:45:11.246 -> 172642 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
...

Sketch Log:

10:32:37.186 ->  __  __       ____
10:32:37.186 -> |  \/  |_   _/ ___|  ___ _ __  ___  ___  _ __ ___
10:32:37.186 -> | |\/| | | | \___ \ / _ \ `_ \/ __|/ _ \| `__/ __|
10:32:37.186 -> | |  | | |_| |___| |  __/ | | \__ \  _  | |  \__ \
10:32:37.186 -> |_|  |_|\__, |____/ \___|_| |_|___/\___/|_|  |___/
10:32:37.186 ->         |___/                      2.4.0-alpha
...
10:45:09.959 -> 9176 SGN:SGN:SGN
10:45:09.959 -> 9189 TSF:MSG:SEND,123-123-0-0,s=1,c=0,t=36,pt=0,l=9,sg=1,ft=0,st=OK:timestamp
10:45:09.959 -> 9196 SGN:SKP:MSG CMD=3,TYPE=16
10:45:10.478 -> 9711 TSF:MSG:SEND,123-123-0-0,s=2,c=3,t=16,pt=0,l=0,sg=1,ft=0,st=OK:
10:45:10.478 -> 9717 SGN:SGN:NCE REQ,TO=0
10:45:10.806 -> 10048 TSF:MSG:READ,0-0-123,s=255,c=3,t=17,pt=6,l=25,sg=1:<NONCE>
10:45:10.853 -> 10054 SGN:SKP:MSG CMD=3,TYPE=17
...

After changing some MY_*-defines I found the MCO:PRO-issue also on the node, for example if MY_TRANSPORT_WAIT_READY_MS is set.

My node is a plain Seeduino M328P with RFM69HW attached according to the HowTo. The Gateway is an Arduino Pro Micro, RFM69HW attached to hardware SPI and W5100 also on hardware SPI, but with a hardware extension (https://arduinodiy.wordpress.com/2017/04/12/the-w5100-bug/).

I know this is not a standard scenario, so I also built an Raspberry Pi 4B+ Gateway, for more testing, with the following config:

./configure \
 --spi-driver=SPIDEV \
--my-transport=rfm69 \
--my-rfm69-frequency=868 \
--my-is-rfm69hw \
--my-gateway=ethernet \
--my-port=5003 \
--my-signing=software \
--my-signing-request-signatures \
--my-signing-debug \
--extra-cxxflags="-DMY_RFM69_NETWORKID=\(100\) -DMY_RFM69_CSMA_LIMIT_DBM=\(-90\) -DMY_SIGNAL_REPORT_ENABLED"

It runs into the same issue :-( :

pi@raspberrypi:~/MySensors $ sudo ./bin/mysgw
Aug 05 11:24:02 INFO  Starting gateway...
Aug 05 11:24:02 INFO  Protocol version - 2.3.2
Aug 05 11:24:02 DEBUG MCO:BGN:INIT GW,CP=RPNGLS--,FQ=NA,REL=255,VER=2.3.2
...
Aug 05 11:24:51 DEBUG SGN:VER:OK
Aug 05 11:24:51 DEBUG GWT:RFC:C=0,MSG=123;255;3;0;6;M
Aug 05 11:24:51 DEBUG SGN:SKP:MSG CMD=3,TYPE=16
Aug 05 11:24:51 DEBUG TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=16,pt=0,l=0,sg=0,ft=0,st=OK:
Aug 05 11:24:51 DEBUG SGN:SGN:NCE REQ,TO=123
Aug 05 11:24:51 DEBUG !MCO:PRO:RC=1
... many many MCO:PROs ...
Aug 05 11:24:52 DEBUG !MCO:PRO:RC=1
Aug 05 11:24:52 DEBUG TSF:MSG:READ,123-123-0,s=255,c=3,t=17,pt=6,l=25,sg=0:<NONCE>
Aug 05 11:24:52 DEBUG SGN:SKP:MSG CMD=3,TYPE=17
Aug 05 11:24:52 DEBUG SGN:NCE:FROM=123
Aug 05 11:24:52 DEBUG SGN:BND:NONCE=DC9C963E113AC087BE7F548A7E330E34B09453BB3BA95A8273AAAAAAAAAAAAAA
Aug 05 11:24:52 DEBUG SGN:BND:HMAC=5FEB8F6821395B8C16F00A4EE23BE730212E85F94B1CB0D3AFC60512EF8066F5

The issue hurts for it slows down presentation and message sending and I'm trying to track down the (possible) error. But I'm lost in the source code :-( Where should I start?

Thanks in advance,

P.S.

Here's my test cases:

TC#	GW		Signing		WAIT_MS		Sketch	Results
1	Arduino		n		0		hello	working fine
2	Arduino		only GW		0		hello	GW: MCO:PRO rc=1+rc=2
3	Arduino		n		1000		hello	GW:fine, Node: MCO:PRO rc=1
4	Arduino		y		0					hello	GW: MCO:PRO rc=1 (presentation+send msg to node), Node:fine
5	Arduino		y		1000		hello	GW: MCO:PRO rc=1 (presentation+send msg to node), Node:MCO:PRO rc=1 (@presentation) (node logfile unfortunately missing)
6	Arduino		y		1000		mini	GW+Node: MCO:PRO rc=1
7	Raspberry	y		1000		mini	GW+Node: MCO:PRO rc=1
8	Raspberry	y		1000		hello	GW+Node: MCO:PRO rc=1 (presentation+send msg to node)
9	Raspberry	y		0		hello	GW: MCO:PRO rc=1, Node: according to the logs MCO:PRO, but in my memory fine, have to repeat the test

Sketches:
hello = HelloM328P.ino
mini = MySminimal.ino
https://github.com/hosentraeger/MySensors-MCO-PRO-analyzing

[Yveaux]

@frits I see you're using 2.4.0 alpha. Did you also test with a mysensors release version?

[frits]

@Yveaux , yes, also on 2.3.2. I've recompiled node and gateway with origin/master, got this on the node:

13:24:33.890 ->  
13:24:33.890 ->  __  __       ____
13:24:33.890 -> |  \/  |_   _/ ___|  ___ _ __  ___  ___  _ __ ___
13:24:33.890 -> | |\/| | | | \___ \ / _ \ `_ \/ __|/ _ \| `__/ __|
13:24:33.890 -> | |  | | |_| |___| |  __/ | | \__ \  _  | |  \__ \
13:24:33.890 -> |_|  |_|\__, |____/ \___|_| |_|___/\___/|_|  |___/
13:24:33.890 ->         |___/                      2.3.2
13:24:33.890 -> 
13:24:33.890 -> 16 MCO:BGN:INIT NODE,CP=RPNNAA--,FQ=16,REL=255,VER=2.3.2
13:24:33.890 -> 33 SGN:PER:OK
13:24:33.974 -> 50 SGN:INI:BND OK
13:24:33.974 -> 52 TSM:INIT
13:24:33.974 -> 53 TSF:WUR:MS=1000
13:24:33.974 -> 55 TSM:INIT:TSP OK
13:24:33.974 -> 57 TSF:SID:OK,ID=123
13:24:33.974 -> 59 TSM:FPAR
13:24:33.974 -> 60 SGN:SGN:NREQ=255
13:24:33.974 -> 66 ?TSF:MSG:SEND,123-123-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK:
13:24:34.946 -> 1055 MCO:BGN:STP
13:24:34.946 -> 1056 MCO:BGN:INIT OK,TSP=0
13:24:35.951 -> 2029 TSF:MSG:READ,0-0-255,s=255,c=3,t=20,pt=0,l=0,sg=0:
13:24:35.951 -> 2034 TSF:MSG:BC
13:24:35.951 -> 2073 !TSM:FPAR:NO REPLY
13:24:35.951 -> 2075 TSM:FPAR
13:24:35.951 -> 2076 SGN:SGN:NREQ=255
13:24:36.004 -> 2082 ?TSF:MSG:SEND,123-123-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK:
13:24:36.693 -> 2783 TSF:MSG:READ,0-0-123,s=255,c=3,t=8,pt=1,l=1,sg=0:0
13:24:36.693 -> 2788 SGN:SKP:MSG CMD=3,TYPE=8
13:24:36.693 -> 2791 TSF:MSG:FPAR OK,ID=0,D=1
13:24:37.981 -> 4090 TSM:FPAR:OK
13:24:37.981 -> 4091 TSM:ID
13:24:37.981 -> 4092 TSM:ID:OK
13:24:37.981 -> 4094 TSM:UPL
13:24:37.981 -> 4096 SGN:SKP:MSG CMD=3,TYPE=24
13:24:37.981 -> 4107 TSF:MSG:SEND,123-123-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=OK:1
13:24:38.590 -> 4328 TSF:MSG:READ,0-0-123,s=255,c=3,t=25,pt=1,l=1,sg=0:1
13:24:38.590 -> 4333 SGN:SKP:MSG CMD=3,TYPE=25
13:24:38.590 -> 4335 TSF:MSG:PONG RECV,HP=1
13:24:38.590 -> 4338 TSM:UPL:OK
13:24:38.590 -> 4340 TSM:READY:ID=123,PAR=0,DIS=1
13:24:38.590 -> 4343 SGN:PRE:SGN REQ
13:24:38.590 -> 4344 SGN:PRE:WHI NREQ
13:24:38.590 -> 4346 SGN:SKP:MSG CMD=3,TYPE=15
13:24:38.590 -> 4359 TSF:MSG:SEND,123-123-0-0,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0101
13:24:38.590 -> 4366 SGN:PRE:XMT,TO=0
13:24:38.590 -> 4368 SGN:PRE:WAIT GW
13:24:38.590 -> 4370 !MCO:PRO:RC=1
13:24:38.590 -> 4371 !MCO:PRO:RC=1
13:24:38.590 -> 4373 !MCO:PRO:RC=1
13:24:38.590 -> 4375 !MCO:PRO:RC=1
13:24:38.590 -> 4377 !MCO:PRO:RC=1
13:24:38.590 -> 4378 !MCO:PRO:RC=1
...

... and on the gateway:

13:24:34.269 -> 0 MCO:BGN:INIT GW,CP=RPNGAA--,FQ=8,REL=255,VER=2.3.2
13:24:34.322 -> 38 TSM:INIT
13:24:34.322 -> 40 TSF:WUR:MS=0
13:24:34.322 -> 43 TSM:INIT:TSP OK
13:24:34.322 -> 45 TSM:INIT:GW MODE
13:24:34.322 -> 47 TSM:READY:ID=0,PAR=0,DIS=0
13:24:34.322 -> 49 MCO:REG:NOT NEEDED
13:24:34.871 -> 614 GWT:TIN:IP=192.168.2.73
13:24:35.875 -> 1617 MCO:BGN:STP
13:24:35.875 -> 1619 MCO:BGN:INIT OK,TSP=1
13:24:35.875 -> 1622 TSM:READY:NWD REQ
13:24:35.929 -> 1630 ?TSF:MSG:SEND,0-0-255-255,s=255,c=3,t=20,pt=0,l=0,sg=0,ft=0,st=OK:
13:24:35.976 -> 1683 TSF:MSG:READ,123-123-255,s=255,c=3,t=7,pt=0,l=0,sg=0:
13:24:35.976 -> 1689 TSF:MSG:BC
13:24:35.976 -> 1691 TSF:MSG:FPAR REQ,ID=123
13:24:35.976 -> 1695 TSF:CKU:OK,FCTRL
13:24:35.976 -> 1697 TSF:MSG:GWL OK
13:24:36.679 -> 2383 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=8,pt=1,l=1,sg=0,ft=0,st=OK:0
13:24:37.983 -> 3704 TSF:MSG:READ,123-123-0,s=255,c=3,t=24,pt=1,l=1,sg=0:1
13:24:37.983 -> 3710 TSF:MSG:PINGED,ID=123,HP=1
13:24:38.184 -> 3926 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=25,pt=1,l=1,sg=0,ft=0,st=OK:1
13:24:38.237 -> 3956 TSF:MSG:READ,123-123-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0101
13:24:38.438 -> 4173 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0101
13:24:38.485 -> 4196 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=0:
13:24:38.817 -> 4489 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=0,ft=0,st=OK:<NONCE>
13:24:38.986 -> 4665 TSF:MSG:READ,123-123-0,s=255,c=0,t=17,pt=0,l=5,sg=1:2.3.2
13:24:39.541 -> 5160 TSF:MSG:READ,123-123-0,s=255,c=3,t=16,pt=0,l=0,sg=1:
13:24:39.842 -> 5453 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=17,pt=6,l=25,sg=1,ft=0,st=OK:<NONCE>
13:24:40.043 -> 5629 TSF:MSG:READ,123-123-0,s=255,c=3,t=6,pt=1,l=1,sg=1:0
13:24:42.052 -> 7622 TSF:MSG:READ,123-123-0,s=255,c=3,t=26,pt=1,l=1,sg=1:2
13:24:42.252 -> 7839 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=16,pt=0,l=0,sg=0,ft=0,st=OK:
13:24:42.299 -> 7847 !MCO:PRO:RC=1
13:24:42.299 -> 7849 !MCO:PRO:RC=1
13:24:42.299 -> 7852 !MCO:PRO:RC=1
13:24:42.299 -> 7856 !MCO:PRO:RC=1
13:24:42.299 -> 7858 !MCO:PRO:RC=1
13:24:42.299 -> 7860 !MCO:PRO:RC=1
13:24:42.299 -> 7862 !MCO:PRO:RC=1
13:24:42.299 -> 7864 !MCO:PRO:RC=1
13:24:42.299 -> 7866 !MCO:PRO:RC=1
13:24:42.299 -> 7868 !MCO:PRO:RC=1
13:24:42.299 -> 7870 !MCO:PRO:RC=1
13:24:42.299 -> 7872 !MCO:PRO:RC=1
13:24:42.299 -> 7874 !MCO:PRO:RC=1
13:24:42.299 -> 7876 !MCO:PRO:RC=1
13:24:42.299 -> 7878 !MCO:PRO:RC=1
13:24:42.299 -> 7880 !MCO:PRO:RC=1
13:24:42.299 -> 7882 !MCO:PRO:RC=1
13:24:42.299 -> 7884 !MCO:PRO:RC=1
13:24:42.299 -> 7886 !MCO:PRO:RC=1
13:24:42.299 -> 7888 !MCO:PRO:RC=1
13:24:42.299 -> 7890 !MCO:PRO:RC=1
13:24:42.299 -> 7892 !MCO:PRO:RC=1
13:24:42.353 -> 7897 !MCO:PRO:RC=1
13:24:42.353 -> 7899 !MCO:PRO:RC=1
13:24:42.353 -> 7901 !MCO:PRO:RC=1
13:24:42.353 -> 7903 !MCO:PRO:RC=1
13:24:42.353 -> 7905 !MCO:PRO:RC=1
13:24:42.353 -> 7907 !MCO:PRO:RC=1
13:24:42.353 -> 7909 !MCO:PRO:RC=1
13:24:42.353 -> 7911 !MCO:PRO:RC=1
13:24:42.353 -> 7913 !MCO:PRO:RC=1
13:24:42.353 -> 7915 !MCO:PRO:RC=1
13:24:42.353 -> 7917 !MCO:PRO:RC=1
13:24:42.353 -> 7919 !MCO:PRO:RC=1
13:24:42.353 -> 7921 !MCO:PRO:RC=1
13:24:42.353 -> 7923 !MCO:PRO:RC=1
13:24:42.353 -> 7925 !MCO:PRO:RC=1
13:24:42.353 -> 7927 !MCO:PRO:RC=1
13:24:42.353 -> 7929 !MCO:PRO:RC=1
13:24:42.353 -> 7931 !MCO:PRO:RC=1
13:24:42.353 -> 7936 !MCO:PRO:RC=1
13:24:42.353 -> 7938 !MCO:PRO:RC=1
13:24:42.353 -> 7940 !MCO:PRO:RC=1
13:24:42.353 -> 7942 !MCO:PRO:RC=1
13:24:42.353 -> 7944 !MCO:PRO:RC=1
13:24:42.353 -> 7946 !MCO:PRO:RC=1
13:24:42.353 -> 7948 !MCO:PRO:RC=1
13:24:42.399 -> 7950 !MCO:PRO:RC=1
13:24:42.399 -> 7952 !MCO:PRO:RC=1
13:24:42.399 -> 7954 !MCO:PRO:RC=1
13:24:42.399 -> 7956 !MCO:PRO:RC=1
13:24:42.399 -> 7958 !MCO:PRO:RC=1
13:24:42.399 -> 7960 !MCO:PRO:RC=1
13:24:42.399 -> 7962 !MCO:PRO:RC=1
13:24:42.399 -> 7964 !MCO:PRO:RC=1
13:24:42.399 -> 7966 !MCO:PRO:RC=1
13:24:42.399 -> 7968 !MCO:PRO:RC=1
13:24:42.399 -> 7970 !MCO:PRO:RC=1
13:24:42.399 -> 7974 !MCO:PRO:RC=1
13:24:42.399 -> 7976 !MCO:PRO:RC=1
13:24:42.399 -> 7979 !MCO:PRO:RC=1
13:24:42.399 -> 7981 !MCO:PRO:RC=1
13:24:42.399 -> 7983 !MCO:PRO:RC=1
13:24:42.399 -> 7985 !MCO:PRO:RC=1
13:24:42.399 -> 7987 !MCO:PRO:RC=1
13:24:42.399 -> 7989 !MCO:PRO:RC=1
13:24:42.399 -> 7991 !MCO:PRO:RC=1
13:24:42.399 -> 7993 !MCO:PRO:RC=1
13:24:42.399 -> 7995 !MCO:PRO:RC=1
13:24:42.453 -> 7997 !MCO:PRO:RC=1
13:24:42.453 -> 7999 !MCO:PRO:RC=1
13:24:42.453 -> 8001 !MCO:PRO:RC=1
13:24:42.453 -> 8003 !MCO:PRO:RC=1
13:24:42.453 -> 8005 !MCO:PRO:RC=1
13:24:42.453 -> 8007 !MCO:PRO:RC=1
13:24:42.453 -> 8009 !MCO:PRO:RC=1
13:24:42.453 -> 8013 !MCO:PRO:RC=1
13:24:42.453 -> 8015 !MCO:PRO:RC=1
13:24:42.453 -> 8017 !MCO:PRO:RC=1
13:24:42.453 -> 8019 !MCO:PRO:RC=1
13:24:42.453 -> 8022 !MCO:PRO:RC=1
13:24:42.453 -> 8024 !MCO:PRO:RC=1
13:24:42.453 -> 8026 !MCO:PRO:RC=1
13:24:42.453 -> 8028 !MCO:PRO:RC=1
13:24:42.453 -> 8030 !MCO:PRO:RC=1
13:24:42.453 -> 8032 !MCO:PRO:RC=1
13:24:42.453 -> 8034 !MCO:PRO:RC=1
13:24:42.453 -> 8036 !MCO:PRO:RC=1
13:24:42.453 -> 8038 !MCO:PRO:RC=1
13:24:42.453 -> 8040 !MCO:PRO:RC=1
13:24:42.453 -> 8042 !MCO:PRO:RC=1
13:24:42.453 -> 8044 !MCO:PRO:RC=1
13:24:42.453 -> 8046 !MCO:PRO:RC=1
13:24:42.453 -> 8048 !MCO:PRO:RC=1
13:24:42.500 -> 8052 !MCO:PRO:RC=1
13:24:42.500 -> 8054 !MCO:PRO:RC=1
13:24:42.500 -> 8056 !MCO:PRO:RC=1
13:24:42.500 -> 8058 !MCO:PRO:RC=1
13:24:42.500 -> 8060 !MCO:PRO:RC=1
13:24:42.500 -> 8062 !MCO:PRO:RC=1
13:24:42.500 -> 8065 !MCO:PRO:RC=1
13:24:42.500 -> 8067 !MCO:PRO:RC=1
13:24:42.500 -> 8069 !MCO:PRO:RC=1
13:24:42.500 -> 8071 !MCO:PRO:RC=1
13:24:42.500 -> 8073 !MCO:PRO:RC=1
13:24:42.500 -> 8075 !MCO:PRO:RC=1
13:24:42.500 -> 8077 !MCO:PRO:RC=1
13:24:42.500 -> 8079 !MCO:PRO:RC=1
13:24:42.500 -> 8081 !MCO:PRO:RC=1
13:24:42.500 -> 8083 !MCO:PRO:RC=1
13:24:42.500 -> 8085 !MCO:PRO:RC=1
13:24:42.500 -> 8087 !MCO:PRO:RC=1
13:24:42.500 -> 8089 !MCO:PRO:RC=1
13:24:42.500 -> 8093 !MCO:PRO:RC=1
13:24:42.500 -> 8095 !MCO:PRO:RC=1
13:24:42.500 -> 8097 !MCO:PRO:RC=1
13:24:42.554 -> 8099 !MCO:PRO:RC=1
13:24:42.554 -> 8101 !MCO:PRO:RC=1
13:24:42.554 -> 8103 !MCO:PRO:RC=1
13:24:42.554 -> 8105 !MCO:PRO:RC=1
13:24:42.554 -> 8108 !MCO:PRO:RC=1
13:24:42.554 -> 8110 !MCO:PRO:RC=1
13:24:42.554 -> 8112 !MCO:PRO:RC=1
13:24:42.554 -> 8114 !MCO:PRO:RC=1
13:24:42.554 -> 8116 !MCO:PRO:RC=1
13:24:42.554 -> 8118 !MCO:PRO:RC=1
13:24:42.554 -> 8120 !MCO:PRO:RC=1
13:24:42.554 -> 8122 !MCO:PRO:RC=1
13:24:42.554 -> 8124 !MCO:PRO:RC=1
13:24:42.554 -> 8126 !MCO:PRO:RC=1
13:24:42.554 -> 8128 !MCO:PRO:RC=1
13:24:42.554 -> 8132 !MCO:PRO:RC=1
13:24:42.554 -> 8134 !MCO:PRO:RC=1
13:24:42.554 -> 8136 !MCO:PRO:RC=1
13:24:42.554 -> 8138 !MCO:PRO:RC=1
13:24:42.554 -> 8140 !MCO:PRO:RC=1
13:24:42.554 -> 8142 !MCO:PRO:RC=1
13:24:42.554 -> 8144 !MCO:PRO:RC=1
13:24:42.554 -> 8146 !MCO:PRO:RC=1
13:24:42.554 -> 8148 !MCO:PRO:RC=1
13:24:42.600 -> 8151 !MCO:PRO:RC=1
13:24:42.600 -> 8153 !MCO:PRO:RC=1
13:24:42.600 -> 8155 !MCO:PRO:RC=1
13:24:42.600 -> 8157 !MCO:PRO:RC=1
13:24:42.600 -> 8159 !MCO:PRO:RC=1
13:24:42.600 -> 8161 !MCO:PRO:RC=1
13:24:42.600 -> 8163 !MCO:PRO:RC=1
13:24:42.600 -> 8165 !MCO:PRO:RC=1
13:24:42.600 -> 8167 !MCO:PRO:RC=1
13:24:42.600 -> 8171 !MCO:PRO:RC=1
13:24:42.600 -> 8173 !MCO:PRO:RC=1
13:24:42.600 -> 8179 TSF:MSG:READ,123-123-0,s=255,c=3,t=17,pt=6,l=25,sg=0:<NONCE>
13:24:42.754 -> 8306 TSF:MSG:SEND,0-0-123-123,s=255,c=3,t=27,pt=1,l=1,sg=1,ft=0,st=OK:1