Why are my nodes either all Online or all Offline?



  • As far as I can remember, my OpenHAB used to show the online status of each individual MySensor node when I first set it up about a year ago. Several upgrades (and a lot of tinkering) has passed and now I find that all nodes are set to Online status as soon as the gateway is connected to OpenHAB.

    Shouldn't the nodes go online/offline one by one depending on their individual status in the MySensors network?
    What am I missing here?

    I use OpenHAB is v 2.4.0 (release)
    The Gateway is a ESP8266 on ethernet on MySensors v 2.3.1 (all nodes are 2.3.1 as well).

    I've tried changing the network sanity check and heartbeat settings for the gateway with no success. This is what I have now:

    //mysensors.things
    
    Bridge mysensors:bridge-eth:MYS_gw1 "MySensors Ethernet Gateway" [ 
        ipAddress="192.168.1.6", 
        tcpPort=5003,
        sendDelay=200,
        // periodically ensure that gateway is up and running
        networkSanCheckEnabled=true,                
        // check the gateway every x minutes
        networkSanCheckInterval=1, 
        // send heartbeat request to nodes
        networkSanCheckSendHeartbeat=true,
        // disconnect nodes after x failed heartbeat requests
        networkSanCheckSendHeartbeatFailAttempts=2 
    ]
    {
    // Node 10 --- EasyPCB Testrigg --- DHT-22 med batteri*/
    Thing temperature MYS_Node10_Temp 	"MYS 10-1 Temp" @ "Lab" [ nodeId=10, childId=1 ]
    Thing humidity    MYS_Node10_Humid 	"MYS 10-2 Fukt" @ "Lab" [ nodeId=10, childId=2 ]
    Thing multimeter  MYS_Node10_Maint 	"MYS 10-201 Underhåll" @ "Lab" [ nodeId=10, childId=201 ]
    }
    // More sensors defined
    

    Below is the log from when I have switched off the gateway and waited until it's offline in OpenHAB.
    Why is the status set to "Online" for node 21 (t=22:43:38.494) and node 22 (t=22:43:38.495)

    // OpenHAB log - mysensors binding set to DEBUG level
    
    2019-04-24 22:43:37.301 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Connecting to IP bridge [192.168.1.6:5003]
    2019-04-24 22:43:38.400 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 0;0;3;0;2;
    2019-04-24 22:43:38.420 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;14;Gateway startup complete.
    2019-04-24 22:43:38.424 [INFO ] [rs.internal.gateway.MySensorsGateway] - Node 0 available again!
    2019-04-24 22:43:38.428 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;0;0;18;2.3.1
    2019-04-24 22:43:38.431 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Presentation Message received
    2019-04-24 22:43:38.434 [WARN ] [rs.internal.gateway.MySensorsGateway] - Presented child is alredy present in gateway
    2019-04-24 22:43:38.437 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;2;2.3.1
    2019-04-24 22:43:38.440 [DEBUG] [rsAbstractConnection$MySensorsReader] - Good,Gateway is up and running! (Ver:2.3.1)
    2019-04-24 22:43:38.454 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:multimeter:MY
    S_gw1:MYS_Node10_Maint
    2019-04-24 22:43:38.460 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:temperature:M
    YS_gw1:MYS_Node12_Temp
    2019-04-24 22:43:38.465 [DEBUG] [ensors.factory.MySensorsCacheFactory] - Writing on cache given_ids, content: [0,21,22,10,11,12]
    2019-04-24 22:43:38.465 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:humidity:MYS_
    gw1:MYS_Node10_Humid
    2019-04-24 22:43:38.462 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:temperature:M
    YS_gw1:MYS_Node10_Temp
    2019-04-24 22:43:38.471 [INFO ] [al.protocol.ip.MySensorsIpConnection] - Successfully connected to MySensors Bridge.
    2019-04-24 22:43:38.473 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 10-2 not registered yet, registering...
    2019-04-24 22:43:38.471 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 12-1 not registered yet, registering...
    2019-04-24 22:43:38.473 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 10-201 not registered yet, registering...
    2019-04-24 22:43:38.475 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:baro:MYS_gw1:
    MYS_Node12_Press
    2019-04-24 22:43:38.482 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 12-2 not registered yet, registering...
    2019-04-24 22:43:38.491 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 10-1 not registered yet, registering...
    2019-04-24 22:43:38.492 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:multimeter:MY
    S_gw1:MYS_Node12_Maint
    2019-04-24 22:43:38.494 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:waterMeter:MY
    S_gw1:MYS_Node21_Water1
    2019-04-24 22:43:38.484 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:baro:MYS_gw1:
    MYS_Node12_Baro
    2019-04-24 22:43:38.496 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 12-201 not registered yet, registering...
    2019-04-24 22:43:38.501 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 21-1 not registered yet, registering...
    2019-04-24 22:43:38.495 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:power:MYS_gw1
    :MYS_Node22_Energy1
    2019-04-24 22:43:38.506 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 12-3 not registered yet, registering...
    2019-04-24 22:43:38.517 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 22-1 not registered yet, registering...
    2019-04-24 22:44:07.274 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 10;255;3;0;32;500
    2019-04-24 22:44:38.444 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 0;0;3;0;2;
    2019-04-24 22:44:38.548 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;2;2.3.1
    2019-04-24 22:44:41.444 [DEBUG] [ateway.MySensorsNetworkSanityChecker] - Network sanity check: PASSED
    2019-04-24 22:44:41.446 [DEBUG] [ateway.MySensorsNetworkSanityChecker] - Sending I_HEARTBEAT_REQUESTs
    2019-04-24 22:44:41.449 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 0;255;3;0;18;
    2019-04-24 22:44:41.517 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;22;68713
    2019-04-24 22:44:41.651 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 21;255;3;0;18;
    2019-04-24 22:44:41.855 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 22;255;3;0;18;
    2019-04-24 22:44:42.059 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 10;255;3;0;18;
    2019-04-24 22:44:42.263 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 11;255;3;0;18;
    2019-04-24 22:44:42.467 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 12;255;3;0;18;
    2019-04-24 22:45:31.012 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 12;255;3;0;33;110000
    2019-04-24 22:45:31.046 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 12;1;1;0;0;24.98
    2019-04-24 22:45:31.052 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 12 found in gateway
    2019-04-24 22:45:31.055 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 1 found in node 12
    2019-04-24 22:45:31.058 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: temp(V_TEMP) value to: 24.98
    2019-04-24 22:45:31.077 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 12/1 to 2019-04-24T22:45:31.000+0200
    2019-04-24 22:45:31.081 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 12;2;1;0;4;1017.18
    2019-04-24 22:45:31.093 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 12 found in gateway
    2019-04-24 22:45:31.099 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 12
    2019-04-24 22:45:31.105 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: pressure(V_PRESSURE) value to: 1017.18
    2019-04-24 22:45:31.114 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 12/2 to 2019-04-24T22:45:31.000+0200
    2019-04-24 22:45:31.118 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 12;3;1;0;5;stable
    2019-04-24 22:45:31.124 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 12 found in gateway
    2019-04-24 22:45:31.126 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 3 found in node 12
    2019-04-24 22:45:31.130 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: baro(V_FORECAST) value to: stable
    2019-04-24 22:45:31.138 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 12/3 to 2019-04-24T22:45:31.000+0200
    2019-04-24 22:45:31.151 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 12;255;3;0;32;500
    2019-04-24 22:45:44.451 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 0;0;3;0;2;
    2019-04-24 22:45:44.482 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;2;2.3.1
    2019-04-24 22:45:47.451 [DEBUG] [ateway.MySensorsNetworkSanityChecker] - Network sanity check: PASSED
    2019-04-24 22:45:47.453 [DEBUG] [ateway.MySensorsNetworkSanityChecker] - Sending I_HEARTBEAT_REQUESTs
    2019-04-24 22:45:47.457 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 0;255;3;0;18;
    2019-04-24 22:45:47.487 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;22;134686
    2019-04-24 22:45:47.660 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 21;255;3;0;18;
    2019-04-24 22:45:47.865 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 22;255;3;0;18;
    2019-04-24 22:45:48.069 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 10;255;3;0;18;
    2019-04-24 22:45:48.272 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 11;255;3;0;18;
    2019-04-24 22:45:48.476 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 12;255;3;0;18;
    2019-04-24 22:45:54.001 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 10;255;3;0;33;110074
    2019-04-24 22:45:57.956 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 10;2;1;0;1;29.90
    2019-04-24 22:45:57.964 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 10 found in gateway
    2019-04-24 22:45:57.968 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 10
    2019-04-24 22:45:57.972 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: hum(V_HUM) value to: 29.90
    2019-04-24 22:45:57.985 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 10/2 to 2019-04-24T22:45:57.000+0200
    2019-04-24 22:45:57.989 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 10;255;3;0;32;500
    
    


  • @fredswed Hi, did you ever get this sorted out? I am facing the same issue and cant find out whats going on.


 

275
Online

8.7k
Users

9.5k
Topics

99.9k
Posts