MySensors Ethernet Gateway goes to OFFLINE state

[jogant]

@TimO
funny that it works for me :smiley:

[TimO]

@jogant Sadly it works fine in my devolpment and test environment, too. ;-)
If you are using a OH2 snapshot that is older than 11 days there is a chance that it still works but it's not predictable.

[jogant]

@TimO
ok, I'm using OH2 b4, that is definitely older than 11 days.

[TimO]

@pentti12 I've pushed a new version of the binding to github, please try it out and let me know if it helps.

[pentti12]

Now I got only MySensors Ethernet Gateway into ONLINE state. The Humidity and Temperature Sensors (Things) stay UNINITIALIZED state.

[TimO]

@pentti12 Have you deleted the things and newly discovered them? When the binding changes the mapdb (where discovered devices are stored) isn't updated as well. That's why I'm using the *.things files instead. If you try to remove the items errors could occure, this is another hint that this is the problem. After restart of OH2 the things should disappear.

Another way to do this is:

1. stop OH2
2. rm /var/lib/openhab2/mapdb/*
3. start OH2

But this will delete all discovered things (not only MySensors)!

[pentti12]

@pentti12 said:

Now I got only MySensors Ethernet Gateway into ONLINE state. The Humidity and Temperature Sensors (Things) stay UNINITIALIZED state.

I removed mapdb and things disappeared. I started OH2 again, but I got only MySensors Ethernet Gateway into ONLINE state after adding them manually The Humidity and Temperature Sensors (Things) stay UNINITIALIZED 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.