MySensors Ethernet Gateway goes to OFFLINE state

TimO

@pentti12 What does the log say?

Jic

@TimO I have exactly the same problem.

My event.log is:

2016-11-22 20:45:24.043 [temChannelLinkRemovedEvent] - Link 'mysensors_door_gateway_door01_armed => mysensors:door:gateway:door01:armed' has been removed.
2016-11-22 20:54:35.089 [ItemChannelLinkAddedEvent ] - Link 'door01_tripped-mysensors:door:gateway:door01:tripped' has been added.
2016-11-22 20:54:35.097 [ItemChannelLinkAddedEvent ] - Link 'door01_lastupdate-mysensors:door:gateway:door01:lastupdate' has been added.
2016-11-22 20:54:35.671 [ThingAddedEvent           ] - Thing 'mysensors:bridge-ser:gateway' has been added.
2016-11-22 20:54:35.682 [ThingAddedEvent           ] - Thing 'mysensors:door:gateway:door01' has been added.
2016-11-22 20:54:36.702 [hingStatusInfoChangedEvent] - 'mysensors:bridge-ser:gateway' changed from UNINITIALIZED to INITIALIZING
2016-11-22 20:54:36.713 [hingStatusInfoChangedEvent] - 'mysensors:door:gateway:door01' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2016-11-22 20:54:40.191 [hingStatusInfoChangedEvent] - 'mysensors:bridge-ser:gateway' changed from INITIALIZING to ONLINE
2016-11-22 20:54:40.262 [hingStatusInfoChangedEvent] - 'mysensors:door:gateway:door01' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2016-11-22 20:54:40.462 [hingStatusInfoChangedEvent] - 'mysensors:door:gateway:door01' changed from INITIALIZING to OFFLINE

And my openhab.log is:

2016-11-22 20:58:10.295 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped
2016-11-22 20:58:11.254 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;4;1;0;16;1
2016-11-22 20:58:16.264 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;255;3;0;0;23
2016-11-22 20:58:20.297 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped
2016-11-22 20:58:21.279 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;4;1;0;16;0
2016-11-22 20:58:26.295 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;255;3;0;0;23
2016-11-22 20:58:30.300 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped
2016-11-22 20:58:31.319 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;4;1;0;16;1

I have downloaded your last binding, and the last version of MySensors. Thanks

pentti12

Event log:
2016-11-22 18:57:07.915 [hingStatusInfoChangedEvent] - 'mysensors:humidity:dd0f7b9e' changed from UNINITIALIZED to INITIALIZING
2016-11-22 18:57:07.928 [hingStatusInfoChangedEvent] - 'mysensors:humidity:dd0f7b9e' changed from INITIALIZING to UNINITIALIZED (HANDLER_INITIALIZING_ERROR): org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;

And Openhba log:

2016-11-22 18:57:07.924 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'mysensors:humidity:dd0f7b9e': java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
java.util.concurrent.ExecutionException: java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
at java.util.concurrent.FutureTask.report(FutureTask.java:122)[:1.8.0_101]
at java.util.concurrent.FutureTask.get(FutureTask.java:206)[:1.8.0_101]
at org.eclipse.smarthome.core.common.SafeMethodCaller.callAsynchronous(SafeMethodCaller.java:186)[96:org.eclipse.smarthome.core:0.9.0.201611102032]
at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:79)[96:org.eclipse.smarthome.core:0.9.0.201611102032]
at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:63)[96:org.eclipse.smarthome.core:0.9.0.201611102032]
at org.eclipse.smarthome.core.thing.internal.ThingManager$9.run(ThingManager.java:761)[103:org.eclipse.smarthome.core.thing:0.9.0.201611102032]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_101]
at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_101]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_101]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_101]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_101]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_101]
at java.lang.Thread.run(Thread.java:745)[:1.8.0_101]
Caused by: java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
at org.openhab.binding.mysensors.internal.handler.MySensorsThingHandler.getBridgeHandler(MySensorsThingHandler.java:284)
at org.openhab.binding.mysensors.internal.handler.MySensorsThingHandler.initialize(MySensorsThingHandler.java:74)
at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:764)
at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:1)
at org.eclipse.smarthome.core.common.SafeMethodCaller$CallableWrapper.call(SafeMethodCaller.java:177)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_101]
... 3 more

