openHAB 2.0 binding
-
Does the ack signaling work in the binding? I have a sensor that will request an ack from the gateway. Does anyone know if this is working?
-
@Denke yes acknowledge is actually implemented in binding
-
@andreacioni
Hi I have trouble finding out how to get my node to request ack, do you know how. I think i have figured out how to handle incoming message to check that the ack is received ant that is through message.isAck().But how do i get the node to send a request for ack?
-
@Denke
You look and you look, then you ask someone how to do.Then you look again and you find it:)
, true after the set message in send did the trick.
gw.send(msgSetpointHeat.set(setTemp,1),true);
-
ALMOST WORKING
Hi,
Ive been trying for ages to get a stable OpenHab + MySensors setup going. Your new binding is exactly what I've been hoping for and I'm so close to having it working, but can't see what I am doing wrong???I've installed OpenHab2 plus your MySensors binding. I tried to follow your setup guide / otes, but I suspect a lot has changed since you wrote it.
What Works
-
OpenHab runs without any errors in the log file {Yay!}
-
My sensor data appears in the OpenHab log - ok.
-
In OpenHab, my individual sensors are there and 'linked' to sensor channel.
-
The date/time in browser from the NTP binding updates as expected.
Whats Wrong
-
The items (in demo.items) appear in the browser, but no data values?
-
I can see data from sensor changes in OpenHab log, but still NOT displayed?
Can anybody post an items, things and sitemap example file, or spot an error in mine?
My demo.items:
Number mywatts2 "Watts 2 [%.1f W]" <temperature> {channel="mysensors:power:503a021b:Power_21_6:watt"} Number mywatts1 "Watts 1 [%.1f W]" <temperature> {channel="mysensors:power:503a021b:Power_21_2:watt" Number myhum01 "hum 1 [%.1f %RH]" <temperature> {channel="mysensors:humidity:7fbda972:hum"} Number mytemp01 "Temp [%.1f C]" <temperature> {channel="mysensors:temperature:503a021b:Temperature_22_3:temp"My demo.things:
yahooweather:weather:Dawesville [ location=28584781 ] astro:sun:home [ geolocation="-32.634619,115.638888", interval=60 ] ntp:ntp:demo [ hostname="nl.pool.ntp.org", refreshInterval=60, refreshNtp=30 ]Extract from my demo.sitemap:
sitemap demo label="Main Menu" { Frame { Text item=mywatts1 Text item=mywatts2 Text item=myhum01 Text item=mytemp01 } }Initial startup from my openhab.log (apologies for the length)
2016-05-14 09:49:21.749 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTING - org.openhab.binding.mysensors 2016-05-14 09:49:21.760 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTED - org.openhab.binding.mysensors 2016-05-14 09:49:21.770 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.mysensors.internal.MySensorsHandlerFactory, component.id=2, service.id=104, service.bundleid=10, service.scope=bundle} - org.openhab.binding.mysensors 2016-05-14 09:49:32.367 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'ipp:printer:Brother_MFC_8510DN' has been added. 2016-05-14 09:49:32.383 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=197, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.398 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:bridge-eth:503a021b' has been added. 2016-05-14 09:49:32.410 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:humidity:503a021b:Humidity_22_2' has been added. 2016-05-14 09:49:32.411 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:bridge-eth, thing.id=mysensors:bridge-eth:503a021b, service.id=198, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.436 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:humidity, thing.id=mysensors:humidity:503a021b:Humidity_22_2, service.id=199, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.438 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:humidity:7fbda972' has been added. 2016-05-14 09:49:32.441 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:power:40868d6c' has been added. 2016-05-14 09:49:32.444 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:power:503a021b:Power_21_1' has been added. 2016-05-14 09:49:32.447 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:power:503a021b:Power_21_2' has been added. 2016-05-14 09:49:32.448 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:power:503a021b:Power_21_3' has been added. 2016-05-14 09:49:32.452 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:power:503a021b:Power_21_4' has been added. 2016-05-14 09:49:32.455 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:power:503a021b:Power_21_5' has been added. 2016-05-14 09:49:32.456 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:power:503a021b:Power_21_6' has been added. 2016-05-14 09:49:32.458 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:temperature:503a021b:Temperature_22_3' has been added. 2016-05-14 09:49:32.459 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:temperature:522d430d' has been added. 2016-05-14 09:49:32.460 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'ntp:ntp:local' has been added. 2016-05-14 09:49:32.477 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:humidity, thing.id=mysensors:humidity:7fbda972, service.id=200, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.497 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:power, thing.id=mysensors:power:40868d6c, service.id=201, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.500 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:power, thing.id=mysensors:power:503a021b:Power_21_1, service.id=202, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.502 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:power, thing.id=mysensors:power:503a021b:Power_21_2, service.id=203, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.505 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:power, thing.id=mysensors:power:503a021b:Power_21_3, service.id=204, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.511 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:power, thing.id=mysensors:power:503a021b:Power_21_4, service.id=205, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.514 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:power, thing.id=mysensors:power:503a021b:Power_21_5, service.id=206, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.520 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:power, thing.id=mysensors:power:503a021b:Power_21_6, service.id=207, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.523 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:temperature, thing.id=mysensors:temperature:503a021b:Temperature_22_3, service.id=208, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.525 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:temperature, thing.id=mysensors:temperature:522d430d, service.id=209, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 2016-05-14 09:49:32.673 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:503a021b' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:32.699 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:503a021b:Humidity_22_2' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:32.712 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - Initialization of the MySensors Bridge 2016-05-14 09:49:32.714 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:7fbda972' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:32.756 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - Connecting to bridge ... 2016-05-14 09:49:32.899 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:40868d6c' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:32.901 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - Connection to ethernet gateway successful! 2016-05-14 09:49:33.053 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:503a021b:Humidity_22_2' changed from INITIALIZING to ONLINE 2016-05-14 09:49:33.081 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:7fbda972' changed from INITIALIZING to ONLINE 2016-05-14 09:49:33.121 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:503a021b' changed from INITIALIZING to ONLINE 2016-05-14 09:49:33.123 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_2' changed from INITIALIZING to ONLINE 2016-05-14 09:49:33.178 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:503a021b:Temperature_22_3' changed from INITIALIZING to ONLINE 2016-05-14 09:49:33.106 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 0;0;3;0;14;Gateway startup complete. 2016-05-14 09:49:33.200 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 0;0;3;0;2;1.5.1 2016-05-14 09:49:33.203 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_1' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:33.205 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_2' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:33.213 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_3' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:33.217 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_4' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:33.235 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_5' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:33.237 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_6' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:33.239 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:503a021b:Temperature_22_3' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:33.240 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:522d430d' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:33.242 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'CurrentDate-ntp:ntp:local:dateTime' has been added. 2016-05-14 09:49:33.254 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'hum01-mysensors:humidity:503a021b:Humidity_22_2:hum' has been added. 2016-05-14 09:49:33.255 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mywatts1-mysensors:power:503a021b:Power_21_2:watt' has been added. 2016-05-14 09:49:33.256 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mywatts1-mysensors:power:503a021b:Power_21_4:watt' has been added. 2016-05-14 09:49:33.263 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mywatts2-mysensors:power:503a021b:Power_21_5:watt' has been added. 2016-05-14 09:49:33.264 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'temp01-mysensors:power:503a021b:Power_21_5:watt' has been added. 2016-05-14 09:49:33.268 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'watts1-mysensors:power:503a021b:Power_21_5:watt' has been added. 2016-05-14 09:49:33.304 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'watts1-mysensors:power:503a021b:Power_21_6:watt' has been added. 2016-05-14 09:49:33.305 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'watts2-mysensors:power:503a021b:Power_21_2:watt' has been added. 2016-05-14 09:49:33.534 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'ipp_printer_Brother_MFC_8510DN-ipp:printer:Brother_MFC_8510DN' has been added. 2016-05-14 09:49:33.593 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:49:33.884 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:49:34.272 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_1' changed from INITIALIZING to ONLINE 2016-05-14 09:49:34.274 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_bridge_eth_503a021b-mysensors:bridge-eth:503a021b' has been added. 2016-05-14 09:49:34.275 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_humidity_503a021b_Humidity_22_2-mysensors:humidity:503a021b:Humidity_22_2' has been added. 2016-05-14 09:49:34.277 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_humidity_7fbda972-mysensors:humidity:7fbda972' has been added. 2016-05-14 09:49:34.278 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_power_40868d6c-mysensors:power:40868d6c' has been added. 2016-05-14 09:49:34.278 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_power_503a021b_Power_21_1-mysensors:power:503a021b:Power_21_1' has been added. 2016-05-14 09:49:34.279 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_power_503a021b_Power_21_2-mysensors:power:503a021b:Power_21_2' has been added. 2016-05-14 09:49:34.279 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_power_503a021b_Power_21_3-mysensors:power:503a021b:Power_21_3' has been added. 2016-05-14 09:49:34.280 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_power_503a021b_Power_21_4-mysensors:power:503a021b:Power_21_4' has been added. 2016-05-14 09:49:34.280 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_power_503a021b_Power_21_5-mysensors:power:503a021b:Power_21_5' has been added. 2016-05-14 09:49:34.281 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_power_503a021b_Power_21_6-mysensors:power:503a021b:Power_21_6' has been added. 2016-05-14 09:49:34.284 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_temperature_503a021b_Temperature_22_3-mysensors:temperature:503a021b:Temperature_22_3' has been added. 2016-05-14 09:49:34.295 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_temperature_522d430d-mysensors:temperature:522d430d' has been added. 2016-05-14 09:49:34.347 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'ntp_ntp_local-ntp:ntp:local' has been added. 2016-05-14 09:49:34.557 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'ipp_printer_Brother_MFC_8510DN' has been added. 2016-05-14 09:49:34.560 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_bridge_eth_503a021b' has been added. 2016-05-14 09:49:34.576 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_4' changed from INITIALIZING to ONLINE 2016-05-14 09:49:34.581 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_humidity_503a021b_Humidity_22_2' has been added. 2016-05-14 09:49:34.584 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_humidity_7fbda972' has been added. 2016-05-14 09:49:34.589 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_power_40868d6c' has been added. 2016-05-14 09:49:34.589 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_power_503a021b_Power_21_1' has been added. 2016-05-14 09:49:34.591 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_power_503a021b_Power_21_2' has been added. 2016-05-14 09:49:34.592 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_power_503a021b_Power_21_3' has been added. 2016-05-14 09:49:34.593 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_power_503a021b_Power_21_4' has been added. 2016-05-14 09:49:34.594 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_power_503a021b_Power_21_5' has been added. 2016-05-14 09:49:34.596 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:522d430d' changed from INITIALIZING to ONLINE 2016-05-14 09:49:34.597 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_power_503a021b_Power_21_6' has been added. 2016-05-14 09:49:34.597 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_temperature_503a021b_Temperature_22_3' has been added. 2016-05-14 09:49:34.599 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:40868d6c' changed from INITIALIZING to ONLINE 2016-05-14 09:49:34.880 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_temperature_522d430d' has been added. 2016-05-14 09:49:35.059 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'ntp_ntp_local' has been added. 2016-05-14 09:49:35.093 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_5' changed from INITIALIZING to ONLINE 2016-05-14 09:49:35.099 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_6' changed from INITIALIZING to ONLINE 2016-05-14 09:49:35.190 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:503a021b:Power_21_3' changed from INITIALIZING to ONLINE 2016-05-14 09:49:42.498 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:49:43.626 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;174 2016-05-14 09:49:43.972 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2076 2016-05-14 09:49:44.806 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /ui 2016-05-14 09:49:45.008 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at /start 2016-05-14 09:49:45.503 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'ntp:ntp:local' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:45.976 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'ntp:ntp:local' changed from INITIALIZING to ONLINE 2016-05-14 09:49:46.283 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'ipp:printer:Brother_MFC_8510DN' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:46.285 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'ipp:printer:Brother_MFC_8510DN' changed from INITIALIZING to OFFLINE 2016-05-14 09:49:46.979 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 2016-05-14 09:49:47.057 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app 2016-05-14 09:49:47.812 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'demo.sitemap' 2016-05-14 09:49:48.239 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'demo.things' 2016-05-14 09:49:48.414 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'logging.persist' 2016-05-14 09:49:48.425 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'yahooweather:weather:Dawesville' has been added. 2016-05-14 09:49:48.726 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'rrd4j.persist' 2016-05-14 09:49:48.749 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'demo.script' 2016-05-14 09:49:48.983 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'yahooweather:weather:Dawesville' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:48.984 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'yahooweather:weather:Dawesville' changed from INITIALIZING to ONLINE 2016-05-14 09:49:48.985 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'astro:sun:home' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:49.390 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'astro:sun:home' has been added. 2016-05-14 09:49:49.473 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'demo.items' 2016-05-14 09:49:49.566 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'ntp:ntp:demo' changed from UNINITIALIZED to INITIALIZING 2016-05-14 09:49:49.737 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'ntp:ntp:demo' has been added. 2016-05-14 09:49:49.742 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mywatts2-mysensors:power:503a021b:Power_21_6:watt' has been added. 2016-05-14 09:49:49.743 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mywatts1-mysensors:power:503a021b:Power_21_2:watt' has been added. 2016-05-14 09:49:49.743 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'myhum01-mysensors:humidity:7fbda972:hum' has been added. 2016-05-14 09:49:49.744 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mytemp01-mysensors:temperature:503a021b:Temperature_22_3:temp' has been added. 2016-05-14 09:49:50.090 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'astro:sun:home' changed from INITIALIZING to ONLINE 2016-05-14 09:49:50.608 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'ntp:ntp:demo' changed from INITIALIZING to ONLINE 2016-05-14 09:49:53.619 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;70 2016-05-14 09:49:53.853 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1737 2016-05-14 09:49:54.166 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'ipp:printer:Brother_MFC_8510DN' changed from OFFLINE to ONLINE 2016-05-14 09:50:02.663 [WARN ] [stence.jpa.internal.JpaConfiguration] - Connection url is required in openhab.cfg! 2016-05-14 09:50:02.663 [WARN ] [stence.jpa.internal.JpaConfiguration] - Empty connection url in openhab.cfg! 2016-05-14 09:50:02.663 [WARN ] [stence.jpa.internal.JpaConfiguration] - Driver class is required in openhab.cfg! 2016-05-14 09:50:02.666 [WARN ] [stence.jpa.internal.JpaConfiguration] - Empty driver class in openhab.cfg! 2016-05-14 09:50:02.666 [INFO ] [stence.jpa.internal.JpaConfiguration] - user was not specified! 2016-05-14 09:50:02.673 [INFO ] [stence.jpa.internal.JpaConfiguration] - password was not specified! 2016-05-14 09:50:02.673 [INFO ] [stence.jpa.internal.JpaConfiguration] - syncmappings was not specified! 2016-05-14 09:50:02.724 [INFO ] [core.karaf.internal.FeatureInstaller] - Installed 'openhab-persistence-jpa' 2016-05-14 09:50:03.283 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;0;1;0;37;0 2016-05-14 09:50:03.591 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:50:03.856 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:50:08.636 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:50:13.592 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;167 2016-05-14 09:50:13.859 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2095 2016-05-14 09:50:23.600 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;71 2016-05-14 09:50:23.861 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1744 2016-05-14 09:50:33.595 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:50:33.855 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:50:34.786 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:50:43.593 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;170 2016-05-14 09:50:43.862 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2113 2016-05-14 09:50:53.596 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;69 2016-05-14 09:50:53.860 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1751 2016-05-14 09:51:00.926 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;12 2016-05-14 09:51:03.595 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:51:03.860 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:51:09.380 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;1;1;0;37;17 2016-05-14 09:51:13.594 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;170 2016-05-14 09:51:13.855 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2131 2016-05-14 09:51:23.597 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;71 2016-05-14 09:51:23.862 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1758 2016-05-14 09:51:29.115 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:51:31.920 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;255;0;0;17;1.5.1 2016-05-14 09:51:32.131 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;255;3;0;6;0 2016-05-14 09:51:32.520 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;255;0;0;17;1.5.1 2016-05-14 09:51:32.732 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;255;3;0;6;0 2016-05-14 09:51:33.958 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;255;3;0;11;Lounge Environs 2016-05-14 09:51:34.219 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;255;3;0;12;1.1 2016-05-14 09:51:34.478 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;2;0;0;7; 2016-05-14 09:51:34.739 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;3;0;0;6; 2016-05-14 09:51:35.000 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;1;1;0;43;2.5 ppm 2016-05-14 09:51:35.264 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;255;3;0;11;Energy Meter 2016-05-14 09:51:35.525 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;0;0;13; 2016-05-14 09:51:35.787 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;0;0;13; 2016-05-14 09:51:36.049 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;43;KwHours 2016-05-14 09:51:36.313 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;43;KwHours 2016-05-14 09:51:36.570 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;43;Watts 2016-05-14 09:51:36.831 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;3;1;0;0;24.1 2016-05-14 09:51:55.263 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:51:55.833 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;69 2016-05-14 09:51:56.094 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1698 2016-05-14 09:52:05.837 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:52:06.104 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:52:15.832 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;171 2016-05-14 09:52:16.093 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.1998 2016-05-14 09:52:21.403 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:52:25.833 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;68 2016-05-14 09:52:26.099 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1704 2016-05-14 09:52:35.837 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:52:36.102 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:52:37.110 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;0;1;0;37;0 2016-05-14 09:52:45.833 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;167 2016-05-14 09:52:46.095 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2016 2016-05-14 09:52:47.542 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:52:55.838 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;70 2016-05-14 09:52:56.108 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1711 2016-05-14 09:53:05.835 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:53:06.105 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:53:13.694 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:53:15.836 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;174 2016-05-14 09:53:16.097 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2034 2016-05-14 09:53:25.839 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;70 2016-05-14 09:53:26.099 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1718 2016-05-14 09:53:35.835 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:53:36.096 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:53:39.845 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:53:45.835 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;172 2016-05-14 09:53:46.095 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2053 2016-05-14 09:53:55.836 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;68 2016-05-14 09:53:56.098 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1724 2016-05-14 09:54:05.837 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:54:06.100 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:54:08.040 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:54:14.044 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;0;1;0;37;0 2016-05-14 09:54:15.840 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;174 2016-05-14 09:54:16.102 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2071 2016-05-14 09:54:25.839 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;68 2016-05-14 09:54:26.100 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1729 2016-05-14 09:54:34.176 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:54:35.836 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:54:36.096 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:54:45.843 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;173 2016-05-14 09:54:46.105 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2089 2016-05-14 09:54:49.812 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;2;1;0;1;55.3 2016-05-14 09:54:55.837 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;68 2016-05-14 09:54:56.102 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1734 2016-05-14 09:55:00.313 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:55:05.851 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:55:06.117 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:55:15.837 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;166 2016-05-14 09:55:16.107 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2108 2016-05-14 09:55:25.836 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;68 2016-05-14 09:55:26.108 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1739 2016-05-14 09:55:26.463 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:55:35.838 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:55:36.098 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:55:45.838 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;170 2016-05-14 09:55:46.106 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2126 2016-05-14 09:55:50.716 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 22;0;1;0;37;0 2016-05-14 09:55:52.609 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 50;0;1;0;37;11 2016-05-14 09:55:55.839 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;1;1;0;17;67 2016-05-14 09:55:56.101 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;4;1;0;18;93.1743 2016-05-14 09:56:05.838 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;2;1;0;17;0 2016-05-14 09:56:06.098 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;5;1;0;18;32.4534 2016-05-14 09:56:15.839 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;3;1;0;17;168 2016-05-14 09:56:16.099 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - 21;6;1;0;18;345.2144All suggestions appreciated...
Thanks,
Paul
-
-
The things are not linked to items, therefore the items do not update. You have configured the bridge via PaperUi, correct? This should work, but I haven't tested it. Could you try to configure the gateway in the things file instead?
Have you seen the examples in the github wiki?
I'm currently on my mobile, so I can't say more at the moment.
-
Hi Tim,
Thanks for replying so promptly
, trying to get this going over the weekend.From what I can see, the fields are linked. I base this on the fact that the log file shows 'link added' and 'link removed' as I play with the 'demo.things' file.
Also, I get the same messages in the log etc, whether I create the links via the things file or with the paper-ui. I've also noticed that when I do make a typing mistake with an items or things entry, OpenHab is usually quick to spit out an error[s], implying that the corrected text is passing the syntax check.I have stripped my demo to the bare minimum and have included my current set here. The first two (MySensors) 'items' do not update but the log file shows no error. The third item is linked to a non MySensors binding and it works regardless of whether I use to paper.ui or create the links manually (which helps me believe I am creating the link to the binding correctly.
My sitemap.demo
sitemap demo label="Test Menu" { Frame { Text item=myhum01 Text item=mytemp01 Text item=mytemp99 } }My items.demo
Number myhum01 "Humidity Test (22:2) [%.1f RH]" { channel="mysensors:humidity:gateway:myhum01:hum" } Number mytemp01 "Temperature Test (22:3) [%.1f] °C" { channel="mysensors:temperature:gateway:mytemp01:temp" } Number mytemp99 "Printer queue [%.0f jobs]" {channel="ipp:printer:Brother_MFC_8510DN:waitingJobs"}QUESTION:
In the following things entry, should the first line start with "Bridge" or without? The binding setup notes appear to show both with and without?My things.demo
Bridge mysensors:bridge-eth:gateway [ ipAddress="192.168.1.70", tcpPort=5003, sendDelay=200 ] { /** define things connected to that bridge here */ humidity myhum01 [ nodeId="22", childId="2" ] temperature mytemp01 [ nodeId="22", childId="3" ] }I always look for 'what I am doing wrong', but I have been on this for days with no joy, I've reached the point where I am really starting to suspect there may be a bug.
Once again, I would appreciate suggestions from anybody...
Thanks,
Paul
-
Hi all
Im having major difficulties getting openhab to discover my serial gateway. I have had it working and i have got input from my sensors. But every know and then when i have restarted the computer the serial gateway is offline. The COM port is active and working according to Windows.
I have tried to restart the computer, unplug and plug the usb several times. Doing this i have at some occasions got everything to work again.Any suggestions what it can be that is being the problem.
I also think i have found a bug.
If i for example take the gateway or an sensor that has been added which is marked off line. Then if i press edit on that one and just press the "save" button then it shows that it is on line even though i seriously doubt it is. As i dont get data from my sensors.
Also how do i get the debug window (console view) to show for openhab?
-
@AffordableTech Hmmm, your configuration looks good. Please show me the debug output.
According the use of Bridge: I suppose using the word Bridge at the beginning is the right way!
@Denke: I haven't used OH2 on Windows yet, but I suppose you see the karaf console?
If so, you should be able to use: log:set DEBUG org.openhab.binding.mysensors and log:tail.
The online/offline status of a thing in OH2 only shows if the bridge is connected and if the thing could be initialized. There is no dependence to wether a thing has received data or not.
-
@TimO said:
log:set DEBUG org.openhab.binding.mysensors
I tried this. From what i can see after doing some attempts to connect.
First & second attempt
This is what i get after just opening the serial gateway thing and edit and sav, seems as openhab then tries to reconnect with the gateway.
third attempt
It seems like it gets connected, bu some error occurs which closes the connection again
Forth attempt actually seemed to get things going
Now to the next step.
I have a device that was just given a new address from the my sensor binding. I restarted the sensor and waited to see it in the inbox (this is something that i have seen working occasionally( sometimes it gets in there sometimes it don't. I added the device manually and it seems to work. How ever my links dont work
-
@Denke: At the first and the second attempt the gnu.io library is not able to establish a connection to the COM port. As "getPortIdentifier()" is mentioned it is possible that there is a correlation with the bug mentioned earlier in this thread. The OH developers need to update this library soon!
At the third attempt the binding does not get an answer on the I_VERSION check and therefore stops its initialization. Try skipStartupCheck=true Wiki to avoid this.
According to the discovery with PaperUI:
I must admit I have not worked with it for a long time. The OH core team has announced some big changes/improvements in the PaperUI and I've postponed my tests for after the release of this changes.
-
Is any bodoy having high CPU load with this binding? My setup is: Openhab 2.0, running on a Pi3, with Java SDK Oracle. Openhab is running +100% CPU all the time. Here is the output of top command:
top - 19:54:33 up 9:19, 1 user, load average: 0.98, 1.01, 1.03 Tasks: 153 total, 1 running, 152 sleeping, 0 stopped, 0 zombie %Cpu(s): 21.4 us, 4.2 sy, 0.0 ni, 74.4 id, 0.0 wa, 0.0 hi, 0.1 si, 0.0 st KiB Mem : 948056 total, 112832 free, 325232 used, 509992 buff/cache KiB Swap: 0 total, 0 free, 0 used. 573692 avail Mem PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND 3919 root 20 0 455068 180668 14280 S 101.3 19.1 547:33.46 java 28144 root 20 0 8436 2840 2280 R 0.7 0.3 0:06.30 top 368 root 20 0 1448 88 16 S 0.3 0.0 0:31.75 rngdIf I look all the threats with command
ps -Lp 3919I can see one that is eating too much CPU:
3919 4226 ? 08:25:13 javaand looking at this one in the output of a thread dump: (4226 DEC = 1082 HEX)
Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: "pool-41-thread-1" #273 prio=5 os_prio=0 tid=0x62addc00 **nid=0x1082** runnable [0x5d6be000] Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: java.lang.Thread.State: RUNNABLE Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at gnu.io.RXTXPort.nativeavailable(Native Method) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at gnu.io.RXTXPort$SerialInputStream.available(RXTXPort.java:1568) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011- locked <0x6afb81f8> (a gnu.io.RXTXPort$SerialInputStream) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at sun.nio.cs.StreamDecoder.inReady(StreamDecoder.java:363) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at sun.nio.cs.StreamDecoder.implReady(StreamDecoder.java:371) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at sun.nio.cs.StreamDecoder.ready(StreamDecoder.java:185) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011- locked <0x6b2a41e8> (a java.io.InputStreamReader) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at java.io.InputStreamReader.ready(InputStreamReader.java:195) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at java.io.BufferedReader.ready(BufferedReader.java:463) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011- locked <0x6b2a41e8> (a java.io.InputStreamReader) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at org.openhab.binding.mysensors.protocol.MySensorsReader.run(MySensorsReader.java:50) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at java.util.concurrent.FutureTask.run(FutureTask.java:266) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) Jun 1 19:30:31 avinaz-pi3 start.sh[3822]: #011at java.lang.Thread.run(Thread.java:745)You can see that is related to org.openhab.binding.mysensors.protocol.MySensorsReader.run(MySensorsReader.java:50)
Any ideas?
-
@avinaz : After a second thought I now have an idea!
With the last commit I changed line 50 in MySensorsReader.java due to an exception I got after upgrading ubuntu/java and OH2.
The binding (old version) was still unchanged.I need to look into this. Maybe @andreacioni has an idea?
-
@TimO I would say at a glance, that the thread is continuously reading the Serial port, with out a break, so this must be very CPU consuming?
-
I think i need some help. I am not shure, if my problem lies on the openHab2 side or if there is a problem with the mysensors addon.
This is what i I have:
A working mysensors addon. I receive updates in OpenHab:2016-06-06 07:12:08.483 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTING - org.openhab.binding.mysensors 2016-06-06 07:12:08.550 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.mysensors.internal.MySensorsHandlerFactory, component.id=7, service.id=102, service.bundleid=11, service.scope=bundle} - org.openhab.binding.mysensors 2016-06-06 07:12:09.070 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTED - org.openhab.binding.mysensors 2016-06-06 07:12:12.062 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin 2016-06-06 07:12:18.694 [ERROR] [org.openhab.ui.habmin ] - [org.openhab.ui.habmin.designer(3)] bind method [setItemRegistry] not found; Component will fail 2016-06-06 07:12:18.702 [ERROR] [org.openhab.ui.habmin ] - [org.openhab.ui.habmin.designer(3)] bind method [setItemRegistry] not found 2016-06-06 07:12:25.252 [ERROR] [org.eclipse.smarthome.core.transform] - [org.eclipse.smarthome.action.transformation.action(51)] Circular reference detected, getService returning null 2016-06-06 07:12:39.288 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'mysensors_light_gateway_Light_1_0-mysensors:light:gateway:Light_1_0' has been added. 2016-06-06 07:12:39.315 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'home_group_88a3d2a1' has been added. 2016-06-06 07:12:42.030 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /ui 2016-06-06 07:12:42.261 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at /start 2016-06-06 07:12:42.825 [WARN ] [sql.internal.MysqlPersistenceService] - The SQL user is missing - please configure the sql:user parameter in openhab.cfg 2016-06-06 07:12:43.071 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app 2016-06-06 07:12:43.114 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 2016-06-06 07:12:43.470 [INFO ] [.openhab.core.internal.CoreActivator] - openHAB runtime has been started (v2.0.0, build b2). 2016-06-06 07:12:43.479 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.sitemap' 2016-06-06 07:12:44.159 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.things' 2016-06-06 07:12:44.397 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:bridge-eth:gateway' has been added. 2016-06-06 07:12:44.468 [INFO ] [nsors.handler.MySensorsBridgeHandler] - Using Metric measure unit 2016-06-06 07:12:44.470 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - Set skip check on startup to: false 2016-06-06 07:12:44.489 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=278, service.bundleid=11, service.scope=singleton} - org.openhab.binding.mysensors 2016-06-06 07:12:44.527 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - Initialization of the MySensors Bridge 2016-06-06 07:12:44.531 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:gateway' changed from UNINITIALIZED to INITIALIZING 2016-06-06 07:12:44.540 [DEBUG] [rs.protocol.ip.MySensorsIpConnection] - Connecting to bridge ... 2016-06-06 07:12:44.762 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 0;0;3;0;2; 2016-06-06 07:12:44.770 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 0;255;3;0;14;Gateway startup complete. 2016-06-06 07:12:44.776 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 0;255;3;0;11;EthernetGateway 2016-06-06 07:12:44.778 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 0;255;3;0;12;1.0 2016-06-06 07:12:44.849 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 0;255;3;0;2;2.0.0-beta 2016-06-06 07:12:44.852 [DEBUG] [s.internal.MySensorsBridgeConnection] - Good,Gateway is up and running! (Ver:2.0.0-beta) 2016-06-06 07:12:44.858 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:bridge-eth, thing.id=mysensors:bridge-eth:gateway, service.id=279, service.bundleid=11, service.scope=singleton} - org.openhab.binding.mysensors 2016-06-06 07:12:44.859 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:gateway' changed from INITIALIZING to ONLINE 2016-06-06 07:12:44.900 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'logging.persist' 2016-06-06 07:12:44.990 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'rrd4j.persist' 2016-06-06 07:12:45.047 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.items' 2016-06-06 07:12:45.168 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'n1c0-mysensors:light:gateway:n1c0:status' has been added. 2016-06-06 07:12:45.171 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'n1c1-mysensors:light:gateway:n1c1:status' has been added. 2016-06-06 07:12:45.174 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'n1c2-mysensors:humidity:gateway:n1c2:hum' has been added. 2016-06-06 07:12:45.178 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'n1c3-mysensors:humidity:gateway:n1c3:hum' has been added. 2016-06-06 07:12:45.182 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'n1c4-mysensors:humidity:gateway:n1c4:hum' has been added. 2016-06-06 07:14:50.818 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 1;3;1;1;1;1 2016-06-06 07:14:50.820 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - ACK received! 2016-06-06 07:14:55.831 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 1;3;1;1;1;0 2016-06-06 07:14:55.832 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - ACK received! 2016-06-06 07:28:01.373 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 1;3;1;1;1;1 2016-06-06 07:28:01.375 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - ACK received! 2016-06-06 07:28:06.385 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 1;3;1;1;1;0 2016-06-06 07:28:06.387 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - ACK received! 2016-06-06 07:32:21.567 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 1;3;1;1;1;1 2016-06-06 07:32:21.568 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - ACK received! 2016-06-06 07:32:26.578 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 1;3;1;1;1;0 2016-06-06 07:32:26.582 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - ACK received!Seems ok.
But I am not able to see the result in Openhab
my.thing:
mysensors:bridge-eth:gateway [ ipAddress="192.168.100.10", tcpPort=5003, sendDelay=200 ] { /** define things connected to that bridge here */ light n1c0 [ nodeId="1", childId="0", requestAck=true ] light n1c1 [ nodeId="1", childId="1", requestAck=true ] humidity n1c2 [ nodeId="1", childId="2" ] humidity n1c3 [ nodeId="1", childId="3" ] humidity n1c4 [ nodeId="1", childId="4" ] }my.item:
Switch n1c0 "PFLANZENNAME1 Pumpe" <water> (gGF_irrigation01) { channel="mysensors:light:gateway:n1c0:status" } Switch n1c1 "PFLANZENNAME1 Pumpe Automatikmodus" <water> (gGF_irrigation01) { channel="mysensors:light:gateway:n1c1:status" } Number n1c2 "PFLANZENNAME1 Bodenfeuchtigkeit max" <water> (gGF_irrigation01) { channel="mysensors:humidity:gateway:n1c2:hum" } Number n1c3 "PFLANZENNAME1 Bodenfeuchtigkeit min" <water> (gGF_irrigation01) { channel="mysensors:humidity:gateway:n1c3:hum" } Number n1c4 "PFLANZENNAME1 Bodenfeuchtigkeit" <water> (gGF_irrigation01,gGF_eating) { channel="mysensors:humidity:gateway:n1c4:hum" }There are simply no values in openhab although I see the debug messages in the openhab log.
Any idea how to fix it? I did not use the autodicovery of openhab paper UI. what confuses me is the name of the link used by openhab:2016-06-06 07:12:45.168 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'n1c0-mysensors:light:gateway:n1c0:status' has been added.That does not match the channel. is that ok?
Thank
DakkyEDIT: updated openhab log to complete startup process and added question
-
Hey man,
This is awesome!
You should add support for Relays.
-
Hi @TimO
I am having the exact same simtoms. Huge CPU usage with latest binding version.
Let me know if you need me to run some test to get this solved.
-
@dakky said:
I think i need some help. I am not shure, if my problem lies on the openHab2 side or if there is a problem with the mysensors addon.
No idea anyone? i'm stucked here and I'd really like to continue
EDIT: MAybe this helps debugging: it seem there is no "link" between things and items. The rest endpoints reveals, that the thing has no item:
[ { "statusInfo": { "status": "ONLINE", "statusDetail": "NONE" }, "link": "http://autohome:8080/rest/things/mysensors:bridge-eth:gateway", "configuration": { "tcpPort": 5003, "ipAddress": "192.168.100.10", "skipStartupCheck": false, "imperial": false, "sendDelay": 200 }, "properties": {}, "UID": "mysensors:bridge-eth:gateway", "channels": [ { "linkedItems": [], "id": "n1c0", "itemType": "light", "properties": {} }, { "linkedItems": [], "id": "n1c1", "itemType": "light", "properties": {} }, { "linkedItems": [], "id": "n1c2", "itemType": "humidity", "properties": {} }, { "linkedItems": [], "id": "n1c3", "itemType": "humidity", "properties": {} }, { "linkedItems": [], "id": "n1c4", "itemType": "humidity", "properties": {} } ] } ]Greetings
-
Dammit ... found the error
created a pull request: https://github.com/tobof/openhab2-addons/pull/18
Greetings
Dakky
-
Glad you found it!
I'm a little low in time at the moment, I will look at the PRs next weekend. Thank you for the contributions!
-
There is a new jar available that fixes the bug with the high CPU usage.
Additionally @meju25 added support for customSensor and an option to adjust the behavior in case no ACK could be received (see wiki). Thanks a lot!
-
Thanks @TimO , seems is working fine. I updated my installation with the new jar and now CPU is around 4-6%. Thanks a lot. By the way, I did not see a commit in the code repository.
-
@avinaz: I'm glad, thanks for testing!
Did you look into the right branch? I've committed all changes to github.
-
Hi all
Implemented a barometric sensor "pressure meter" that i sample in the sensor. Then i send the value Through "BARO". I can see in both the sensor output and the openhab debug that i receive a value for example "1.2".
I try to display this in the openhab and i dont get the Text item to update.
Anyone know how this can be as i have other values that i am able to display.
line in .items
String Pool_Pressure "Pressure (bar) [%s]" <pressure> (gPool)line in .sitemap
Text item=Pool_Pressureany help is appriciated as i have tried different options with Number and so on
-
Merging sensor to one
Hi
I have been thinking and i think it would be nice if there is a way to merge sensor data to one in the mysensors binding.Background
I am trying to build a pool control based on mysensors and i have a lot of options that i wan it to handle. For example:- on/off of my pool pumps (S_LIGHT)
- on/off of lights around the pool (S_LIGHT)
- thermostat function for heaters (S_HVAC)
- measuring pressure on pump (S_BARO)
- measuring consumed power on pump (S_POWER)
All this are using the same sensor ID and getting own child IDs. When they now are brought in to openhab they appear as single sensors
Is there a possibility to get an automatic merge for thes combined sensors?
And what do you think about the idea.
Or is there already a solution that exist that I am not aware aboutexample from paper UI
with openhab poolcontrol view
-
@Denke
If I understand correctly, you are suggesting to combine different sensor types in one thing.
IMHO this would jeopardize the concept of the binding. The binding provides one thing per message type (see https://www.mysensors.org/download/serial_api_15). the thing has one channel per supported sub type.
I also do not see the advantage of combining different sensor/message types in one thing as you need one item per channel anyhow.
=> this would not reduce the number of items needed.Grouping the items in the site map - as you already did - is the best way here.
Does anybody see this differently?
-
@meju25 I saw this from a management perspective.
I have one device, my pool controller, that has multiple sensors and also channels per sensor just as HVAC that has a sum of channels. In my case i implemented a number of sensors in different types of channels "S_HVAC, S_LIGHT,...." what i see that it gets messy when you try to see if the channels are active or the pool control is active it has multiple sensors.So maybe a suggestion is to maybe implement this in the mysensors instead of in the openhab binding.
In that case i would like to add something that allows me to tell what channels are in a "sensor" by combining differnt channels and sensor types.I don't know if i got my message through.
But all this is mainly for management of my devices so that this gets less messy
-
@TimO do you plan to implement the reconnect thing? I often have to struggle with that and the only solution is the restart the openhab service :S Is there a problem with the reconnect or is it just "another task on the todo list"?
Greetings
Dakky
-
Hi
Is there anybody that have had a problem with the S_BARO and the V_PRESSURE channel?I try to send a value on this channel as 1.2 I can see that the serial gateway receives it
23:10:51.808 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 0;0;3;0;9;read: 163-163-0 s=10,c=1,t=4,pt=7,l=5,sg=0:1.2
I set up the channel as i have done for alot of other channels through the paper ui. where i link the channel to an item.
The item file looks like this
String Pool_Pressure "Pressure [%s]" <pressure> (gPool)the sitemap file looks like this
Text item=Pool_Pressureresult is this
The field never gets updated.
any suggestions?
-
@Denke
I cant find V_PRESSURE connected to CHANNEL_BARO below, can that be the reason?
-
@Denke : You're right, the pressure channel was missing in the thing definition. This is fixed in the current version!
@dakky : Which gateway type keeps disconnecting and why? I've a serial and ethernet gateway running side by side in OH2 and both didn't get disconnected so far. The hardest part is to recognize if the connection gets down.
-
Hi @TimO . Are you having both gateways connected to same the OH simultaneously?
Any troubles with that? I imagine you should have different radio channels for each gateway, right? like two separate sensors networks.
-
Hi @gonzalonal !
Only the ethernet gateway has an attached radio.
Here is my current (MySensors) setup:
Bridge mysensors:bridge-eth:MySGW [ ipAddress="192.168.2.99", tcpPort=5003, sendDelay=100, skipStartupCheck=true ] { humidity bathHum01 [ nodeId="101", childId="0" ] temperature bathTemp01 [ nodeId="101", childId="1" ] humidity bathBat01 [ nodeId="101", childId="255" ] humidity bedHum01 [ nodeId="103", childId="2" ] temperature bedTemp01 [ nodeId="103", childId="1" ] humidity bedBat01 [ nodeId="103", childId="255" ] humidity workHum01 [ nodeId="104", childId="2" ] temperature workTemp01 [ nodeId="104", childId="1" ] humidity workBat01 [ nodeId="104", childId="255" ] humidity kitchenHum01 [ nodeId="105", childId="2" ] temperature kitchenTemp01 [ nodeId="105", childId="1" ] humidity kitchenBat01 [ nodeId="105", childId="255" ] humidity childHum01 [ nodeId="107", childId="3" ] temperature childTemp01 [ nodeId="107", childId="4" ] light childLight01 [ nodeId="107", childId="0" ] light childLight02 [ nodeId="107", childId="1" ] light childLight03 [ nodeId="107", childId="2" ] humidity guestHum01 [ nodeId="106", childId="0" ] temperature guestTemp01 [ nodeId="106", childId="1" ] light guestPresence01 [ nodeId="106", childId="3" ] light livingLight01 [ nodeId="106", childId="4" ] light livingLight02 [ nodeId="106", childId="5" ] light livingLight03 [ nodeId="106", childId="6" ] light livingLight04 [ nodeId="106", childId="7" ] light corridorLight03 [ nodeId="106", childId="8" ] light livingLight06 [ nodeId="106", childId="9" ] humidity livingHum01 [ nodeId="108", childId="2" ] temperature livingTemp01 [ nodeId="108", childId="1" ] humidity livingBat01 [ nodeId="108", childId="255" ] humidity gardenHum01 [ nodeId="110", childId="2" ] temperature gardenTemp01 [ nodeId="110", childId="1" ] humidity gardenBat01 [ nodeId="110", childId="255" ] motion gardenMotion01 [ nodeId="110", childId="3" ] } Bridge mysensors:bridge-ser:MySGWKeller [ serialPort="/dev/ttyUSB0", sendDelay=100, skipStartupCheck=true ] { cover kitchenShutter02 [ nodeId="0", childId="0" ] cover kitchenShutter03 [ nodeId="0", childId="1" ] cover livingShutter01 [ nodeId="0", childId="2" ] cover livingShutter02 [ nodeId="0", childId="3" ] cover kitchenShutter01 [ nodeId="0", childId="4" ] light corridorLight01 [ nodeId="0", childId="5" ] light corridorLight02 [ nodeId="0", childId="6" ] power corridorLight01watt [ nodeId="0", childId="7" ] }
-
I'm using a ethernet gateway.
the problem is: if the sensors gateway gets restarted/disconnected/updated/whatever the binding does not reconnect. The only way to fix that, is to restart openhab in order to force the binding to connect again.
Detecting an disconnect is really that hard? in the worst case, what about simply reconnecting timebased? not as cute as only reconnect when nessecary but a dirty quickfix.
-
Thanks @TimO. Now I get it.
What do you think about having two gateways, one with NRF radio and the other one with RFM69 radio?Will the binding support both gateways simultaneusly with no issues?
Another question..Will you support HearbeatRequest for the development branch?
Thanks, regards.
-
Hello againg.
I have updated my OH2 to latest nightbuild from cloudbees, and also updated to lastest realease of OH2 MyS Binding.
Now, I am facing an issue, that everytime that OH2 starts, it sends out all the items status to its respective node. This is causing, for example, to open my gates, or lowering my rollershutters, even disabling/enabling my alarm system. So I have shutdown OH2 for time being.
Have anyone faced this behavior before?
This is some of the log:
15:41:40.241 [WARN ] [ome.core.thing.internal.ThingManager] - Initializing handler for thing 'mysensors:bridge-ser:gateway' takes more than 5000ms. 15:41:40.272 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.295 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.322 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.335 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.348 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.362 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.375 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.388 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.402 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.545 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Home.items' 15:41:40.671 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.685 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.697 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.710 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.724 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.738 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.755 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.768 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.783 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.806 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 0;255;3;0;14;Gateway startup complete. 15:41:40.811 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.856 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.871 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.885 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.898 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.914 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.928 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:40.941 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:41.029 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 2;3;1;0;0; 15:41:41.110 [DEBUG] [g.mysensors.handler.MySensorsHandler] - handleCommand called 15:41:41.288 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 3;253;1;0;2; 15:41:41.546 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 1;6;1;0;2; 15:41:41.799 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 3;252;1;0;24;REFRESH 15:41:42.052 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 3;254;1;0;2; 15:41:42.307 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 1;254;1;0;2; 15:41:42.562 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 3;3;1;0;2; 15:41:42.817 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 1;253;1;0;2; 15:41:43.073 [DEBUG] [g.mysensors.protocol.MySensorsWriter] - Sending to MySensors: 2;252;1;0;24;REFRESHI would really appreciate your help.
-
@gonzalonal I have no idea why this should happen. The binding isn't that smart to do something like that. I've done a complete fresh installation on 21.06. with the nightly build from cloudbees and don't see this issue.
@dakky Do you see disconnection messages in the log? The binding already tries to detect a disconnect and tries to reestablish the connection when an ethernet gateway is used. Currently the time within a reconnect would be successful is very small due to sleep limitations of threads in OH2 bindings.
-
Thanks @TimO . I will try to make a progressive installation and try to figure out what is causing this behavior.
I'll let you know if I find out.
Have you read my previous message?
Regards@gonzalonal said:
Thanks @TimO. Now I get it.
What do you think about having two gateways, one with NRF radio and the other one with RFM69 radio?Will the binding support both gateways simultaneusly with no issues?
Another question..Will you support HearbeatRequest for the development branch?
Thanks, regards.
-
@gonzalonal : I just downloaded the recent version of the binding again and I don't see this behaviour.
I hope both gateways will work together! This is was the idea. I've not tested it in combination with RFM69 but I don't see a reason why this should not work!
-
@TimO I have solved my probles using OH2 Beta3 and the last release of the MyS binding.
It seems that the problem is only reproduced with the last release of OH2 from Cloudbees. At least withe the release 372.
Have you tryed it?
-
After a full computer crash (overheated CPU, CPU fan stopped working) I'm trying to set everything up on a new computer. Using the latest openhab2 beta 3 I'm having issues with using this binding. Specifically, whenever I add my standard thing configuration I get a buffer overflow in openhab which results in a core dump.
I have downloaded latest binding from the link at github, but that did not affect it. In addition to this binding I am using the zwave binding and rfxcom binding. All three connect to devices through serial ports, although I I'm not sure if they do it in the same way.
You may recall that I have had trouble using these three bindings together earlier, but for some reason things worked out and it has worked quite reliably since then.
I'm not sure which information from the system crash is useful, but if there is anything I can send you to help you figure out what happens, let me know. Also, I realise that this problem might not be related directly with mysensors, but maybe with the serial library used (I saw a reference to NVJavaSeria in the stack trace after the crash.
-
Hello again.
As a temporary fix I found a simple TCP/serial Gateway Python snippet that handles the connection to the serial mysensors device and provides a TCP interface to openhab. Switching the binding of aggression from serial to socket and having it connected to this software gateway removes the core dump issue. This confirms my suspicions that the issues are with the serial port library.
It works okay as a workaround until someone figure something out
If anyone else is having issues with the serial connection, this is the by then snippet I found and used:
https://github.com/pyserial/pyserial/blob/master/examples/tcp_serial_redirect.py
-
@kolaf I'm only using one serial gw and that is running rock stable within OH2. My last update was 22 days ago, so maybe it is time for an update again.
I recently changed my job, so I'm a little low in time at the moment.
-
From what i can see this binding is not part of the official bindings for openHAB. Are there any plans to try to get this as a official binding for openhab?
/Fredrik
-
@Fredrik-Jönsson : Yes, this is on my to do list with high priority. I've done some preparations but need to contact the OH2 developers before creating a PR.
-
I've found the time to sync my repository with upstream master OH2. Now I do see the behaviour you described above. The status of every item is send out at startup.
Using the current package of the MySensors binding from today with an older version of OH2 does work fine. But my development environment now throws out the status of all things.
Are you using any other bindings? Are they working fine?At first I need to find out if this problem only occures with the MySensors binding or if there is a bug.
-
Hi @TimO.
I am using several bindings in my OH2 setup.
As I am using MyS and OH2 in a productive and sensible environement I could try out how the other bindings behaved. As soon as I detect the error I had to rollback my setup. So I can't really tell is there is a bug with the latest snapshot of OH2 or if it is something more specific of MyS binding.I am currently using OH2 Beta 3 with no issues.
-
@TimO That sounds great, i will start trying this out. By reading this thread it sounds like it works quite well..
Thanks for all you time you put into this binding.
/Fredrik
-
@gonzalonal : I've found the reason for this beaviour and fixed the bug.
@andreacioni contributed a PR to the code that includes a channel for the last update of a thing. If you want to know at which time there was a message from a node you may subscribe to the "lastupdate" channel.
Example:
DateTime lastUpdate01 "Last Update" { channel="mysensors:baro:gateway:baro01:lastupdate" }Thanks @andreacioni !
-
Hi @TimO .
I have tried the binding and the issue is fixed as you have said.
I have been looking at the logs, and found the following regarding last update feature:15:52:11.580 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 1;253;1;0;2;1 15:52:11.583 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.581-0300 15:52:11.585 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.584-0300 15:52:11.590 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.588-0300 15:52:11.594 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.592-0300 15:52:11.598 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.597-0300 15:52:11.602 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.601-0300 15:52:11.606 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.604-0300 15:52:11.609 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.608-0300 15:52:11.613 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.611-0300 15:52:11.618 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.616-0300 15:52:11.624 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.622-0300 15:52:11.628 [DEBUG] [g.mysensors.handler.MySensorsHandler] - Setting last update for node 1 to 2016-08-08T15:52:11.626-0300Is this the expected behavior? It seems to me that we should have only one "Setting last.." for value updated.
Maybe is my setup. Can someone check this.
Thanks!
-
Hi @gonzalonal
The implementation of the binding create a thing for every channel of a single node. So the solution result is what you can see in the log. We can do a little bit more to prevent a single message to fire every 'lastupdate' channel of every thing that have the same node ID. But I think it is not a good idea.
-
Got it!
Thanks for your quick reply @andreacioni
-
Hi guys,
I've tried to add my ethernet gateway to openhab2 through but I just cant get the Online-status on, it. Do I have to do anything else than just adding it into the things file?
-
Hi @Gustafsson,
Have you read the readme? Readme . Probably you have not configured things? See the readme on how to do that
-
@andreacioni That's exactly what I did.
Things
Bridge mysensors:bridge-eth:gateway [ ipAddress="192.168.254.228", tcpPort=5003, sendDelay=200 ] door frontDoor [ nodeId="102", childId="4" ] }Items
Text frontDoor "Door" { channel="mysensors:door:gateway:frontDoor:tripped" }Sitemap
sitemap MySitemap label="Main Menu" { Frame { Text item=frontDoor } }This is how everything looks like now. I get the door item green in habmin but still the red cross on the gateway. The door item dosn't show me if it's open or closed.
-
You are missing the opening brackets
Bridge mysensors:bridge-eth:gateway [ ipAddress="192.168.254.228", tcpPort=5003, sendDelay=200 ] {
door frontDoor [ nodeId="102", childId="4" ]
}
-
@gonzalonal sorry, I missed it when I pasted in my code here, so there is in fact a { in my code. Any other ideas?
-
I have a problem with adding one of my nodes.
My temp en hum nodes are working perfectly, but i try to add a Light Level node. The sensor is sending the amount of Lux and not the percentage:18:14:59.572 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 1-1-0 s=0,c=1,t=23,pt=3,l=2,sg=0:3 18:14:59.572 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 1;0;1;0;23;3 18:14:59.574 [ERROR] [g.mysensors.protocol.MySensorsReader] - (java.lang.IllegalStateException: Could not update state, because callback is missing) on reading from serial port, message: class org.openhab.binding.mysensors.protocol.serial.MySensorsSerialReaderIt is using CHILD_ID_LIGHT inside the skechts. Is this light level node supported?
-
@FutureCow said:
18:14:59.572 [DEBUG] [g.mysensors.protocol.MySensorsReader] - 1;0;1;0;23;3According to this: https://www.mysensors.org/download/serial_api_20
V_LIGHT_LEVEL 23 Uncalibrated light level. 0-100%. Use V_LEVEL for light level in lux. S_LIGHT_LEVEL V_LEVEL 37 Used for sending level-value S_DUST, S_AIR_QUALITY, S_SOUND (dB), S_VIBRATION (hz), S_LIGHT_LEVEL (lux)Your node should send "1;0;1;0;37;3 if I'm not mistaken.
The light level message is supported by the binding.
-
Small update to the progress:
-
Expert mode: I've added a special thing that will allow rule based parsing and sending of MySensors messages. If the binding is limiting your creativity you are now free to go.
You are now able to combine/adapt rules like in this Thread with the easy process of thing/item/sensor configuration. I've added a description to the Wiki. This may solve some problems mentioned above with sensors requesting information like pulse count. -
Last week the OH2 core team has released beta4 of OH2. I've tested the binding against the current beta and it is working fine.
-
I've created a PR against the official openhab2-addons repository. I'm now awaiting feedback and hope to introduce the MySensors binding as part of OH2.
-
-
Great job @Timo!
-
Great news to hear @TimO .
I will be testing the binding in a couple of hours. It's really usefull the new RAW messenger things.
Thanks!
-
Hi all, i am new in mysensors, but i have problem with install this plugin to OpenHab2.0 i tried versions 2.0 and 2.0b4 but i couldn't see this plugin in OpenHab2, i tried with RPi B, RPi3 with Raspian Jessie, and with Windows 10. But all with the same result.
I placed org.openhab.binding.mysensors-2.0.0.SNAPSHOT.jar into addons directory, but i can't se it in OpenHab.
Can anyone help me?
-
@xlibor Did you follow the instructions on the wiki and installed serial transport first? Is there a log file entry? How did you install OH2? Debian Package or Zip File?
-
This post is deleted!
-
@TimO Yes, i follow instruction on wiki and install serial transport sucesfully. I tried both types, first zip file and next package, but both with the same result
In log file is nothing about mysensors or any error.
Now i downloaded again from github and now all works without any problem. But i don't know where was problem.
-
Hey All. Newbie alert here. I love the work you all do on here and this project is so exciting. I'm hooked after 2 weeks of 'fettling' and building sensors.
I've hit a brick wall though. after struggling with openhab1 I decided to make the leap straight to openhab2. Although in early days of testing it seems immediately abit more user friendly.
What I've done so far...
RaspberryPi3 is acting as controller with openhab2 installed and running fine. bindings to TV and Nest thermostat all working lovely.Clone arduino Uno's with capacitors added for radio with NRF24 radio cards
Created GatewayW5100 gateway using the mysensors library sketch for it and set static IP to 192.168.0.11 (Openhab2 is set to 192.168.0.10) and my router gives DHCP from 192.168.0.20 up
created a temp and humidity sensor node and a node with a relay to turn a lamp on (both from mysensors library). Tested radio on all before moving ahead.
Installed the mysensors binding on OH2
Added a ethernet gateway
disabled startup connection check as it kept failing to initialise and showed as offline
Tried auto discovery of nodes... NOTHING
Tried setting node ID manually on the arduinos.. Still NOTHING, even if i add a thing manually to OH2.
I'm hoping i'm doing something stupid and its an easy fix.. Any pointers for a newbie??
D.
-
Have you tried to reach your gateway with telnet?
telnet 192.168.2.11 5003Does it respond? Do you see the messages from the node arriving?
Have you enabled debug output of the binding like described in the wiki? What does the log say?
-
Hi@TimO
first, thanks for your awesome work
On the weekend i switched from OH1 to OH2 and your binding is working just out of the box
Im only missing the IR_Send and the V_TEXT sensor to integrate all of my sensors
could you integrate them in your binding?
-
Thanks @Fleischtorte!
There already was a request for IR and I've added one for V_TEXT.
https://github.com/tobof/openhab2-addons/issues
@andreacioni has done some serious refactoring of the code. After looking in the refactoring I'll add the missing sensors.
-
I have the following setup:
- MQTT Gateway on Wemos D1 mini (ESP8266)
- Node on Arduin pro mini
i can't get the openhab binding to distribute address to the nodes, in things in openhab there is no option for MQTT Gateway to add, what is the procedure ?
Cheers
-
@Meshx86 the openhab binding doesnt support MQTT as far as I know.
If you are using the MQTT binding then you need another controller/method to provide the internal functions for my sensors. I use NodeRed workflow to listen for ID requests and it provides them.
Or static assign them in the node stretch.
-
@TimO: Thanks to you this weekend, while kids slept or napped, I managed to setup RPi gateway/controller with NRF24 and OH2.
Following the OH2 install instructions caused me some trouble, not sure why, but following your install instructions it came right up.
Thank you for the time and effort here.
-
Having a couple issues:
- while adding 2nd node (tried power and temp/hum) OH2 would not assign node ID.
- assigned node ID manually (ID=2) and OH2 recognized power node but will not respond to V_VAR1 request.
16:23:58.850 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;1;1;0;0;20.4 16:23:58.874 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;47.3 16:24:01.910 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 16:24:09.293 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;47.5 16:24:11.911 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 16:24:11.942 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;0;2;0;24; 16:24:19.736 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;47.7 16:24:21.912 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 16:24:26.987 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;0;2;0;24; 16:24:30.177 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;47.6 16:24:31.913 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 16:24:40.604 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;47.5 16:24:41.914 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 16:24:42.044 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;0;2;0;24; 16:24:51.916 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 16:24:57.102 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;0;2;0;24; 16:25:01.917 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 16:25:11.919 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 16:25:12.145 [DEBUG] [rs.internal.protocol.MySensorsReader] - 2;0;2;0;24; 16:25:21.921 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 16:25:22.304 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;47.6Things:
Bridge mysensors:bridge-ser:gateway [ serialPort="/dev/pts/2", sendDelay=200 ] { /** define things connected to that bridge here */ humidity hum01 [ nodeId="1", childId="0" ] temperature temp01 [ nodeId="1", childId="1" ] power power01 [ nodeId="2", childId="0" ] }Items:
Number hum01 "Humidity" <humidity> { channel="mysensors:humidity:gateway:hum01:hum" } Number temp01 "Temperature" <temperature> { channel="mysensors:temperature:gateway:temp01:temp" } Number temp01F "Temperature [%.1f °F]" <temperature> DateTime Sensor01LastUpdate "Last seen [%1$ta %1$tR]" <clock> DateTime Sensor02LastUpdate "Last seen [%1$ta %1$tR]" <clock> Number watt01 "Current Demand [%.0f Watt]" { channel="mysensors:power:gateway:power01:watt" } Number kwh01 "Daily Use [%.1f kWh]" { channel="mysensors:power:gateway:power01:kwh" }What am i doing wrong?
-
OK, I think I got past that, I reinstalled everything and cleaned up my MySensors libraries.There is a chance I was pulling from different version when compiling the different examples from different packages.
But, I noticed this exception at OH2 startup:
06:29:26.838 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-ser:gateway' changed from UNINITIALIZED to INITIALIZING 06:29:26.924 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 1, chiledId: 1, revertState: false 06:29:26.924 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 1, chiledId: 0, revertState: false 06:29:26.932 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 2, chiledId: 0, revertState: false 06:29:26.951 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:gateway:hum01' changed from UNINITIALIZED to INITIALIZING 06:29:26.946 [DEBUG] [col.serial.MySensorsSerialConnection] - Set skip check on startup to: false 06:29:26.956 [DEBUG] [col.serial.MySensorsSerialConnection] - Enabling connection watchdog 06:29:26.964 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:gateway:temp01' changed from UNINITIALIZED to INITIALIZING 06:29:26.969 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:power:gateway:power01' changed from UNINITIALIZED to INITIALIZING 06:29:26.993 [DEBUG] [ternal.factory.MySensorsCacheFactory] - Cache file: given_ids.cached exist. 06:29:27.001 [DEBUG] [col.serial.MySensorsSerialConnection] - Connecting to /dev/pts/3 [baudRate:115200] 06:29:27.032 [DEBUG] [ternal.factory.MySensorsCacheFactory] - Cache (given_ids) content: [] 06:29:27.067 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-ser:gateway' changed from INITIALIZING to UNINITIALIZED (HANDLER_INITIALIZING_ERROR) 06:29:27.041 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'mysensors:bridge-ser:gateway': java.lang.UnsupportedOperationException java.util.concurrent.ExecutionException: java.lang.UnsupportedOperationException at java.util.concurrent.FutureTask.report(FutureTask.java:122)[:1.8.0_65] at java.util.concurrent.FutureTask.get(FutureTask.java:206)[:1.8.0_65] at org.eclipse.smarthome.core.common.SafeMethodCaller.callAsynchronous(SafeMethodCaller.java:186)[99:org.eclipse.smarthome.core:0.9.0.201610220816] at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:79)[99:org.eclipse.smarthome.core:0.9.0.201610220816] at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:63)[99:org.eclipse.smarthome.core:0.9.0.201610220816] at org.eclipse.smarthome.core.thing.internal.ThingManager$9.run(ThingManager.java:755)[106:org.eclipse.smarthome.core.thing:0.9.0.201610220816] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_65] at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_65] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_65] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_65] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_65] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_65] at java.lang.Thread.run(Thread.java:745)[:1.8.0_65] Caused by: java.lang.UnsupportedOperationException at java.util.AbstractList.add(AbstractList.java:148)[:1.8.0_65] at java.util.AbstractList.add(AbstractList.java:108)[:1.8.0_65] at org.openhab.binding.mysensors.internal.handler.MySensorsBridgeHandler.loadCacheFile(MySensorsBridgeHandler.java:176)[10:org.openhab.binding.mysensors:2.0.0.201610021604] at org.openhab.binding.mysensors.internal.handler.MySensorsBridgeHandler.initialize(MySensorsBridgeHandler.java:90)[10:org.openhab.binding.mysensors:2.0.0.201610021604] at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:758)[106:org.eclipse.smarthome.core.thing:0.9.0.201610220816] at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:1)[106:org.eclipse.smarthome.core.thing:0.9.0.201610220816] at org.eclipse.smarthome.core.common.SafeMethodCaller$CallableWrapper.call(SafeMethodCaller.java:177)[99:org.eclipse.smarthome.core:0.9.0.201610220816] at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_65] ... 3 more 06:29:27.198 [DEBUG] [col.serial.MySensorsSerialConnection] - Final port list: /dev/pts/3 06:29:27.232 [DEBUG] [col.serial.MySensorsSerialConnection] - Successfully connected to serial port. 06:29:27.234 [DEBUG] [col.serial.MySensorsSerialConnection] - Waiting 3 seconds to allow correct reset trigger on serial connection opening 06:29:29.428 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 06:29:29.897 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app 06:29:30.262 [DEBUG] [rs.internal.protocol.MySensorsReader] - ;255;3;0;14;Gateway startup complete. 06:29:30.274 [ERROR] [rs.internal.protocol.MySensorsReader] - (java.lang.NumberFormatException: For input string: "") on reading from serial port, message: class org.openhab.binding.mysensors.internal.protocol.serial.MySensorsSerialReader 06:29:30.283 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;0;0;18;2.0.1-betaThings still seem to work, but do I have something wrong?
-
Hi @carlyler
what version of JVM are you using? 1.7 or 1.8?
-
@andreacioni after reading this thread I realized you have done a lot of work here as well, thank you.
I am running:
pi@raspberrypi:~ $ java -version
java version "1.8.0_65"
Java(TM) SE Runtime Environment (build 1.8.0_65-b17)
Java HotSpot(TM) Client VM (build 25.65-b01, mixed mode)
pi@raspberrypi:~ $
-
Hello community,
i have a small problem with openhab2 (on a orange pi with armbian - similar to raspberry) and mysensors Serial Gateway.
I installed openhab2 with the get methode and it works fine. I was able, to start it with openhab2.service (also automated) and openhab2 worked as it should. But if i want to connect the mysensors serial gateway to the orange pi with openhab2, openhab2 can't find my gateway. The log says "openhab is unable to connect to serial port, wrong serial port?"
But my serial port is ok.
If i stop openhab2.service and manual start openhab2, than all is fine, it can connect to serial gateway immediately and work fine. I don't know, why it can't work if openhab2 starts as service. Can you help me in this case?Thank you.
And sorry for my english, it's not my first language
-
@hausinger Sounds like a problem with the permissions. The openhab2 service is running as user and group openhab and this user needs permission to the serial port (dialout).
sudo usermod -a -G dialout openhab
-
Hi all
I have a problem when my raspberry pi and mysensors/openhab when i do a restart. The problems seems to be ralated to the serial gateway and that the openhab dont find it or has the wrong settings.Openhab starts when i make a reboot
I checked the serial by typing
stty -F /dev/ttyUSB0
response was
Seems here that the baudrate is wrong as my settings in the things file is
What i did to make it work was just to change this to use baud 9600 and then back to baud = 115200
after this it looked like
And everything started to work nicely
Does anyone now what the cause of this is and also how to fix it?
-
@ TimO thank you. That was the problem
-
I have 2 simpel questions.
- What is the status of the PR for mysensors to get inside openhab native?
- What type of sensor do i need to use for a relay?
-
-
Sadly there is no progress at all: https://github.com/openhab/openhab2-addons/pull/1243 Till now nobody found the time to look into the PR.
-
Use "Light" for a Relay. If you discover the device via PaperUI it will be represented as a "Light" (Switch).
-
-
@TimO I took a quick look at your pull request (personal curiosity) and noticed that one of your committed files includes a merge conflict - you probably want to fix that before the OpenHab team accepts your PR.
-
@blacey said:
I took a quick look at your pull request (personal curiosity) and noticed that one of your committed files includes a merge conflict
Hey @blacey, thank you for peeking in.
I haven't touched the PR for a long time, but it will be necessary soon because there were a lot changes in the development branch. I will fix that too!
-
@TimO Nice! While I have been a longtime Vera user, I think my next move will be OpenHab. I sponsored the Pine64 project on KickStarter and have one sitting on my project desk ready for some prototyping. I think it is great that you are making MySensors a first-class OpenHab 2.0 citizen. Hopefully Kai or one of the OpenHab contributors will merge your binding into the distro.
-
@TimO It seems the binding does not support autoupdate="false" yet. Can you confirm this and do you consider adding this option? I could use the MQTT binding to get what I want (for example feedback of rollershutter position while moving based on the value sent by the sensor and not based on the command sent via the UI), but I don't want to move away from the MySensors binding.
-
Hi All
Big thumb up to all those who made it possible, awsome job here
Truely amazing for us diyers to have access to that technology so easily. Thanks a lotBUT! I have a problem with binding... in short :
1- I installed openhab2 on RPi3 with a fresh SDCard
2-Updated java (as per https://community.openhab.org/t/my-openhab-connectio-failed/12957/11)
3- I followed tobof 's excellent step-by-step howto
4- I installed mysGateway (RPi acting as controller AND gateway)
5- Withpi@raspberrypi:~/MySensors $ sudo ./examples_linux/mysGateway -di can see that my motion sensor and my RPi gateway get on wellmysGateway: Starting gateway... mysGateway: Protocol version - 2.0.1-beta mysGateway: MCO:BGN:INIT GW,CP=RNNG---,VER=2.0.1-beta mysGateway: TSF:LRT:OK mysGateway: TSM:INIT mysGateway: TSM:INIT:TSP OK mysGateway: TSM:INIT:GW MODE mysGateway: TSM:READY mysGateway: MCO:REG:NOT NEEDED mysGateway: MCO:BGN:STP mysGateway: MCO:BGN:INIT OK,ID=0,PAR=0,DIS=0,REG=1 mysGateway: TSF:MSG:READ,1-1-0,s=1,c=1,t=16,pt=0,l=1,sg=0:1 mysGateway: TSF:MSG:READ,1-1-0,s=1,c=1,t=16,pt=0,l=1,sg=0:0 mysGateway: TSF:MSG:READ,1-1-0,s=1,c=1,t=16,pt=0,l=1,sg=0:1 mysGateway: TSF:MSG:READ,1-1-0,s=1,c=1,t=16,pt=0,l=1,sg=0:06- created a thing (text editor) for the bridge
pi@raspberrypi:/usr/share/openhab2/conf/things $ nano home.thingsBridge mysensors:bridge-ser:gateway [ serialPort="/dev/pts/2", sendDelay=200 ] { }7- Following openhab2 "live" log with
pi@raspberrypi:/usr/share/openhab2 $ sudo ./start_debug.sh05:37:32.536 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTING - org.openhab.binding.mysensors 05:37:32.561 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTED - org.openhab.binding.mysensors 05:37:32.789 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.mysensors.internal.MySensorsHandlerFactory, component.id=0, service.id=110, service.bundleid=10, service.scope=bundle} - org.openhab.binding.mysensors 05:37:50.539 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.items' 05:37:58.611 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.sitemap' 05:37:59.319 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.things' 05:37:59.664 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:bridge-ser:gateway' has been added. 05:38:00.412 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=232, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 05:38:00.435 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:bridge-ser, thing.id=mysensors:bridge-ser:gateway, service.id=233, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 05:38:00.473 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-ser:gateway' changed from UNINITIALIZED to INITIALIZING 05:38:00.474 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - Initialization of the MySensors Bridge 05:38:00.594 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'MotionTest-mysensors:motion:1584d6773a3:armed' has been added. 05:38:00.605 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'MotionTest-mysensors:motion:1584d6773a3:tripped' has been added. RXTX Warning: Removing stale lock file. /var/lock/LCK..ttyAMA0 05:38:00.658 [DEBUG] [col.serial.MySensorsSerialConnection] - Successfully connected to serial port. 05:38:00.677 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-ser:gateway' changed from INITIALIZING to ONLINE 05:38:05.333 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 05:38:06.180 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app 05:38:08.009 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /ui 05:38:08.223 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at /start 05:38:08.951 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin 05:38:09.203 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel 05:38:11.343 [INFO ] [o.myopenhab.internal.MyOpenHABClient] - Connected to my.openHAB service (UUID = 7c0faa03-e826-473a-b1f7-8b685b487bb8, base URL = http://localhost:8080)Looks pretty to me...
8- but then i try to register my sensor (arduino pro and 501PIR sensor) with paper UI
No luck. Discovery window stays on forever!
0_1479359161452_Scan.jpegsudo usermod -a -G dialout openhabreturns "unknown user" ...Any clue ?
Thanks a lot for your help !
-
Binding is still unsuccessful
Following error disapeared (Updated my previous post).
Guess that happened as RPi started reboot before openhab session was properly closed... 3 reboots cured that error...in
pi@raspberrypi:/usr/share/openhab2/userdata/logs $ nano openhab.log:2016-11-17 06:40:00.094 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTING - org.openhab.binding.mysensors 2016-11-17 06:40:00.383 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.bindin$ 2016-11-17 06:40:00.480 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTED - org.openhab.binding.mysensors 2016-11-17 06:40:11.249 [WARN ] [eclipse.jetty.servlet.ServletHandler] - javax.servlet.ServletException: A MultiException has 1 exceptions. They are: 1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_0,0,33431755) has been shut down at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:489)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76)[12:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:812)[82:org.eclipse.jetty.servlet:9.2.14.v20151106] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:587)[82:org.eclipse.jetty.servlet:9.2.14.v20151106] at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:70)[173:org.ops4j.pax.web.pax-web-jetty:4.2.4] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:577)[80:org.eclipse.jetty.security:9.2.14.v20151106] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:223)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:271)[173:org.ops4j.pax.web.pax-web-jetty:4.2.4] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)[82:org.eclipse.jetty.servlet:9.2.14.v20151106] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80)[173:org.ops4j.pax.web.pax-web-jetty:4.2.4] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.Server.handle(Server.java:499)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:311)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:544)[73:org.eclipse.jetty.io:9.2.14.v20151106] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)[84:org.eclipse.jetty.util:9.2.14.v20151106] at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)[84:org.eclipse.jetty.util:9.2.14.v20151106] at java.lang.Thread.run(Thread.java:745)[:1.8.0_111] Caused by: A MultiException has 1 exceptions. They are: 1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_0,0,33431755) has been shut down at org.jvnet.hk2.internal.FactoryCreator.getFactoryHandle(FactoryCreator.java:106)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.FactoryCreator.dispose(FactoryCreator.java:173)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.SystemDescriptor.dispose(SystemDescriptor.java:526)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.glassfish.jersey.process.internal.RequestScope$Instance.remove(RequestScope.java:532)[158:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.process.internal.RequestScope$Instance.release(RequestScope.java:549)[158:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:319)[158:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305)[159:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154)[159:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] ... 26 more Caused by: java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_0,0,33431755) has been shut down at org.jvnet.hk2.internal.ServiceLocatorImpl.checkState(ServiceLocatorImpl.java:2288)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.ServiceLocatorImpl.getServiceHandleImpl(ServiceLocatorImpl.java:629)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.ServiceLocatorImpl.getServiceHandle(ServiceLocatorImpl.java:622)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.ServiceLocatorImpl.getServiceHandle(ServiceLocatorImpl.java:640)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.FactoryCreator.getFactoryHandle(FactoryCreator.java:103)[151:org.glassfish.hk2.locator:2.4.0.b34] ... 34 more 2016-11-17 06:40:11.296 [WARN ] [org.eclipse.jetty.server.HttpChannel] - /rest/events javax.servlet.ServletException: javax.servlet.ServletException: A MultiException has 1 exceptions. They are: 1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_0,0,33431755) has been shut down at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:88)[173:org.ops4j.pax.web.pax-web-jetty:4.2.4] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.Server.handle(Server.java:499)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:311)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:544)[73:org.eclipse.jetty.io:9.2.14.v20151106] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)[84:org.eclipse.jetty.util:9.2.14.v20151106] at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)[84:org.eclipse.jetty.util:9.2.14.v20151106] at java.lang.Thread.run(Thread.java:745)[:1.8.0_111] Caused by: javax.servlet.ServletException: A MultiException has 1 exceptions. They are: 1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_0,0,33431755) has been shut down at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:489)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76)[12:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:812)[82:org.eclipse.jetty.servlet:9.2.14.v20151106] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:587)[82:org.eclipse.jetty.servlet:9.2.14.v20151106] at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:70)[173:org.ops4j.pax.web.pax-web-jetty:4.2.4] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:577)[80:org.eclipse.jetty.security:9.2.14.v20151106] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:223)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:271)[173:org.ops4j.pax.web.pax-web-jetty:4.2.4] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)[82:org.eclipse.jetty.servlet:9.2.14.v20151106] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)[81:org.eclipse.jetty.server:9.2.14.v20151106] at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80)[173:org.ops4j.pax.web.pax-web-jetty:4.2.4] ... 8 more Caused by: A MultiException has 1 exceptions. They are: 1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_0,0,33431755) has been shut down at org.jvnet.hk2.internal.FactoryCreator.getFactoryHandle(FactoryCreator.java:106)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.FactoryCreator.dispose(FactoryCreator.java:173)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.SystemDescriptor.dispose(SystemDescriptor.java:526)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.glassfish.jersey.process.internal.RequestScope$Instance.remove(RequestScope.java:532)[158:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.process.internal.RequestScope$Instance.release(RequestScope.java:549)[158:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:319)[158:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:319)[158:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305)[159:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154)[159:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] ... 26 more Caused by: java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_0,0,33431755) has been shut down at org.jvnet.hk2.internal.ServiceLocatorImpl.checkState(ServiceLocatorImpl.java:2288)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.ServiceLocatorImpl.getServiceHandleImpl(ServiceLocatorImpl.java:629)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.ServiceLocatorImpl.getServiceHandle(ServiceLocatorImpl.java:622)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.ServiceLocatorImpl.getServiceHandle(ServiceLocatorImpl.java:640)[151:org.glassfish.hk2.locator:2.4.0.b34] at org.jvnet.hk2.internal.FactoryCreator.getFactoryHandle(FactoryCreator.java:103)[151:org.glassfish.hk2.locator:2.4.0.b34] ... 34 more 2016-11-17 06:40:11.330 [WARN ] [org.eclipse.jetty.server.HttpChannel] - Could not send response error 500: javax.servlet.ServletException: javax.servlet.ServletException: A MultiException has 1 e$ 1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_0,0,33431755) has been shut down 2016-11-17 06:40:20.925 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.items' 2016-11-17 06:40:28.127 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.sitemap' 2016-11-17 06:40:29.101 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.things' 2016-11-17 06:40:30.364 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=235, service.bundleid=10, s$ 2016-11-17 06:40:30.385 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:bridge-ser, thing.i$ 2016-11-17 06:40:30.435 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - Initialization of the MySensors Bridge 2016-11-17 06:40:30.654 [DEBUG] [col.serial.MySensorsSerialConnection] - Successfully connected to serial port. 2016-11-17 06:40:36.145 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 2016-11-17 06:40:38.163 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app 2016-11-17 06:40:38.712 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /ui 2016-11-17 06:40:39.160 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at /start 2016-11-17 06:40:39.955 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin 2016-11-17 06:40:40.396 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel 2016-11-17 06:40:42.527 [INFO ] [o.myopenhab.internal.MyOpenHABClient] - Connected to my.openHAB service (UUID = 7c0faa03-e826-473a-b1f7-8b685b487bb8, base URL = http://localhost:8080)
-
@ben999 The error in your second post does not seem to be related the to binding and I've no clue what it means. So I'm glad the error is gone.
Have you enabled DEBUG for the binding? Please show me the output after you started the discovery process. The connection to the serial gateway is successful, so no need to change the permissions with usermod at the moment.
With debug enabled you should see the messages incoming from the gateway. If you don't see the messages arriving please check the connection between serial gateway and the node.
-
@TimO Thanks a lot for your message
Talking right to the boss !!Actually, except that https://forum.mysensors.org/uploads/files/1479359162183-scan.jpeg nothing else happens...
Log from sensor (arduino micro++nrf24+dth22) :
No change, even when i launch a "scan for things"Starting sensor (RNNNA-, 2.0.0) TSM:INIT TSM:RADIO:OK TSP:ASSIGNID:OK (ID=1) TSM:FPAR TSP:MSG:SEND 1-1-255-255 s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=bc: TSP:MSG:READ 0-0-1 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=1) TSM:UPL TSP:PING:SEND (dest=0) TSP:MSG:SEND 1-1-0-0 s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=ok:1 TSP:MSG:READ 0-0-1 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 1-1-0-0 s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=ok:0100 !TSP:MSG:SEND 1-1-0-0 s=255,c=0,t=17,pt=0,l=5,sg=0,ft=0,st=fail:2.0.0 TSP:MSG:SEND 1-1-0-0 s=255,c=3,t=6,pt=1,l=1,sg=0,ft=1,st=ok:0 TSP:MSG:SEND 1-1-0-0 s=255,c=3,t=11,pt=0,l=22,sg=0,ft=0,st=ok:TemperatureAndHumidity TSP:MSG:SEND 1-1-0-0 s=255,c=3,t=12,pt=0,l=3,sg=0,ft=0,st=ok:1.1 TSP:MSG:SEND 1-1-0-0 s=0,c=0,t=7,pt=0,l=0,sg=0,ft=0,st=ok: TSP:MSG:SEND 1-1-0-0 s=1,c=0,t=6,pt=0,l=0,sg=0,ft=0,st=ok: Request registration... TSP:MSG:SEND 1-1-0-0 s=255,c=3,t=26,pt=1,l=1,sg=0,ft=0,st=ok:2 TSP:MSG:READ 0-0-1 s=255,c=3,t=27,pt=1,l=1,sg=0:1 Node registration=1 Init complete, id=1, parent=0, distance=1, registration=1 TSP:MSG:SEND 1-1-0-0 s=1,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=ok:18.5 T: 18.50 TSP:MSG:SEND 1-1-0-0 s=0,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=ok:68.1 H: 68.10And that's the openhab log... again, no change when i start a "scan for things"... :
pi@raspberrypi:/usr/share/openhab2 $ sudo ./start_debug.sh Launching the openHAB runtime... Listening for transport dt_socket at address: 5005 __ _____ ____ ____ ____ ___ ____ / / / / | / __ ) / __ \/ __ \/ _ \/ __ \/ /_/ / /| | / __ | / /_/ / /_/ / __/ / / / __ / ___ |/ /_/ / \____/ .___/\___/_/ /_/_/ /_/_/ |_/_____/ /_/ 2.0.0-SNAPSHOT Build #574 Hit '<tab>' for a list of available commands and '[cmd] --help' for help on a specific command. Hit '<ctrl-d>' or type 'system:shutdown' or 'logout' to shutdown openHAB. openhab> log:set DEBUG org.openhab.binding.mysensors openhab> log:tail 19:47:20.265 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTING - org.openhab.binding.mysensors 19:47:20.517 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.mysensors.internal.MySensorsHandlerFactory, component.id=0, service.id=109, service.bundleid=10, service.scope=bundle} - org.openhab.binding.mysensors 19:47:20.619 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTED - org.openhab.binding.mysensors 19:47:40.172 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.items' 19:47:48.877 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.sitemap' 19:47:49.580 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.things' 19:47:49.939 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:bridge-ser:gateway' has been added. 19:47:50.853 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=234, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 19:47:50.869 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:bridge-ser, thing.id=mysensors:bridge-ser:gateway, service.id=235, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 19:47:50.908 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-ser:gateway' changed from UNINITIALIZED to INITIALIZING 19:47:50.915 [DEBUG] [nsors.handler.MySensorsBridgeHandler] - Initialization of the MySensors Bridge 19:47:51.047 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:humidity:4008f727' has been added. 19:47:51.072 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:4008f727' changed from UNINITIALIZED to INITIALIZING 19:47:51.073 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:humidity, thing.id=mysensors:humidity:4008f727, service.id=237, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 19:47:51.084 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:temperature:762194fe' has been added. 19:47:51.210 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=mysensors:temperature, thing.id=mysensors:temperature:762194fe, service.id=238, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 19:47:51.212 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:762194fe' changed from UNINITIALIZED to INITIALIZING 19:47:51.329 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:4008f727' changed from INITIALIZING to ONLINE 19:47:51.330 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:762194fe' changed from INITIALIZING to ONLINE 19:47:51.332 [DEBUG] [col.serial.MySensorsSerialConnection] - Successfully connected to serial port. 19:47:51.341 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'MotionTest-mysensors:motion:1584d6773a3:armed' has been added. 19:47:51.345 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'MotionTest-mysensors:motion:1584d6773a3:tripped' has been added. 19:47:51.357 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-ser:gateway' changed from INITIALIZING to ONLINE 19:47:51.413 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_humidity_4008f727_battery' has been added. 19:47:51.417 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_humidity_4008f727_battery-mysensors:humidity:4008f727:battery' has been added. 19:47:51.421 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_humidity_4008f727_hum' has been added. 19:47:51.425 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_humidity_4008f727_hum-mysensors:humidity:4008f727:hum' has been added. 19:47:51.431 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_temperature_762194fe_battery' has been added. 19:47:51.434 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_temperature_762194fe_battery-mysensors:temperature:762194fe:battery' has been added. 19:47:51.440 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_temperature_762194fe_temp' has been added. 19:47:51.454 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_temperature_762194fe_temp-mysensors:temperature:762194fe:temp' has been added. 19:47:56.749 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 19:47:57.810 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app 19:47:58.557 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /ui 19:47:59.161 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at /start 19:47:59.682 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin 19:47:59.807 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel 19:48:04.034 [INFO ] [o.myopenhab.internal.MyOpenHABClient] - Connected to my.openHAB service (UUID = 7c0faa03-e826-473a-b1f7-8b685b487bb8, base URL = http://localhost:8080)Is there any other log that is worth having a look at ?
This is really strange, because when i hook up a PIR sensor to the same arduino and nrf24, and i launchpi@raspberrypi:~/MySensors $ sudo ./examples_linux/mysGateway -di can see that arduino and raspberry interract together...Thanks a lot for your time
-
@ben999 The OH2 log looks good, but could you please enable debug output in OH2 for the binding as described in the Wiki?
In the log from the node I see that the presentation message is the one that failed to deliver. Does this happen all the time? The binding is looking for that message. This could be a power issue.
-
@TimO thank you for your answer
Both RPi and sensors have been power with different power supplies but no change
Then, to get the debug output at the karaf prompt I typed :openhab> log:set DEBUG
This is what i got when asking for a "search for things" process:openhab> log:set DEBUG openhab> log:tail 22:59:40.423 [DEBUG] [e.osgi.LoggingCommandSessionListener] - Command: 'log:clear' returned 'null' 22:59:42.378 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers 22:59:43.026 [DEBUG] [e.osgi.LoggingCommandSessionListener] - Executing command: 'log:set DEBUG' 22:59:43.028 [DEBUG] [org.apache.felix.configadmin ] - getProperties() 22:59:43.032 [DEBUG] [org.apache.felix.configadmin ] - getProperties() 22:59:44.731 [DEBUG] [e.osgi.LoggingCommandSessionListener] - Executing command: 'log:tail' 22:59:46.716 [DEBUG] [io.socket.engineio.client.Socket ] - writing ping packet - expecting pong within 60000ms 22:59:46.720 [DEBUG] [io.socket.engineio.client.Socket ] - flushing 1 packets in socket 22:59:46.765 [DEBUG] [io.socket.engineio.client.Socket ] - socket received: type 'pong', data 'null' 22:59:55.667 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@10fcc9b{/192.168.1.9:45209<->8080,Open,in,out,-,-,30000/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 22:59:55.669 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@10fcc9b{/192.168.1.9:45209<->8080,Open,in,out,-,-,30002/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout expired 22:59:55.671 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SelectChannelEndPoint@10fcc9b{/192.168.1.9:45209<->8080,Open,in,out,-,-,30004/30000,HttpConnection}{io=0,kio=0,kro=1} 22:59:57.110 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Accept@13e699a 22:59:57.112 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Accept@13e699a 22:59:57.113 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - onOpen SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,0/30000,null}{io=0,kio=0,kro=0} 22:59:57.116 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,1/30000,null}{io=0,kio=0,kro=0} idle timeout check, elapsed: 1 ms, remaining: 29999 ms 22:59:57.123 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - new HttpChannelOverHttp@1a5f1b6{r=0,c=false,a=IDLE,uri=} -> SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,9/30000,null}{io=0,kio=0,kro=0},null,HttpChannelState@1d0ab0c{s=IDLE i=true a=null} 22:59:57.126 [DEBUG] [.eclipse.jetty.server.HttpConnection] - New HTTP Connection HttpConnection@ec8647[IDLE,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,11/30000,null}{io=0,kio=0,kro=0}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=0,c=false,a=IDLE,uri=}] 22:59:57.128 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - onOpen HttpConnection@ec8647[IDLE,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,14/30000,HttpConnection}{io=0,kio=0,kro=0}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=0,c=false,a=IDLE,uri=}] 22:59:57.133 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@ec8647[IDLE,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,15/30000,HttpConnection}{io=0,kio=0,kro=0}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=0,c=false,a=IDLE,uri=}] 22:59:57.136 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - IDLE-->FILL_INTERESTED HttpConnection@ec8647[FILL_INTERESTED,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,22/30000,HttpConnection}{io=0,kio=0,kro=0}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=0,c=false,a=IDLE,uri=}] 22:59:57.138 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,0/30000,HttpConnection}{io=1,kio=0,kro=0} 22:59:57.139 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 22:59:57.141 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Created SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,3/30000,HttpConnection}{io=1,kio=0,kro=0} 22:59:57.143 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 22:59:57.145 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,7/30000,HttpConnection}{io=1,kio=1,kro=0} 22:59:57.146 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 22:59:57.148 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 1/1 selected 22:59:57.150 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 1 -> 0 on SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,12/30000,HttpConnection}{io=1,kio=0,kro=1} 22:59:57.152 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 1 -> 0 for SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,14/30000,HttpConnection}{io=0,kio=0,kro=1} 22:59:57.153 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 22:59:57.156 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING HttpConnection@ec8647[FILLING,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,17/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=0,c=false,a=IDLE,uri=}] 22:59:57.157 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 22:59:57.158 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 22:59:57.158 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@ec8647[FILLING,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,20/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=0,c=false,a=IDLE,uri=}] onFillable HttpChannelState@1d0ab0c{s=IDLE i=true a=null} 22:59:57.159 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 381 SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,22/30000,HttpConnection}{io=0,kio=0,kro=1} 22:59:57.160 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@16d7302[p=0,l=381,c=8192,r=381]={<<<GET /rest/discove....6,en;q=0.4\r\n\r\n>>>2.168.1.96:8080/u...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 22:59:57.161 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - START --> SPACE1 22:59:57.162 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE1 --> URI 22:59:57.162 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - URI --> SPACE2 22:59:57.163 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE2 --> REQUEST_VERSION 22:59:57.163 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - REQUEST_VERSION --> HEADER 22:59:57.164 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_VALUE 22:59:57.165 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:59:57.167 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:59:57.172 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:59:57.173 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:59:57.175 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_VALUE 22:59:57.177 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:59:57.178 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:59:57.180 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_VALUE 22:59:57.181 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:59:57.183 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:59:57.184 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_VALUE 22:59:57.185 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:59:57.187 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:59:57.188 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:59:57.189 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:59:57.190 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_VALUE 22:59:57.192 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:59:57.193 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:59:57.194 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> END 22:59:57.195 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=IDLE,uri=/rest/discovery} messageComplete 22:59:57.201 [DEBUG] [org.eclipse.jetty.server.HttpInput ] - HttpInputOverHTTP@1a10417 EOF 22:59:57.204 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=IDLE,uri=/rest/discovery} handle enter 22:59:57.207 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@1d0ab0c{s=IDLE i=true a=null} handling IDLE 22:59:57.214 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=DISPATCHED,uri=/rest/discovery} action REQUEST_DISPATCH 22:59:57.218 [DEBUG] [org.eclipse.jetty.server.Server ] - REQUEST GET /rest/discovery on HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=DISPATCHED,uri=/rest/discovery} 22:59:57.220 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/discovery]... 22:59:57.223 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/discovery] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-2,name=org.ops4j.pax.web.service.spi.model.ServletModel-2,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@bc5ed1,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-1,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default],contextParams={},virtualHosts={},connectors={}}}} 22:59:57.225 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 22:59:57.227 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 22:59:57.228 [DEBUG] [org.eclipse.jetty.server.session ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@32d70e 22:59:57.229 [DEBUG] [org.eclipse.jetty.server.session ] - session=null 22:59:57.231 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/discovery -> org.ops4j.pax.web.service.spi.model.ServletModel-2@378be0fa==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true 22:59:57.232 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/discovery] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]] 22:59:57.233 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@166f693, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@1596f67 22:59:57.235 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null 22:59:57.244 [DEBUG] [e.internal.WriterInterceptorExecutor] - Message body writer (class com.eclipsesource.jaxrs.provider.gson.GsonProvider) is trying to close the entity output stream. Not closing. 22:59:57.246 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5bfca8[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,13,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1b5d408] generate: NEED_HEADER (null,[p=0,l=13,c=8192,r=13],true)@START 22:59:57.249 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5bfca8[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,13,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1b5d408] generate: FLUSH ([p=0,l=141,c=8192,r=141],[p=0,l=13,c=8192,r=13],true)@COMPLETING 22:59:57.253 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 154 SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,W,93/30000,HttpConnection}{io=0,kio=0,kro=1} 22:59:57.256 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5bfca8[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,13,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1b5d408] generate: DONE ([p=141,l=141,c=8192,r=0],[p=13,l=13,c=8192,r=0],true)@END 22:59:57.259 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ o.e.j.s.h.ContextHandler@190a83a{/static,null,AVAILABLE} 22:59:57.261 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.core.audio_0.9.0.201611041332 [100], contextID=default]} 22:59:57.262 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.9.0.201611041332 [137], contextID=default]} 22:59:57.264 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.9.0.201611041332 [138], contextID=default]} 22:59:57.269 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@1ea92c5} 22:59:57.270 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.9.0.201611041332 [186], contextID=default]} 22:59:57.272 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.classic_0.9.0.201611041332 [187], contextID=default]} 22:59:57.273 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.9.0.201611041332 [188], contextID=default]} 22:59:57.274 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.io.rest.docs_2.0.0.201611062131 [189], contextID=default]} 22:59:57.276 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.0.0.201611062131 [192], contextID=default]} 22:59:57.277 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.0.0.201611062131 [168], contextID=default]} 22:59:57.278 [DEBUG] [org.eclipse.jetty.server.Server ] - RESPONSE /rest/discovery 200 handled=true 22:59:57.279 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@1d0ab0c{s=DISPATCHED i=true a=null} unhandle DISPATCHED 22:59:57.281 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - reset HttpParser{s=END,0 of 0} 22:59:57.281 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - END --> START 22:59:57.283 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=IDLE,uri=} handle exit, result COMPLETE 22:59:57.301 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,45/30000,HttpConnection}{io=0,kio=0,kro=1} 22:59:57.303 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,46/30000,HttpConnection}{io=0,kio=0,kro=1} 22:59:57.304 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@1860225[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 22:59:57.307 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@ec8647[FILLING,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,50/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=IDLE,uri=}] 22:59:57.310 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@ec8647[FILLING_FILL_INTERESTED,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,52/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=IDLE,uri=}] 22:59:57.313 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@ec8647[FILL_INTERESTED,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,55/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=IDLE,uri=}] 22:59:57.315 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,0/30000,HttpConnection}{io=1,kio=0,kro=1} 22:59:57.316 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 22:59:57.318 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/1 selected 22:59:57.320 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 22:59:57.322 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,7/30000,HttpConnection}{io=1,kio=1,kro=1} 22:59:57.323 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 23:00:01.810 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 1/1 selected 23:00:01.812 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 1 -> 0 on SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,4497/30000,HttpConnection}{io=1,kio=0,kro=1} 23:00:01.814 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 1 -> 0 for SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,4499/30000,HttpConnection}{io=0,kio=0,kro=1} 23:00:01.814 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 23:00:01.816 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING HttpConnection@ec8647[FILLING,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,4502/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=IDLE,uri=}] 23:00:01.817 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 23:00:01.818 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 23:00:01.819 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@ec8647[FILLING,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,4504/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=1,c=false,a=IDLE,uri=}] onFillable HttpChannelState@1d0ab0c{s=IDLE i=true a=null} 23:00:01.821 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 525 SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,4506/30000,HttpConnection}{io=0,kio=0,kro=1} 23:00:01.822 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@16d7302[p=0,l=525,c=8192,r=525]={<<<POST /rest/discov...d":"mysensors"}>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 23:00:01.822 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - START --> SPACE1 23:00:01.823 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE1 --> URI 23:00:01.824 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - URI --> SPACE2 23:00:01.824 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE2 --> REQUEST_VERSION 23:00:01.825 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - REQUEST_VERSION --> HEADER 23:00:01.826 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 23:00:01.827 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 23:00:01.828 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 23:00:01.828 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 23:00:01.829 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_VALUE 23:00:01.830 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 23:00:01.831 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 23:00:01.832 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 23:00:01.832 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 23:00:01.833 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_NAME 23:00:01.834 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_NAME --> HEADER_VALUE 23:00:01.835 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 23:00:01.835 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 23:00:01.836 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 23:00:01.837 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 23:00:01.838 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 23:00:01.839 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 23:00:01.839 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_VALUE 23:00:01.840 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 23:00:01.841 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 23:00:01.842 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 23:00:01.843 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER
-
This post is deleted!
-
end of debug log (sorry, post too long)
23:00:01.843 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 23:00:01.844 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 23:00:01.845 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> CONTENT 23:00:01.846 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1a5f1b6{r=2,c=false,a=IDLE,uri=/rest/discovery/bindings/mysensors/scan} handle enter 23:00:01.847 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@1d0ab0c{s=IDLE i=true a=null} handling IDLE 23:00:01.848 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1a5f1b6{r=2,c=false,a=DISPATCHED,uri=/rest/discovery/bindings/mysensors/scan} action REQUEST_DISPATCH 23:00:01.849 [DEBUG] [org.eclipse.jetty.server.Server ] - REQUEST POST /rest/discovery/bindings/mysensors/scan on HttpChannelOverHttp@1a5f1b6{r=2,c=false,a=DISPATCHED,uri=/rest/discovery/bindings/mysensors/scan} 23:00:01.850 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/discovery/bindings/mysensors/scan]... 23:00:01.851 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/discovery/bindings/mysensors/scan] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-2,name=org.ops4j.pax.web.service.spi.model.ServletModel-2,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@bc5ed1,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-1,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default],contextParams={},virtualHosts={},connectors={}}}} 23:00:01.852 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 23:00:01.853 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 23:00:01.853 [DEBUG] [org.eclipse.jetty.server.session ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@32d70e 23:00:01.854 [DEBUG] [org.eclipse.jetty.server.session ] - session=null 23:00:01.855 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/discovery/bindings/mysensors/scan -> org.ops4j.pax.web.service.spi.model.ServletModel-2@378be0fa==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true 23:00:01.856 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/discovery/bindings/mysensors/scan] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]] 23:00:01.857 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@a929e4, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@13d3535 23:00:01.857 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null 23:00:01.872 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5bfca8[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,4,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1866dfa] generate: NEED_HEADER (null,[p=0,l=4,c=8192,r=4],true)@START 23:00:01.874 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5bfca8[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,4,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1866dfa] generate: FLUSH ([p=0,l=134,c=8192,r=134],[p=0,l=4,c=8192,r=4],true)@COMPLETING 23:00:01.875 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 138 SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,W,54/30000,HttpConnection}{io=0,kio=0,kro=1} 23:00:01.877 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@5bfca8[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,4,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1866dfa] generate: DONE ([p=134,l=134,c=8192,r=0],[p=4,l=4,c=8192,r=0],true)@END 23:00:01.879 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ o.e.j.s.h.ContextHandler@190a83a{/static,null,AVAILABLE} 23:00:01.880 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.core.audio_0.9.0.201611041332 [100], contextID=default]} 23:00:01.881 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.9.0.201611041332 [137], contextID=default]} 23:00:01.882 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.9.0.201611041332 [138], contextID=default]} 23:00:01.883 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@1ea92c5} 23:00:01.884 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.9.0.201611041332 [186], contextID=default]} 23:00:01.884 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.classic_0.9.0.201611041332 [187], contextID=default]} 23:00:01.885 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.9.0.201611041332 [188], contextID=default]} 23:00:01.886 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.io.rest.docs_2.0.0.201611062131 [189], contextID=default]} 23:00:01.887 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.0.0.201611062131 [192], contextID=default]} 23:00:01.888 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/mysensors/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.0.0.201611062131 [168], contextID=default]} 23:00:01.889 [DEBUG] [org.eclipse.jetty.server.Server ] - RESPONSE /rest/discovery/bindings/mysensors/scan 200 handled=true 23:00:01.891 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@1d0ab0c{s=DISPATCHED i=true a=null} unhandle DISPATCHED 23:00:01.893 [DEBUG] [.eclipse.jetty.server.HttpConnection] - unconsumed input HttpConnection@ec8647[FILLING,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,16/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=CONTENT,0 of 25},g=HttpGenerator{s=END},c=HttpChannelOverHttp@1a5f1b6{r=2,c=true,a=COMPLETED,uri=/rest/discovery/bindings/mysensors/scan}] 23:00:01.894 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=CONTENT HeapByteBuffer@16d7302[p=500,l=525,c=8192,r=25]={POST /rest/discov....6,en;q=0.4\r\n\r\n<<<{"bindingId":"mysensors"}>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 23:00:01.895 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1a5f1b6{r=2,c=true,a=COMPLETED,uri=/rest/discovery/bindings/mysensors/scan} content java.nio.HeapByteBufferR[pos=500 lim=525 cap=8192] 23:00:01.897 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=CONTENT HeapByteBuffer@16d7302[p=525,l=525,c=8192,r=0]={POST /rest/discov...d":"mysensors"}<<<>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 23:00:01.897 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - CONTENT --> END 23:00:01.898 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1a5f1b6{r=2,c=true,a=COMPLETED,uri=/rest/discovery/bindings/mysensors/scan} messageComplete 23:00:01.899 [DEBUG] [org.eclipse.jetty.server.HttpInput ] - HttpInputOverHTTP@1a10417 EOF 23:00:01.901 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,24/30000,HttpConnection}{io=0,kio=0,kro=1} 23:00:01.903 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@ec8647[FILLING,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,26/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,25 of 25},g=HttpGenerator{s=END},c=HttpChannelOverHttp@1a5f1b6{r=2,c=true,a=COMPLETED,uri=/rest/discovery/bindings/mysensors/scan}] filled 0 23:00:01.904 [DEBUG] [org.eclipse.jetty.server.HttpInput ] - HttpInputOverHTTP@1a10417 eof EOF 23:00:01.905 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - reset HttpParser{s=END,25 of 25} 23:00:01.905 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - END --> START 23:00:01.907 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1a5f1b6{r=2,c=false,a=IDLE,uri=} handle exit, result COMPLETE 23:00:01.908 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,31/30000,HttpConnection}{io=0,kio=0,kro=1} 23:00:01.909 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,33/30000,HttpConnection}{io=0,kio=0,kro=1} 23:00:01.910 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@1860225[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 23:00:01.912 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@ec8647[FILLING,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,36/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=2,c=false,a=IDLE,uri=}] 23:00:01.914 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@ec8647[FILLING_FILL_INTERESTED,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,38/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=2,c=false,a=IDLE,uri=}] 23:00:01.917 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@ec8647[FILL_INTERESTED,SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,-,-,40/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@1a5f1b6{r=2,c=false,a=IDLE,uri=}] 23:00:01.918 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,1/30000,HttpConnection}{io=1,kio=0,kro=1} 23:00:01.919 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 23:00:01.920 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/1 selected 23:00:01.923 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@b87e8a 23:00:01.925 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@15c7d05{/192.168.1.9:45427<->8080,Open,in,out,R,-,7/30000,HttpConnection}{io=1,kio=1,kro=1} 23:00:01.926 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 23:00:09.067 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers 23:00:11.859 [DEBUG] [io.socket.engineio.client.Socket ] - writing ping packet - expecting pong within 60000ms 23:00:11.859 [DEBUG] [io.socket.engineio.client.Socket ] - flushing 1 packets in socket 23:00:11.904 [DEBUG] [io.socket.engineio.client.Socket ] - socket received: type 'pong', data 'null'
192.168.1.9 is the computer (ubuntu) from which i do ssh...And this is the log during "idle"
23:02:48.131 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers 23:02:55.692 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@10fcc9b{/192.168.1.9:45209<->8080,Open,in,out,-,-,30000/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 23:02:55.693 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@10fcc9b{/192.168.1.9:45209<->8080,Open,in,out,-,-,30002/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout expired 23:02:55.695 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SelectChannelEndPoint@10fcc9b{/192.168.1.9:45209<->8080,Open,in,out,-,-,30003/30000,HttpConnection}{io=0,kio=0,kro=1} 23:03:08.614 [DEBUG] [io.socket.engineio.client.Socket ] - writing ping packet - expecting pong within 60000ms 23:03:08.614 [DEBUG] [io.socket.engineio.client.Socket ] - flushing 1 packets in socket 23:03:08.655 [DEBUG] [io.socket.engineio.client.Socket ] - socket received: type 'pong', data 'null'
-
@ben999 My bad sorry, your first log already contained the debug information.
To which serial port is the gateway connected? I see that it connected, but I'm missing a few messages that should appear. Could you please specify the bridge / gateway (only the gateway) in the *.things file according to the description in the wiki and see if that works?
We should see the messages the gateway receives in the debug output.
-
I have almost the same problem. Items don't get added to the inbox when a new node is found.
Also when i add them manualy, things don't work(it was working earlier) So i think something changed for the bindings. So at the moment the MySensors binding is not working with latest snapshot(#597)My Debug log:
2016-11-20 01:43:15.119 [DEBUG] [ernal.handler.MySensorsBridgeHandler] - Initialization of the MySensors bridge 2016-11-20 01:43:15.119 [DEBUG] [col.serial.MySensorsSerialConnection] - Set skip check on startup to: false 2016-11-20 01:43:15.119 [DEBUG] [col.serial.MySensorsSerialConnection] - Enabling connection watchdog 2016-11-20 01:43:15.126 [DEBUG] [ternal.factory.MySensorsCacheFactory] - Cache file: given_ids.cached exist. 2016-11-20 01:43:15.126 [DEBUG] [ternal.factory.MySensorsCacheFactory] - Cache (given_ids) content: [32, 52, 51, 40, 36, 35] 2016-11-20 01:43:15.126 [DEBUG] [ernal.handler.MySensorsBridgeHandler] - Initialization of the MySensors bridge DONE! 2016-11-20 01:43:15.127 [DEBUG] [col.serial.MySensorsSerialConnection] - Connecting to /dev/ttyUSB21 [baudRate:9600] 2016-11-20 01:43:15.127 [DEBUG] [col.serial.MySensorsSerialConnection] - Final port list: /dev/ttyUSB21 2016-11-20 01:43:15.133 [DEBUG] [col.serial.MySensorsSerialConnection] - Successfully connected to serial port. 2016-11-20 01:43:15.133 [DEBUG] [col.serial.MySensorsSerialConnection] - Waiting 3 seconds to allow correct reset trigger on serial connection opening 2016-11-20 01:43:18.235 [DEBUG] [rs.internal.protocol.MySensorsWriter] - Sending to MySensors: 0;0;3;0;2; 2016-11-20 01:43:18.246 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;2;2.0.0 2016-11-20 01:43:18.246 [DEBUG] [col.serial.MySensorsSerialConnection] - Good,Gateway is up and running! (Ver:2.0.0) 2016-11-20 01:43:18.247 [INFO ] [col.serial.MySensorsSerialConnection] - Successfully connected to MySensors Bridge. 2016-11-20 01:43:18.247 [WARN ] [col.serial.MySensorsSerialConnection] - Network Sanity Checker thread disabled from bridge configuration 2016-11-20 01:43:18.258 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 32, chiledId: 0, revertState: false 2016-11-20 01:43:18.259 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 35, chiledId: 0, revertState: false 2016-11-20 01:43:18.259 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 51, chiledId: 3, revertState: false 2016-11-20 01:43:18.259 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 52, chiledId: 3, revertState: false 2016-11-20 01:43:18.259 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 35, chiledId: 3, revertState: false 2016-11-20 01:43:18.260 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 40, chiledId: 0, revertState: true 2016-11-20 01:43:18.260 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 36, chiledId: 3, revertState: false 2016-11-20 01:43:18.260 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 36, chiledId: 1, revertState: false 2016-11-20 01:43:18.260 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 36, chiledId: 0, revertState: false 2016-11-20 01:43:18.260 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 32, chiledId: 3, revertState: false 2016-11-20 01:43:18.261 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 52, chiledId: 4, revertState: false 2016-11-20 01:43:18.261 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 52, chiledId: 5, revertState: false 2016-11-20 01:43:18.262 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 32, chiledId: 1, revertState: false 2016-11-20 01:43:18.262 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 35, chiledId: 1, revertState: false 2016-11-20 01:43:18.263 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 51, chiledId: 4, revertState: false 2016-11-20 01:43:20.379 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:SANCHK:OK 2016-11-20 01:43:28.247 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-11-20 01:43:20.379 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:SANCHK:OK 2016-11-20 01:43:28.247 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-11-20 01:43:38.248 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-11-20 01:43:48.248 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-11-20 01:43:58.249 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-11-20 01:44:01.834 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 32-32-0 s=255,c=3,t=22,pt=5,l=4,sg=0:19562725 2016-11-20 01:44:01.845 [DEBUG] [rs.internal.protocol.MySensorsReader] - 32;255;3;0;22;19562725 2016-11-20 01:44:02.591 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 32-32-0 s=1,c=1,t=0,pt=7,l=5,sg=0:18.2 2016-11-20 01:44:02.591 [DEBUG] [rs.internal.protocol.MySensorsReader] - 32;1;1;0;0;18.2 2016-11-20 01:44:02.683 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 32-32-0 s=2,c=1,t=4,pt=7,l=5,sg=0:1000.8 2016-11-20 01:44:02.683 [DEBUG] [rs.internal.protocol.MySensorsReader] - 32;2;1;0;4;1000.8 2016-11-20 01:44:02.764 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 32-32-0 s=0,c=1,t=1,pt=7,l=5,sg=0:52.1 2016-11-20 01:44:02.774 [DEBUG] [rs.internal.protocol.MySensorsReader] - 32;0;1;0;1;52.1 2016-11-20 01:44:02.855 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 32-32-0 s=2,c=1,t=5,pt=0,l=6,sg=0:cloudy 2016-11-20 01:44:02.856 [DEBUG] [rs.internal.protocol.MySensorsReader] - 32;2;1;0;5;cloudy 2016-11-20 01:44:08.249 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-11-20 01:44:18.249 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-11-20 01:44:20.380 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:SANCHK:OK 2016-11-20 01:44:27.370 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 52-52-0 s=4,c=1,t=0,pt=7,l=5,sg=0:15.1 2016-11-20 01:44:27.370 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:ACK msg 2016-11-20 01:44:27.380 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:SEND 0-0-52-52 s=4,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=ok:15.1 2016-11-20 01:44:27.381 [DEBUG] [rs.internal.protocol.MySensorsReader] - 52;4;1;0;0;15.1 2016-11-20 01:44:27.522 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 52-52-0 s=3,c=1,t=1,pt=7,l=5,sg=0:49.3 2016-11-20 01:44:27.522 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:ACK msg 2016-11-20 01:44:27.563 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;!TSP:MSG:SEND 0-0-52-52 s=3,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=ok:49.3 2016-11-20 01:44:27.564 [DEBUG] [rs.internal.protocol.MySensorsReader] - 52;3;1;0;1;49.3 2016-11-20 01:44:28.250 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-11-20 01:44:30.102 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 51-51-0 s=4,c=1,t=0,pt=7,l=5,sg=0:4.5 2016-11-20 01:44:30.102 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:ACK msg 2016-11-20 01:44:30.113 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:SEND 0-0-51-51 s=4,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=ok:4.5 2016-11-20 01:44:30.113 [DEBUG] [rs.internal.protocol.MySensorsReader] - 51;4;1;0;0;4.5 2016-11-20 01:44:30.204 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 51-51-0 s=3,c=1,t=1,pt=7,l=5,sg=0:91.8 2016-11-20 01:44:30.204 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:ACK msg 2016-11-20 01:44:30.255 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;!TSP:MSG:SEND 0-0-51-51 s=3,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=fail:91.8 2016-11-20 01:44:30.255 [DEBUG] [rs.internal.protocol.MySensorsReader] - 51;3;1;0;1;91.8 2016-11-20 01:44:38.250 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-11-20 01:44:42.816 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 35-35-0 s=255,c=3,t=0,pt=1,l=1,sg=0:57 2016-11-20 01:44:42.826 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:ACK msg 2016-11-20 01:44:42.826 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:SEND 0-0-35-35 s=255,c=3,t=0,pt=1,l=1,sg=0,ft=0,st=ok:57 2016-11-20 01:44:42.837 [DEBUG] [rs.internal.protocol.MySensorsReader] - 35;255;3;0;0;57 2016-11-20 01:44:42.908 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 35-35-0 s=3,c=1,t=38,pt=7,l=5,sg=0:2.6600 2016-11-20 01:44:42.908 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:ACK msg 2016-11-20 01:44:42.918 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:SEND 0-0-35-35 s=3,c=1,t=38,pt=7,l=5,sg=0,ft=0,st=ok:2.6600 2016-11-20 01:44:42.919 [DEBUG] [rs.internal.protocol.MySensorsReader] - 35;3;1;0;38;2.6600 2016-11-20 01:44:43.040 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:READ 35-35-0 s=1,c=1,t=0,pt=7,l=5,sg=0:18.9 2016-11-20 01:44:43.040 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;TSP:MSG:ACK msg 2016-11-20 01:44:43.081 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;9;!TSP:MSG:SEND 0-0-35-35 s=1,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=ok:18.9 2016-11-20 01:44:43.081 [DEBUG] [rs.internal.protocol.MySensorsReader] - 35;1;1;0;0;18.9As you can see, i recieve messages from the gateway. No items are updated.
-
This post is deleted!
-
@FutureCow Strange, I'll look into it. I suppose I've introduced this bug with the latest refactoring.
-
@TimO
Hi Tim
Thanks a lot for your message.
NRF is connected to GPIO pins as per https://www.mysensors.org/build/raspberry
I have rundmesg |grep -i ttyas suggested by @Yveaux and i got[ 0.000000] Kernel command line: 8250.nr_uarts=0 dma.dmachans=0x7f35 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2709.boardrev=0xa02082 bcm2709.serial=0xcc3e67e3 smsc95xx.macaddr=B8:27:EB:3E:67:E3 bcm2708_fb.fbswap=1 bcm2709.uart_clock=48000000 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait [ 0.001337] console [tty1] enabled [ 1.781491] 3f201000.uart: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2I assumed that my serail port would be ttyAMA0
So i wrote my "home.things" file with it:Bridge mysensors:bridge-ser:gateway [ serialPort="/dev/ttyAMA0", sendDelay=200 ] { /** define things connected to that bridge here */ }This
pi@raspberrypi:~ $ screen /dev/ttyAMA0 115200returns that[screen is terminating]Also, when watching the live output from
sudo ./bin/mysgw -di can see thatpi@raspberrypi:~ $ cd MySensors pi@raspberrypi:~/MySensors $ sudo ./bin/mysgw -d mysgw: Starting gateway... mysgw: Protocol version - 2.0.1-beta mysgw: MCO:BGN:INIT GW,CP=RNNG---,VER=2.0.1-beta mysgw: TSF:LRT:OK mysgw: TSM:INIT mysgw: TSM:INIT:TSP OK mysgw: TSM:INIT:GW MODE mysgw: TSM:READY mysgw: Listening for connections on 0.0.0.0:5003but when i launch openhab with
sudo ./start_debug.shi seeLaunching the openHAB runtime... Listening for transport dt_socket at address: 50055003 vs 5005... I tried that
pi@raspberrypi:~/MySensors $ ./configure --my-port=5005but no luck, I got that in return...mysgw: accept: Bad file descriptor mysgw: accept: Bad file descriptorgoing forever
