Hi. We’ve configured MQTT connection from client to server with historical data keep, and it works fine. Last night the Edge was disconnected (wrong network config), when this morning we reconected it correctly started republishing the data, according to the logs below, but the data on the server is missing.
The only uncommon thing I see is that after the connection was established and it starter publishing backlog data it dropped once again. Is it possible something went lost in this phase?
Thanks
Edge 7.9.12, MQTT 3.4.8.
INFO | jvm 1 | 2019/11/15 08:42:59 | W [c.c.m.t.g.m.EdgeNode ] [07:42:59]: Not connected - attempting connect
INFO | jvm 1 | 2019/11/15 08:42:59 | W [c.c.c.c.CirrusClient ] [07:42:59]: TransmissionClient-Transmission-RPC-14d7c2a8-deb9-4487: connect with retry failed due to MqttException
INFO | jvm 1 | 2019/11/15 08:42:59 | org.eclipse.paho.client.mqttv3.MqttException: MqttException
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.ExceptionHelper.createMqttException(ExceptionHelper.java:38)
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.ClientComms$ConnectBG.run(ClientComms.java:690)
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.lang.Thread.run(Thread.java:748)
INFO | jvm 1 | 2019/11/15 08:42:59 | Caused by: java.net.UnknownHostException: mqtt.server.it
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.net.InetAddress.getAllByName0(InetAddress.java:1281)
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.net.InetAddress.getAllByName(InetAddress.java:1193)
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.net.InetAddress.getAllByName(InetAddress.java:1127)
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.net.InetAddress.getByName(InetAddress.java:1077)
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:75)
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.SSLNetworkModule.start(SSLNetworkModule.java:92)
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.ClientComms$ConnectBG.run(ClientComms.java:676)
INFO | jvm 1 | 2019/11/15 08:42:59 | ... 1 common frames omitted
INFO | jvm 1 | 2019/11/15 08:42:59 | W [c.c.c.c.CirrusClient ] [07:42:59]: MQTT Client details: MQTT Server Name = ips-prod-broker01 :: MQTT Server URL = ssl://mqtt.server.it:8883 :: MQTT Client ID = TransmissionClient-Transmission-RPC-14d7c2a8-deb9-4487 :: Using CA File = false :: Using Certificate = false :: Using private key = false :: Using JKS = false :: Using Birth = false :: Using LWT = false
INFO | jvm 1 | 2019/11/15 08:42:59 | I [c.c.m.t.g.m.EdgeNode ] [07:42:59]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Attempting to connect
INFO | jvm 1 | 2019/11/15 08:42:59 | E [c.c.c.c.CirrusClient ] [07:42:59]: Unable to resolve host ssl://mqtt.server.it:8883
INFO | jvm 1 | 2019/11/15 08:42:59 | I [c.c.c.c.CirrusClient ] [07:42:59]: TransmissionClient-080dbbc8-32a3-41f1: Creating the MQTT Client to ssl://mqtt.server.it:8883 on thread Thread-31234
INFO | jvm 1 | 2019/11/15 08:42:59 | W [c.c.c.c.CirrusClient ] [07:42:59]: TransmissionClient-080dbbc8-32a3-41f1: connect failed due to MqttException
INFO | jvm 1 | 2019/11/15 08:42:59 | org.eclipse.paho.client.mqttv3.MqttException: MqttException
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.ExceptionHelper.createMqttException(ExceptionHelper.java:38)
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.ClientComms$ConnectBG.run(ClientComms.java:690)
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.lang.Thread.run(Thread.java:748)
INFO | jvm 1 | 2019/11/15 08:42:59 | Caused by: java.net.UnknownHostException: mqtt.server.it
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.net.InetAddress.getAllByName0(InetAddress.java:1281)
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.net.InetAddress.getAllByName(InetAddress.java:1193)
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.net.InetAddress.getAllByName(InetAddress.java:1127)
INFO | jvm 1 | 2019/11/15 08:42:59 | at java.net.InetAddress.getByName(InetAddress.java:1077)
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:75)
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.SSLNetworkModule.start(SSLNetworkModule.java:92)
INFO | jvm 1 | 2019/11/15 08:42:59 | at org.eclipse.paho.client.mqttv3.internal.ClientComms$ConnectBG.run(ClientComms.java:676)
INFO | jvm 1 | 2019/11/15 08:42:59 | ... 1 common frames omitted
INFO | jvm 1 | 2019/11/15 08:42:59 | W [c.c.c.c.CirrusClient ] [07:42:59]: MQTT Client details: MQTT Server Name = ips-prod-broker01 :: MQTT Server URL = ssl://mqtt.server.it:8883 :: MQTT Client ID = TransmissionClient-080dbbc8-32a3-41f1 :: Using CA File = false :: Using Certificate = false :: Using private key = false :: Using JKS = false :: Using Birth = false :: Using LWT = true
INFO | jvm 1 | 2019/11/15 08:43:00 | I [c.c.m.t.g.m.EdgeNode ] [07:43:00]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] No longer attempting to connect
INFO | jvm 1 | 2019/11/15 08:43:00 | E [c.c.m.t.g.m.EdgeNode ] [07:43:00]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Failed to achieve connected state
INFO | jvm 1 | 2019/11/15 08:43:00 | I [c.c.m.t.g.m.EdgeNode ] [07:43:00]: Attempting disconnect ssl://mqtt.server.it:8883 :: TransmissionClient-080dbbc8-32a3-41f1
INFO | jvm 1 | 2019/11/15 08:43:00 | I [c.c.m.t.g.m.EdgeNode ] [07:43:00]: Successfully disconnected ssl://mqtt.server.it:8883 :: TransmissionClient-080dbbc8-32a3-41f1
INFO | jvm 1 | 2019/11/15 08:43:01 | W [c.c.m.t.g.m.EdgeNode ] [07:43:01]: Not connected - attempting connect
INFO | jvm 1 | 2019/11/15 08:43:02 | I [c.c.m.t.g.m.EdgeNode ] [07:43:02]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Attempting to connect
INFO | jvm 1 | 2019/11/15 08:43:02 | I [c.c.c.c.CirrusClient ] [07:43:02]: TransmissionClient-080dbbc8-32a3-41f1: Creating the MQTT Client to ssl://mqtt.server.it:8883 on thread Thread-31236
INFO | jvm 1 | 2019/11/15 08:43:05 | I [c.c.c.c.CirrusClient ] [07:43:05]: TransmissionClient-080dbbc8-32a3-41f1: connect succeeded
INFO | jvm 1 | 2019/11/15 08:43:05 | I [c.c.c.c.CirrusClient ] [07:43:05]: TransmissionClient-080dbbc8-32a3-41f1: Connected to ssl://mqtt.server.it:8883
INFO | jvm 1 | 2019/11/15 08:43:05 | I [c.c.c.c.CirrusClient ] [07:43:05]: TransmissionClient-Transmission-RPC-14d7c2a8-deb9-4487: connect with retry succeeded
INFO | jvm 1 | 2019/11/15 08:43:05 | I [c.c.m.t.g.m.EdgeNode ] [07:43:05]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Connected to the MQTT Server
INFO | jvm 1 | 2019/11/15 08:43:05 | I [c.c.c.c.CirrusClient ] [07:43:05]: TransmissionClient-Transmission-RPC-14d7c2a8-deb9-4487: MQTT Client connected to ssl://mqtt.server.it:8883 on thread Thread-23
INFO | jvm 1 | 2019/11/15 08:43:05 | I [c.c.c.c.CirrusClient ] [07:43:05]: TransmissionClient-Transmission-RPC-14d7c2a8-deb9-4487: Connected to ssl://mqtt.server.it:8883
INFO | jvm 1 | 2019/11/15 08:43:07 | I [c.c.m.t.g.TargetCallback ] [07:43:07]: Got STATE message: STATE/cieHostID :: ONLINE
INFO | jvm 1 | 2019/11/15 08:43:07 | I [c.c.m.t.g.m.EdgeNode ] [07:43:07]: Critical/Primary app is ONLINE - cancelling disconnect timer
INFO | jvm 1 | 2019/11/15 08:43:07 | I [c.c.m.t.g.m.EdgeNode ] [07:43:07]: [STATE CHANGE] Handling transition to online
INFO | jvm 1 | 2019/11/15 08:43:10 | E [c.c.m.t.g.m.EdgeNode ] [07:43:10]: Payload failed to publish - decrementing sequence number
INFO | jvm 1 | 2019/11/15 08:43:12 | W [c.c.m.t.g.m.EdgeNode ] [07:43:11]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@67cf1e
[...]
INFO | jvm 1 | 2019/11/15 08:43:24 | I [c.c.m.t.g.m.EdgeNode ] [07:43:24]: Setting metric timestamp to 1573803804588 to avoid collision for Scan_Class10s :: 127060
INFO | jvm 1 | 2019/11/15 08:43:24 | W [c.c.m.t.g.m.EdgeNode ] [07:43:24]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@b926f1
[...]
INFO | jvm 1 | 2019/11/15 08:43:38 | W [c.c.m.t.g.m.EdgeNode ] [07:43:38]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@4d5
INFO | jvm 1 | 2019/11/15 08:43:39 | I [c.c.m.t.g.m.EdgeNode ] [07:43:39]: Setting metric timestamp to 1573803819024 to avoid collision for Scan_Class1s :: 1270529
INFO | jvm 1 | 2019/11/15 08:44:11 | W [c.c.m.t.g.TargetCallback ] [07:44:11]: Active workers in message arrive queue is 6
INFO | jvm 1 | 2019/11/15 08:44:11 | W [c.c.m.t.g.TargetCallback ] [07:44:11]: poolSize = 1, corePoolSize = 1, queueSize = 6, queueRemainingCapacity = 2147483641, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:44:17 | W [c.c.m.t.g.TargetCallback ] [07:44:17]: Active workers in message arrive queue is 7
INFO | jvm 1 | 2019/11/15 08:44:17 | W [c.c.m.t.g.TargetCallback ] [07:44:17]: poolSize = 1, corePoolSize = 1, queueSize = 7, queueRemainingCapacity = 2147483640, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:44:22 | W [c.c.m.t.g.TargetCallback ] [07:44:22]: Active workers in message arrive queue is 8
INFO | jvm 1 | 2019/11/15 08:44:22 | W [c.c.m.t.g.TargetCallback ] [07:44:22]: poolSize = 1, corePoolSize = 1, queueSize = 8, queueRemainingCapacity = 2147483639, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:44:28 | W [c.c.m.t.g.TargetCallback ] [07:44:28]: Active workers in message arrive queue is 9
INFO | jvm 1 | 2019/11/15 08:44:28 | W [c.c.m.t.g.TargetCallback ] [07:44:28]: poolSize = 1, corePoolSize = 1, queueSize = 9, queueRemainingCapacity = 2147483638, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:44:34 | W [c.c.m.t.g.TargetCallback ] [07:44:34]: Active workers in message arrive queue is 10
INFO | jvm 1 | 2019/11/15 08:44:34 | W [c.c.m.t.g.TargetCallback ] [07:44:34]: poolSize = 1, corePoolSize = 1, queueSize = 10, queueRemainingCapacity = 2147483637, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:44:40 | W [c.c.m.t.g.TargetCallback ] [07:44:40]: Active workers in message arrive queue is 11
INFO | jvm 1 | 2019/11/15 08:44:40 | W [c.c.m.t.g.TargetCallback ] [07:44:40]: poolSize = 1, corePoolSize = 1, queueSize = 11, queueRemainingCapacity = 2147483636, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:44:45 | W [c.c.m.t.g.TargetCallback ] [07:44:45]: Active workers in message arrive queue is 12
INFO | jvm 1 | 2019/11/15 08:44:45 | W [c.c.m.t.g.TargetCallback ] [07:44:45]: poolSize = 1, corePoolSize = 1, queueSize = 12, queueRemainingCapacity = 2147483635, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:44:52 | W [c.c.m.t.g.TargetCallback ] [07:44:52]: Active workers in message arrive queue is 13
INFO | jvm 1 | 2019/11/15 08:44:52 | W [c.c.m.t.g.TargetCallback ] [07:44:52]: poolSize = 1, corePoolSize = 1, queueSize = 13, queueRemainingCapacity = 2147483634, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:44:58 | W [c.c.m.t.g.TargetCallback ] [07:44:57]: Active workers in message arrive queue is 14
INFO | jvm 1 | 2019/11/15 08:44:58 | W [c.c.m.t.g.TargetCallback ] [07:44:57]: poolSize = 1, corePoolSize = 1, queueSize = 14, queueRemainingCapacity = 2147483633, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:45:03 | W [c.c.m.t.g.TargetCallback ] [07:45:03]: Active workers in message arrive queue is 15
INFO | jvm 1 | 2019/11/15 08:45:03 | W [c.c.m.t.g.TargetCallback ] [07:45:03]: poolSize = 1, corePoolSize = 1, queueSize = 15, queueRemainingCapacity = 2147483632, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:45:09 | W [c.c.m.t.g.TargetCallback ] [07:45:09]: Active workers in message arrive queue is 16
INFO | jvm 1 | 2019/11/15 08:45:09 | W [c.c.m.t.g.TargetCallback ] [07:45:09]: poolSize = 1, corePoolSize = 1, queueSize = 16, queueRemainingCapacity = 2147483631, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:45:15 | W [c.c.m.t.g.TargetCallback ] [07:45:15]: Active workers in message arrive queue is 17
INFO | jvm 1 | 2019/11/15 08:45:15 | W [c.c.m.t.g.TargetCallback ] [07:45:15]: poolSize = 1, corePoolSize = 1, queueSize = 17, queueRemainingCapacity = 2147483630, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:45:20 | I [c.c.m.t.g.h.HistoryPublisher ] [07:45:20]: Publishing 10000 historical metrics to project01/ips-edge-test-oracle
INFO | jvm 1 | 2019/11/15 08:45:20 | I [c.c.m.t.g.m.EdgeNode ] [07:45:20]: Processing Rebirth request
INFO | jvm 1 | 2019/11/15 08:45:20 | W [c.c.m.t.g.m.EdgeNode ] [07:45:20]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@dd02c7
[...]
INFO | jvm 1 | 2019/11/15 08:45:28 | W [c.c.m.t.g.m.EdgeNode ] [07:45:28]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@1bca954
INFO | jvm 1 | 2019/11/15 08:45:29 | W [c.c.m.t.g.TargetCallback ] [07:45:29]: Active workers in message arrive queue is 17
INFO | jvm 1 | 2019/11/15 08:45:29 | W [c.c.m.t.g.TargetCallback ] [07:45:29]: poolSize = 1, corePoolSize = 1, queueSize = 17, queueRemainingCapacity = 2147483630, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:45:29 | W [c.c.m.t.g.m.EdgeNode ] [07:45:29]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@1a9d29b
[...]
INFO | jvm 1 | 2019/11/15 08:46:14 | W [c.c.m.t.g.m.EdgeNode ] [07:46:13]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@5aa708
INFO | jvm 1 | 2019/11/15 08:46:14 | I [c.c.m.t.g.h.HistoryPublisher ] [07:46:14]: Publishing 10000 historical metrics to project01/ips-edge-test-oracle
INFO | jvm 1 | 2019/11/15 08:46:15 | W [c.c.m.t.g.m.EdgeNode ] [07:46:15]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@dd7672
[...]
INFO | jvm 1 | 2019/11/15 08:46:20 | W [c.c.m.t.g.m.EdgeNode ] [07:46:20]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@157b050
INFO | jvm 1 | 2019/11/15 08:46:21 | W [c.c.m.t.g.TargetCallback ] [07:46:21]: Active workers in message arrive queue is 18
INFO | jvm 1 | 2019/11/15 08:46:21 | W [c.c.m.t.g.TargetCallback ] [07:46:21]: poolSize = 1, corePoolSize = 1, queueSize = 18, queueRemainingCapacity = 2147483629, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:46:21 | W [c.c.m.t.g.m.EdgeNode ] [07:46:21]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@151ad5a
[...]
INFO | jvm 1 | 2019/11/15 08:46:48 | W [c.c.m.t.g.m.EdgeNode ] [07:46:48]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@38cbdb
INFO | jvm 1 | 2019/11/15 08:46:48 | I [c.c.m.t.g.h.HistoryPublisher ] [07:46:48]: Publishing 10000 historical metrics to project01/ips-edge-test-oracle
INFO | jvm 1 | 2019/11/15 08:46:49 | W [c.c.m.t.g.m.EdgeNode ] [07:46:49]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@111ba8a
INFO | jvm 1 | 2019/11/15 08:46:49 | W [c.c.m.t.g.m.EdgeNode ] [07:46:49]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@eaae6
INFO | jvm 1 | 2019/11/15 08:46:50 | I [c.c.m.t.g.m.EdgeNode ] [07:46:50]: Setting metric timestamp to 1573804010073 to avoid collision for Scan_Class10s :: 127078
INFO | jvm 1 | 2019/11/15 08:46:50 | W [c.c.m.t.g.m.EdgeNode ] [07:46:50]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@1c4a1e9
INFO | jvm 1 | 2019/11/15 08:46:50 | W [c.c.m.t.g.m.EdgeNode ] [07:46:50]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@1cacf99
INFO | jvm 1 | 2019/11/15 08:46:50 | I [c.c.m.t.g.m.EdgeNode ] [07:46:50]: Setting metric timestamp to 1573804010788 to avoid collision for Scan_Class1s :: 1270712
INFO | jvm 1 | 2019/11/15 08:46:51 | W [c.c.m.t.g.m.EdgeNode ] [07:46:51]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@1e7f640
[...]
INFO | jvm 1 | 2019/11/15 08:46:53 | W [c.c.m.t.g.m.EdgeNode ] [07:46:53]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@84cc56
INFO | jvm 1 | 2019/11/15 08:46:54 | W [c.c.m.t.g.TargetCallback ] [07:46:54]: Active workers in message arrive queue is 19
INFO | jvm 1 | 2019/11/15 08:46:54 | W [c.c.m.t.g.TargetCallback ] [07:46:54]: poolSize = 1, corePoolSize = 1, queueSize = 19, queueRemainingCapacity = 2147483628, maximumPoolSize = 1
INFO | jvm 1 | 2019/11/15 08:46:54 | W [c.c.m.t.g.m.EdgeNode ] [07:46:54]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@f0f7de
[...]
INFO | jvm 1 | 2019/11/15 08:47:26 | W [c.c.m.t.g.m.EdgeNode ] [07:47:26]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@173b140
INFO | jvm 1 | 2019/11/15 08:47:27 | I [c.c.m.t.g.h.HistoryPublisher ] [07:47:27]: Publishing 6057 historical metrics to project01/ips-edge-test-oracle
INFO | jvm 1 | 2019/11/15 08:47:27 | I [c.c.m.t.g.m.EdgeNode ] [07:47:27]: Rebirth request but just issued a rebirth - ignoring
[...]
INFO | jvm 1 | 2019/11/15 08:47:27 | I [c.c.m.t.g.m.EdgeNode ] [07:47:27]: Rebirth request but just issued a rebirth - ignoring
INFO | jvm 1 | 2019/11/15 08:47:33 | I [c.c.m.t.g.m.EdgeNode ] [07:47:33]: Processing Rebirth request
INFO | jvm 1 | 2019/11/15 08:47:34 | W [c.c.m.t.g.m.EdgeNode ] [07:47:34]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@4330d4
INFO | jvm 1 | 2019/11/15 08:47:35 | W [c.c.m.t.g.m.EdgeNode ] [07:47:35]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@1abae4
INFO | jvm 1 | 2019/11/15 08:49:10 | nov 15, 2019 8:49:10 AM org.eclipse.paho.client.mqttv3.internal.ClientState checkForActivity
INFO | jvm 1 | 2019/11/15 08:49:10 | GRAVE: TransmissionClient-080dbbc8-32a3-41f1: Timed out as no activity, keepAlive=5.000 lastOutboundActivity=1.573.804.150.130 lastInboundActivity=1.573.804.140.502 time=1.573.804.150.446 lastPing=1.573.804.145.448
INFO | jvm 1 | 2019/11/15 08:49:10 | W [c.c.m.t.g.TargetCallback ] [07:49:10]: MQTT connection lost for TransmissionClient-080dbbc8-32a3-41f1
INFO | jvm 1 | 2019/11/15 08:49:10 | E [c.c.m.t.g.TargetCallback ] [07:49:10]: Connection lost
INFO | jvm 1 | 2019/11/15 08:49:10 | org.eclipse.paho.client.mqttv3.MqttException: Scaduto in attesa di una risposta dal server
INFO | jvm 1 | 2019/11/15 08:49:10 | at org.eclipse.paho.client.mqttv3.internal.ExceptionHelper.createMqttException(ExceptionHelper.java:31)
INFO | jvm 1 | 2019/11/15 08:49:10 | at org.eclipse.paho.client.mqttv3.internal.ClientState.checkForActivity(ClientState.java:679)
INFO | jvm 1 | 2019/11/15 08:49:10 | at org.eclipse.paho.client.mqttv3.internal.ClientComms.checkForActivity(ClientComms.java:758)
INFO | jvm 1 | 2019/11/15 08:49:10 | at org.eclipse.paho.client.mqttv3.internal.ClientComms.checkForActivity(ClientComms.java:744)
INFO | jvm 1 | 2019/11/15 08:49:10 | at org.eclipse.paho.client.mqttv3.TimerPingSender$PingTask.run(TimerPingSender.java:78)
INFO | jvm 1 | 2019/11/15 08:49:10 | at java.util.TimerThread.mainLoop(Timer.java:555)
INFO | jvm 1 | 2019/11/15 08:49:10 | at java.util.TimerThread.run(Timer.java:505)
INFO | jvm 1 | 2019/11/15 08:49:11 | W [c.c.m.t.g.m.EdgeNode ] [07:49:11]: Not connected - attempting connect
INFO | jvm 1 | 2019/11/15 08:49:11 | W [c.c.m.t.g.m.EdgeNode ] [07:49:11]: Failed to publish - client is not connected
INFO | jvm 1 | 2019/11/15 08:49:11 | I [c.c.m.t.g.m.EdgeNode ] [07:49:11]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Attempting to connect
INFO | jvm 1 | 2019/11/15 08:49:11 | E [c.c.m.t.g.m.EdgeNode ] [07:49:11]: Payload failed to publish - decrementing sequence number
INFO | jvm 1 | 2019/11/15 08:49:12 | nov 15, 2019 8:49:11 AM org.eclipse.paho.client.mqttv3.internal.ClientState checkForActivity
INFO | jvm 1 | 2019/11/15 08:49:12 | GRAVE: TransmissionClient-080dbbc8-32a3-41f1: Timed out as no activity, keepAlive=5.000 lastOutboundActivity=1.573.804.146.978 lastInboundActivity=1.573.804.142.029 time=1.573.804.151.975 lastPing=1.573.804.146.978
INFO | jvm 1 | 2019/11/15 08:49:13 | W [c.c.m.t.g.m.EdgeNode ] [07:49:12]: Failed to publish - client is not connected
INFO | jvm 1 | 2019/11/15 08:49:13 | E [c.c.m.t.g.m.EdgeNode ] [07:49:13]: Payload failed to publish - decrementing sequence number
INFO | jvm 1 | 2019/11/15 08:49:14 | W [c.c.m.t.g.m.EdgeNode ] [07:49:14]: Failed to publish - client is not connected
INFO | jvm 1 | 2019/11/15 08:49:14 | E [c.c.m.t.g.m.EdgeNode ] [07:49:14]: Payload failed to publish - decrementing sequence number
[...]
INFO | jvm 1 | 2019/11/15 08:49:55 | W [c.c.m.t.g.m.EdgeNode ] [07:49:55]: Failed to publish - client is not connected
INFO | jvm 1 | 2019/11/15 08:49:55 | E [c.c.m.t.g.m.EdgeNode ] [07:49:55]: Payload failed to publish - decrementing sequence number
INFO | jvm 1 | 2019/11/15 08:49:56 | E [c.c.m.t.g.m.EdgeNode ] [07:49:56]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Failed to achieve connected state
INFO | jvm 1 | 2019/11/15 08:49:56 | I [c.c.m.t.g.m.EdgeNode ] [07:49:56]: Attempting disconnect ssl://mqtt.server.it:8883 :: TransmissionClient-080dbbc8-32a3-41f1
INFO | jvm 1 | 2019/11/15 08:49:56 | I [c.c.m.t.g.m.EdgeNode ] [07:49:56]: Successfully disconnected ssl://mqtt.server.it:8883 :: TransmissionClient-080dbbc8-32a3-41f1
INFO | jvm 1 | 2019/11/15 08:49:57 | W [c.c.m.t.g.m.EdgeNode ] [07:49:57]: Failed to publish - client is null
INFO | jvm 1 | 2019/11/15 08:49:57 | E [c.c.m.t.g.m.EdgeNode ] [07:49:57]: Payload failed to publish - decrementing sequence number
INFO | jvm 1 | 2019/11/15 08:49:57 | W [c.c.m.t.g.m.EdgeNode ] [07:49:57]: Not connected - attempting connect
INFO | jvm 1 | 2019/11/15 08:49:58 | I [c.c.m.t.g.m.EdgeNode ] [07:49:58]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Attempting to connect
INFO | jvm 1 | 2019/11/15 08:49:58 | W [c.c.m.t.g.m.EdgeNode ] [07:49:58]: Failed to publish - client is not connected
INFO | jvm 1 | 2019/11/15 08:49:58 | E [c.c.m.t.g.m.EdgeNode ] [07:49:58]: Payload failed to publish - decrementing sequence number
INFO | jvm 1 | 2019/11/15 08:49:58 | I [c.c.c.c.CirrusClient ] [07:49:58]: TransmissionClient-080dbbc8-32a3-41f1: Creating the MQTT Client to ssl://mqtt.server.it:8883 on thread Thread-32808
INFO | jvm 1 | 2019/11/15 08:49:59 | I [c.c.c.c.CirrusClient ] [07:49:59]: TransmissionClient-080dbbc8-32a3-41f1: connect succeeded
INFO | jvm 1 | 2019/11/15 08:49:59 | I [c.c.c.c.CirrusClient ] [07:49:59]: TransmissionClient-080dbbc8-32a3-41f1: Connected to ssl://mqtt.server.it:8883
INFO | jvm 1 | 2019/11/15 08:50:00 | I [c.c.m.t.g.m.EdgeNode ] [07:50:00]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Connected to the MQTT Server
INFO | jvm 1 | 2019/11/15 08:50:00 | I [c.c.c.c.CirrusClient ] [07:50:00]: TransmissionClient-080dbbc8-32a3-41f1: connect succeeded
INFO | jvm 1 | 2019/11/15 08:50:00 | I [c.c.c.c.CirrusClient ] [07:50:00]: TransmissionClient-080dbbc8-32a3-41f1: Connected to ssl://mqtt.server.it:8883
INFO | jvm 1 | 2019/11/15 08:50:00 | W [c.c.m.t.g.TargetCallback ] [07:50:00]: MQTT connection lost for TransmissionClient-080dbbc8-32a3-41f1
INFO | jvm 1 | 2019/11/15 08:50:00 | E [c.c.m.t.g.TargetCallback ] [07:50:00]: Connection lost
INFO | jvm 1 | 2019/11/15 08:50:00 | org.eclipse.paho.client.mqttv3.MqttException: Connessione persa
INFO | jvm 1 | 2019/11/15 08:50:00 | at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:164)
INFO | jvm 1 | 2019/11/15 08:50:00 | at java.lang.Thread.run(Thread.java:748)
INFO | jvm 1 | 2019/11/15 08:50:00 | Caused by: java.io.EOFException: null
INFO | jvm 1 | 2019/11/15 08:50:00 | at java.io.DataInputStream.readByte(DataInputStream.java:267)
INFO | jvm 1 | 2019/11/15 08:50:00 | at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:92)
INFO | jvm 1 | 2019/11/15 08:50:00 | at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:116)
INFO | jvm 1 | 2019/11/15 08:50:00 | ... 1 common frames omitted
INFO | jvm 1 | 2019/11/15 08:50:01 | W [c.c.m.t.g.m.EdgeNode ] [07:50:01]: Not connected - attempting connect
INFO | jvm 1 | 2019/11/15 08:50:01 | W [c.c.m.t.g.m.EdgeNode ] [07:50:01]: Failed to publish - client is not connected
INFO | jvm 1 | 2019/11/15 08:50:01 | I [c.c.m.t.g.m.EdgeNode ] [07:50:01]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Attempting to connect
INFO | jvm 1 | 2019/11/15 08:50:01 | I [c.c.c.c.CirrusClient ] [07:50:01]: TransmissionClient-080dbbc8-32a3-41f1: Creating the MQTT Client to ssl://mqtt.server.it:8883 on thread Thread-32835
INFO | jvm 1 | 2019/11/15 08:50:02 | W [c.c.m.t.g.TargetCallback ] [07:50:02]: MQTT connection lost for TransmissionClient-080dbbc8-32a3-41f1
INFO | jvm 1 | 2019/11/15 08:50:02 | E [c.c.m.t.g.TargetCallback ] [07:50:02]: Connection lost
INFO | jvm 1 | 2019/11/15 08:50:02 | org.eclipse.paho.client.mqttv3.MqttException: Connessione persa
INFO | jvm 1 | 2019/11/15 08:50:02 | at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:164)
INFO | jvm 1 | 2019/11/15 08:50:02 | at java.lang.Thread.run(Thread.java:748)
INFO | jvm 1 | 2019/11/15 08:50:02 | Caused by: java.io.EOFException: null
INFO | jvm 1 | 2019/11/15 08:50:02 | at java.io.DataInputStream.readByte(DataInputStream.java:267)
INFO | jvm 1 | 2019/11/15 08:50:02 | at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:92)
INFO | jvm 1 | 2019/11/15 08:50:02 | at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:116)
INFO | jvm 1 | 2019/11/15 08:50:02 | ... 1 common frames omitted
INFO | jvm 1 | 2019/11/15 08:50:02 | I [c.c.c.c.CirrusClient ] [07:50:02]: TransmissionClient-080dbbc8-32a3-41f1: connect succeeded
INFO | jvm 1 | 2019/11/15 08:50:02 | I [c.c.c.c.CirrusClient ] [07:50:02]: TransmissionClient-080dbbc8-32a3-41f1: Connected to ssl://mqtt.server.it:8883
INFO | jvm 1 | 2019/11/15 08:50:02 | E [c.c.m.t.g.m.EdgeNode ] [07:50:02]: Payload failed to publish - decrementing sequence number
INFO | jvm 1 | 2019/11/15 08:50:02 | I [c.c.m.t.g.m.EdgeNode ] [07:50:02]: [ips-edge-test-oracle][TransmissionClient-080dbbc8-32a3-41f1] Connected to the MQTT Server
INFO | jvm 1 | 2019/11/15 08:50:03 | W [c.c.m.t.g.m.EdgeNode ] [07:50:03]: Failed to publish - client is not connected to primary host
INFO | jvm 1 | 2019/11/15 08:50:03 | E [c.c.m.t.g.m.EdgeNode ] [07:50:03]: Payload failed to publish - decrementing sequence number
INFO | jvm 1 | 2019/11/15 08:50:04 | I [c.c.m.t.g.TargetCallback ] [07:50:04]: Got STATE message: STATE/cieHostID :: ONLINE
INFO | jvm 1 | 2019/11/15 08:50:04 | I [c.c.m.t.g.m.EdgeNode ] [07:50:04]: Critical/Primary app is ONLINE - cancelling disconnect timer
INFO | jvm 1 | 2019/11/15 08:50:04 | I [c.c.m.t.g.m.EdgeNode ] [07:50:04]: [STATE CHANGE] Handling transition to online
INFO | jvm 1 | 2019/11/15 08:50:05 | W [c.c.m.t.g.m.EdgeNode ] [07:50:05]: Birth in progress, rescheduling publish task com.cirruslink.mqtt.transmission.gateway.model.EdgeNode$NodePublishDelayTask@109bcce
INFO | jvm 1 | 2019/11/15 08:50:05 | I [c.c.m.t.g.m.EdgeNode ] [07:50:05]: Setting metric timestamp to 1573804205400 to avoid collision for Scan_Class10s :: 127101
INFO | jvm 1 | 2019/11/15 08:50:05 | I [c.c.m.t.g.m.EdgeNode ] [07:50:05]: Setting metric timestamp to 1573804205801 to avoid collision for Scan_Class1s :: 1270942
INFO | jvm 1 | 2019/11/15 08:50:08 | I [c.c.m.t.g.h.HistoryPublisher ] [07:50:08]: Publishing 58 historical metrics to project01/ips-edge-test-oracle