pentti12

Update:

I started from the scratch. I installed debian MATE and OH2-offline snapshot. I put the org.openhab.binding.mysensors-2.0.0-SNAPSHOT.jar into addons folder. I started openhab2 and after a while I got MySensors Ethernet Gateway and Temperature and Humidity Sensors ONLINE state.

But still I cannot see any temperature nor humidity values from my sensors!

Here is the Karaf Log after I pressed MySensorsGateway Reset button:

08:35:40.194 [ERROR] [otocol.MySensorsNetworkSanityChecker] - Retry period expired, gateway is down. Disconneting bridge...
08:35:40.196 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Request disconnection flag setted to: true
08:35:44.213 [INFO ] [al.protocol.ip.MySensorsIpConnection] - Connection request disconnection...
08:35:44.215 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Request disconnection flag setted to: false
08:35:44.217 [INFO ] [otocol.MySensorsNetworkSanityChecker] - Network Sanity Checker thread stopped
08:35:44.223 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Disconnecting from IP bridge ...
08:35:44.227 [DEBUG] [rs.internal.protocol.MySensorsWriter] - Stopping Writer thread
08:35:44.241 [ERROR] [rs.internal.protocol.MySensorsWriter] - (java.lang.InterruptedException) on writing from serial port, message: class org.openhab.binding.mysensors.internal.protocol.ip.MySensorsIpWriter
08:35:44.244 [DEBUG] [rs.internal.protocol.MySensorsReader] - Stopping Reader thread
08:35:44.246 [ERROR] [rs.internal.protocol.MySensorsReader] - (java.lang.InterruptedException: sleep interrupted) on reading from serial port, message: class org.openhab.binding.mysensors.internal.protocol.ip.MySensorIpReader
08:35:44.261 [DEBUG] [ternal.handler.MySensorsThingHandler] - MySensors Bridge Status updated to OFFLINE for device: mysensors:humidity:a825a6a7
08:35:44.263 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Connecting to IP bridge [192.168.1.201:5003]
08:35:44.267 [DEBUG] [ternal.handler.MySensorsThingHandler] - MySensors Bridge Status updated to OFFLINE for device: mysensors:temperature:43712fbc
08:35:44.295 [WARN ] [al.protocol.ip.MySensorsIpConnection] - Skipping I_VERSION connection test, not recommended...
08:35:44.305 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:43712fbc' changed from ONLINE to OFFLINE
08:35:44.305 [DEBUG] [ternal.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:humidity:a825a6a7
08:35:44.313 [DEBUG] [ternal.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:temperature:43712fbc
08:35:44.325 [INFO ] [al.protocol.ip.MySensorsIpConnection] - Successfully connected to MySensors Bridge.
08:35:44.349 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:d18f7f4c' changed from ONLINE to OFFLINE
08:35:44.354 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:a825a6a7' changed from ONLINE to OFFLINE
08:35:44.361 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:d18f7f4c' changed from OFFLINE to ONLINE
08:35:44.373 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:a825a6a7' changed from OFFLINE to ONLINE
08:35:44.392 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:43712fbc' changed from OFFLINE to ONLINE
08:35:54.341 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped

So what to do next?

Jic

I think that your sensor will be online also when your node are switched off. I am working with SerialGateway not with ethernet, so I am not sure.

Can you send us your .things and your .items? Have you write or automatically discover them?

And output from your node?
Keep the sensor connected to your computer and enable the Serial Monitor in the Arduino IDE. Tools>Serial Monitor.

pentti12

Openhab2 detected things automatically, I don't use my own. Here is the sensor node monitor listing:

B´AÚXStarting sensor (RNNNA-, 2.0.0)
TSM:INIT
TSM:RADIO:OK
TSP:ASSIGNID:OK (ID=9)
TSM:FPAR
TSP:MSG:SEND 9-9-255-255 s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=bc:
TSM:FPAR
TSP:MSG:SEND 9-9-255-255 s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=bc:
TSM:FPAR
TSP:MSG:SEND 9-9-255-255 s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=bc:
TSP:MSG:READ 0-0-9 s=255,c=3,t=8,pt=1,l=1,sg=0:0
TSP:MSG:FPAR RES (ID=0, dist=0)
TSP:MSG:PAR OK (ID=0, dist=1)
TSM:FPAR:OK
TSM:ID
TSM:CHKID:OK (ID=9)
TSM:UPL
TSP:PING:SEND (dest=0)
TSP:MSG:SEND 9-9-0-0 s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=ok:1
TSP:MSG:READ 0-0-9 s=255,c=3,t=25,pt=1,l=1,sg=0:1
TSP:MSG:PONG RECV (hops=1)
TSP:CHKUPL:OK
TSM:UPL:OK
TSM:READY
TSP:MSG:SEND 9-9-0-0 s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=ok:0100
!TSP:MSG:SEND 9-9-0-0 s=255,c=0,t=17,pt=0,l=5,sg=0,ft=0,st=fail:2.0.0
TSP:MSG:SEND 9-9-0-0 s=255,c=3,t=6,pt=1,l=1,sg=0,ft=1,st=ok:0
TSP:MSG:SEND 9-9-0-0 s=255,c=3,t=11,pt=0,l=10,sg=0,ft=0,st=ok:My Bedroom
TSP:MSG:SEND 9-9-0-0 s=255,c=3,t=12,pt=0,l=3,sg=0,ft=0,st=ok:1.0
TSP:MSG:SEND 9-9-0-0 s=10,c=0,t=7,pt=0,l=0,sg=0,ft=0,st=ok:
TSP:MSG:SEND 9-9-0-0 s=11,c=0,t=6,pt=0,l=0,sg=0,ft=0,st=ok:
TSP:MSG:SEND 9-9-0-0 s=1,c=0,t=3,pt=0,l=0,sg=0,ft=0,st=ok:
Request registration...
TSP:MSG:SEND 9-9-0-0 s=255,c=3,t=26,pt=1,l=1,sg=0,ft=0,st=ok:2
TSP:MSG:READ 0-0-9 s=255,c=3,t=27,pt=1,l=1,sg=0:1
Node registration=1
Init complete, id=9, parent=0, distance=1, registration=1
!TSP:MSG:SEND 9-9-0-0 s=11,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=fail:21.8
T: 21.80
TSP:MSG:SEND 9-9-0-0 s=10,c=1,t=1,pt=7,l=5,sg=0,ft=1,st=ok:24.2
H: 24.20
TSP:MSG:SEND 9-9-0-0 s=10,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=ok:24.6
H: 24.60
TSP:MSG:SEND 9-9-0-0 s=11,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=ok:21.9
T: 21.90
TSP:MSG:SEND 9-9-0-0 s=10,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=ok:25.4
H: 25.40
TSP:MSG:SEND 9-9-0-0 s=11,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=ok:22.0
T: 22.00
!TSP:MSG:SEND 9-9-0-0 s=10,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=fail:25.3
H: 25.30

It is operating normally and it send both temperature and humidity values.

jogant

@pentti12
everytime you see a SEND message in the node debug log, you should also see a READ message in your gateway. And than you should also see a SEND message to the controller and a message coming in in your openhab binding.
So would you mind start logging all three stages of the message at the same time as I showed several posts before? If you don't see incoming messages in the binding log there is something wrong with your ethernet connection. Otherwise it's only guessing whats wrong.

pentti12

Before I set up full logging environment, I wonder why I cannot get connection when I enter following command on the terminal window of OH2 server (Pine64):

telnet localhost 5003

unable to connect to remote host

Jic

I didn't see in your node log an ip assignament. Are your sketch configured to Ethernet Gateway?

I think you should use some parameters:

// Enable MY_IP_ADDRESS here if you want a static ip address (no DHCP)
#define MY_IP_ADDRESS 192,168,178,87

// If using static ip you need to define Gateway and Subnet address as well
#define MY_IP_GATEWAY_ADDRESS 192,168,178,1
#define MY_IP_SUBNET_ADDRESS 255,255,255,0

// MQTT broker ip address or url. Define one or the other.
//#define MY_CONTROLLER_URL_ADDRESS "m20.cloudmqtt.com"
#define MY_CONTROLLER_IP_ADDRESS 192, 168, 178, 68

I am not sure how to use Ethernet Gateway, perhaps this example help you

https://github.com/mysensors/MySensors/blob/development/examples/GatewayW5100MQTTClient/GatewayW5100MQTTClient.ino

pentti12

I am not using the MQTT gateway, I am using standard GatewayW5100.ino, which has correct IP-settings:

#define MY_IP_ADDRESS 192,168,1,201 // If this is disabled, DHCP is used to retrieve address
// Renewal period if using DHCP
//#define MY_IP_RENEWAL_INTERVAL 60000
// The port to keep open on node server mode / or port to contact in client mode
#define MY_PORT 5003

// Controller ip address. Enables client mode (default is "server" mode).
// Also enable this if MY_USE_UDP is used and you want sensor data sent somewhere.
#define MY_CONTROLLER_IP_ADDRESS 192, 168, 1, 185

// The MAC address can be anything you want but should be unique on your network.
// Newer boards have a MAC address printed on the underside of the PCB, which you can (optionally) use.
// Note that most of the Ardunio examples use "DEAD BEEF FEED" for the MAC address.
#define MY_MAC_ADDRESS 0xDE, 0xAD, 0xBE, 0xEF, 0xFE, 0xED

And MySensors Ethernet Gateway is detected by Advanced IP Scanner and I can ping it and so on..

jogant

@pentti12

the gateway is the server. You should succeed with telnet <ip_of_mysensors_gw> 5003

Jic

@pentti12

Can you start manually your gateway with -d option to see the log?

You should see there if your gateway its receiving messages. If you are receiving message then, the problem should be in the OH2

jogant

@Jic
what do you mean with "start gateway manually -d option"? My gateway is a standalone device, ethernet connector on one side, nrf24 on the other, arduino in the middle connecting the other two parts.

pentti12

Wait a minute! I think that from the original GatewayW5100 sketch there were missing the IP Gateway and subnet addresses!

So I will recompile sketch and test again.

jogant

@pentti12

slowly my image gets complete. Online/Offline status is resolved by OH2 binding via polling during initialization. Value updates are sent spontaneous by the gateway via callback, afair. So the gateway needs to know the address and mask of the controller to send values.

pentti12

No help after new sketch loaded into GatewayW5100. OH2 log informs the same result:

14:49:09.484 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped

I will take a break - to be continued on next day...

jogant

@pentti12
"connection skipped" message occurs frequently, usually every 10 seconds. That is normal and ok. But you should see messages from the gateway everytime the sensor nodes send updates, except the value is the same as before.

pentti12

I connected my laptop into Ethernet Gateway. Here is a sample of monitoring listing:

0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.4
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=11,c=1,t=0,pt=7,l=5,sg=0:21.9
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.5
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=11,c=1,t=0,pt=7,l=5,sg=0:22.0
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=11,c=1,t=0,pt=7,l=5,sg=0:21.9
0;255;3;0;9;TSP:SANCHK:OK
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.6
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.4
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.5
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.4
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.5
0;255;3;0;9;TSP:SANCHK:OK
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.5
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.6
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.7
0;255;3;0;9;TSP:SANCHK:OK
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.7
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=11,c=1,t=0,pt=7,l=5,sg=0:21.8
0;255;3;0;9;TSP:MSG:READ 9-9-0 s=0,c=1,t=0,pt=7,l=5,sg=0:30.8

So I think that this is OK, when I have only one sensor node alive.

But still, how I shall modify my Arduino Gateway sketch in order to get OH2 server show temperature and humidity values?

Jic

@pentti12

Could be permission problem?
Can you connect your gateway on rpi and execute

ls -l /dev/tty*

I think you are using a ethernet gateway skecth, normaly it's should be mounted in /dev/ttyACM0

Have you already execute

sudo usermod -a -G dialout openhab

Be sure that you have the las binding
https://github.com/tobof/openhab2-addons/blob/MySensors/addons/binding/org.openhab.binding.mysensors/target/org.openhab.binding.mysensors-2.0.0-SNAPSHOT.jar
and your openhab2 is updated.

pentti12

I am running the Ethernet GatewayW5100 software on Arduino UNO. I don't have rpi, I have installed OH2 on Pine64. It is running debian Jessie and it is uptodate. Also the latest mysensors binding is in use. So what to next?

MySensors Ethernet Gateway goes to OFFLINE state

20

11.7k

11.2k

113.0k