Data from MQTT Gateway not pushing to OpenHab



  • I have been debugging this for 2 days and just cannot see the problem. Hopefully someone can see it right away.

    My configuration is OpenHab on Windows with MQTT addon enabled. The gw is the MQTT ethernet gateway and I am following the instructions to see the sketch name. Right now the main point is to get the data through to OpenHab so I can see my first sensor show up. The node is a temperature sensor from the mySensors examples.

    Why will the openhab test not show any data? What am I missing?

    The node and gateway see each other and report perfectly (see below)

    Started!
    0;0;3;0;9;read: 21-21-0 s=255,c=0,t=17,pt=0,l=5:1.4.1
    0;0;3;0;9;read: 21-21-0 s=255,c=3,t=6,pt=1,l=1:0
    0;0;3;0;9;read: 21-21-0 s=255,c=3,t=11,pt=0,l=19:Max6675 Temp Sensor

    0;0;3;0;9;read: 21-21-0 s=255,c=3,t=12,pt=0,l=3:1.0
    0;0;3;0;9;read: 21-21-0 s=0,c=0,t=6,pt=0,l=5:1.4.1
    0;0;3;0;9;read: 21-21-0 s=0,c=1,t=0,pt=7,l=5:150.0
    <<10 12 00 06 4D 51 49 73 64 70 03 02 00 3C 00 04 4D 51 54 54

    20 02 00 00
    <<C0 00

    D0 00
    <<C0 00

    D0 00
    <<C0 00

    D0 00

    The log from OpenHab says it connects to the GW (see below)

    17:55:36.547 [DEBUG] [.s.internal.SchedulerActivator:36 ] - Scheduler has been started.
    17:55:36.641 [INFO ] [uartz.impl.StdSchedulerFactory:1175 ] - Using default implementation for ThreadExecutor
    17:55:36.672 [INFO ] [rtz.core.SchedulerSignalerImpl:61 ] - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
    17:55:36.688 [INFO ] [rg.quartz.core.QuartzScheduler:243 ] - Quartz Scheduler v.2.1.7 created.
    17:55:36.688 [INFO ] [org.quartz.simpl.RAMJobStore :154 ] - RAMJobStore initialized.
    17:55:36.688 [INFO ] [rg.quartz.core.QuartzScheduler:268 ] - Scheduler meta-data: Quartz Scheduler (v2.1.7) 'openHAB-job-scheduler' with instanceId 'NON_CLUSTERED'
    Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
    NOT STARTED.
    Currently in standby mode.
    Number of jobs executed: 0
    Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 2 threads.
    Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

    17:55:36.688 [INFO ] [uartz.impl.StdSchedulerFactory:1324 ] - Quartz scheduler 'openHAB-job-scheduler' initialized from specified file: './etc/quartz.properties'
    17:55:36.688 [INFO ] [uartz.impl.StdSchedulerFactory:1328 ] - Quartz scheduler version: 2.1.7
    17:55:36.688 [INFO ] [rg.quartz.core.QuartzScheduler:534 ] - Scheduler openHAB-job-scheduler_$_NON_CLUSTERED started.
    17:55:36.703 [DEBUG] [o.config.core.ConfigDispatcher:146 ] - Processing openHAB default configuration file 'C:\OpenHab\configurations\openhab_default.cfg'.
    17:55:36.703 [DEBUG] [o.config.core.ConfigDispatcher:168 ] - Processing openHAB main configuration file 'C:\OpenHab\configurations\openhab.cfg'.
    17:55:38.419 [DEBUG] [.o.core.internal.CoreActivator:93 ] - UUID file already exists at 'C:\OpenHab\webapps\static\uuid' with content '15c7367f-a4cf-4ec2-ae9d-84943f4c6a79'
    17:55:38.451 [DEBUG] [.o.core.internal.CoreActivator:115 ] - Created file 'C:\OpenHab\webapps\static\version' with content '1.6.2'
    17:55:38.451 [INFO ] [.o.core.internal.CoreActivator:61 ] - openHAB runtime has been started (v1.6.2).
    17:55:38.544 [DEBUG] [a.internal.AutoUpdateActivator:31 ] - AutoUpdate binding has been started.
    17:55:40.541 [DEBUG] [.p.i.PersistenceModelActivator:23 ] - Registered 'persistence' configuration parser
    17:55:40.915 [DEBUG] [.c.t.i.TransformationActivator:38 ] - Transformation Service has been started.
    17:55:41.227 [DEBUG] [m.internal.MultimediaActivator:34 ] - Multimedia I/O bundle has been started.
    17:55:41.539 [DEBUG] [.s.i.DiscoveryServiceActivator:27 ] - Discovery service has been started.
    17:55:41.555 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl:93 ] - mDNS service has been started
    17:55:41.867 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl:53 ] - Service Discovery initialization completed.
    17:55:41.945 [DEBUG] [.io.transport.mqtt.MqttService:123 ] - Starting MQTT Service...
    17:55:41.961 [INFO ] [.io.transport.mqtt.MqttService:108 ] - MQTT Service initialization completed.
    17:55:41.961 [INFO ] [o.i.t.m.i.MqttBrokerConnection:114 ] - Starting MQTT broker connection 'mysensors'
    17:55:41.961 [DEBUG] [o.i.t.m.i.MqttBrokerConnection:294 ] - Creating new client for 'tcp://192.168.001.140:1883' using id 'MQTT' and file store 'C:\Users\HP\AppData\Local\Temp/mysensors'
    17:55:42.117 [DEBUG] [.i.internal.ItemModelActivator:24 ] - Registered 'item' configuration parser
    17:55:42.226 [DEBUG] [o.o.c.i.items.ItemRegistryImpl:137 ] - Item provider 'GenericItemProvider' has been added.
    17:55:43.240 [DEBUG] [.o.m.s.i.SitemapModelActivator:23 ] - Registered 'sitemap' configuration parser
    17:55:45.003 [DEBUG] [io.rest.internal.RESTActivator:33 ] - REST API has been started.
    17:55:45.112 [INFO ] [sphere.cpr.AtmosphereFramework:2175 ] - Atmosphere is using org.atmosphere.cpr.DefaultAnnotationProcessor for processing annotation
    17:55:45.112 [INFO ] [cpr.DefaultAnnotationProcessor:131 ] - AnnotationProcessor class org.atmosphere.cpr.DefaultAnnotationProcessor$BytecodeBasedAnnotationProcessor being used
    17:55:45.143 [ERROR] [sphere.cpr.AtmosphereFramework:2215 ] -
    java.lang.ClassCastException: org.eclipse.osgi.framework.internal.core.BundleURLConnection cannot be cast to java.net.JarURLConnection
    at org.atmosphere.util.annotation.AnnotationDetector.detect(AnnotationDetector.java:325) ~[atmosphere-runtime-2.0.9.jar:2.0.9]
    at org.atmosphere.cpr.DefaultAnnotationProcessor.scanForAnnotation(DefaultAnnotationProcessor.java:148) ~[atmosphere-runtime-2.0.9.jar:2.0.9]
    at org.atmosphere.cpr.DefaultAnnotationProcessor.configure(DefaultAnnotationProcessor.java:134) ~[atmosphere-runtime-2.0.9.jar:2.0.9]
    at org.atmosphere.cpr.AtmosphereFramework.autoConfigureService(AtmosphereFramework.java:2177) [atmosphere-runtime-2.0.9.jar:2.0.9]
    at org.atmosphere.cpr.AtmosphereFramework.init(AtmosphereFramework.java:655) [atmosphere-runtime-2.0.9.jar:2.0.9]
    at org.atmosphere.cpr.AtmosphereFramework.init(AtmosphereFramework.java:568) [atmosphere-runtime-2.0.9.jar:2.0.9]
    at org.atmosphere.cpr.AtmosphereServlet.configureFramework(AtmosphereServlet.java:91) [atmosphere-runtime-2.0.9.jar:2.0.9]
    at org.atmosphere.cpr.AtmosphereServlet.init(AtmosphereServlet.java:80) [atmosphere-runtime-2.0.9.jar:2.0.9]
    at org.eclipse.equinox.http.servlet.internal.ServletRegistration.init(ServletRegistration.java:49) [org.eclipse.equinox.http.servlet_1.1.300.v20120522-1841.jar:na]
    at org.eclipse.equinox.http.servlet.internal.ProxyServlet.registerServlet(ProxyServlet.java:179) [org.eclipse.equinox.http.servlet_1.1.300.v20120522-1841.jar:na]
    at org.eclipse.equinox.http.servlet.internal.HttpServiceImpl.registerServlet(HttpServiceImpl.java:66) [org.eclipse.equinox.http.servlet_1.1.300.v20120522-1841.jar:na]
    at org.openhab.io.rest.RESTApplication.activate(RESTApplication.java:140) [org.openhab.io.rest_1.6.2.jar:na]
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_31]
    at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_31]
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_31]
    at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0_31]
    at org.eclipse.equinox.internal.ds.model.ServiceComponent.activate(ServiceComponent.java:235) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.model.ServiceComponentProp.activate(ServiceComponentProp.java:146) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.model.ServiceComponentProp.build(ServiceComponentProp.java:345) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.InstanceProcess.buildComponent(InstanceProcess.java:620) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.InstanceProcess.buildComponents(InstanceProcess.java:197) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.Resolver.getEligible(Resolver.java:343) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.SCRManager.serviceChanged(SCRManager.java:222) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:107) [org.eclipse.osgi_3.8.2.v20130124-134944.jar:na]
    at org.eclipse.osgi.framework.internal.core.BundleContextImpl.dispatchEvent(BundleContextImpl.java:861) [org.eclipse.osgi_3.8.2.v20130124-134944.jar:na]
    at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [org.eclipse.osgi_3.8.2.v20130124-134944.jar:na]
    at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [org.eclipse.osgi_3.8.2.v20130124-134944.jar:na]
    at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:819) [org.eclipse.osgi_3.8.2.v20130124-134944.jar:na]
    at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:771) [org.eclipse.osgi_3.8.2.v20130124-134944.jar:na]
    at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.register(ServiceRegistrationImpl.java:130) [org.eclipse.osgi_3.8.2.v20130124-134944.jar:na]
    at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.registerService(ServiceRegistry.java:214) [org.eclipse.osgi_3.8.2.v20130124-134944.jar:na]
    at org.eclipse.osgi.framework.internal.core.BundleContextImpl.registerService(BundleContextImpl.java:433) [org.eclipse.osgi_3.8.2.v20130124-134944.jar:na]
    at org.eclipse.equinox.internal.ds.InstanceProcess.registerService(InstanceProcess.java:536) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.InstanceProcess.buildComponents(InstanceProcess.java:260) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.Resolver.buildNewlySatisfied(Resolver.java:473) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.Resolver.enableComponents(Resolver.java:217) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.SCRManager.performWork(SCRManager.java:816) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.SCRManager$QueuedJob.dispatch(SCRManager.java:783) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.ds.WorkThread.run(WorkThread.java:89) [org.eclipse.equinox.ds_1.4.1.v20120926-201320.jar:na]
    at org.eclipse.equinox.internal.util.impl.tpt.threadpool.Executor.run(Executor.java:70) [org.eclipse.equinox.util_1.0.400.v20120917-192807.jar:na]
    17:55:45.174 [INFO ] [sphere.cpr.AtmosphereFramework:1506 ] - Auto detecting atmosphere handlers /WEB-INF/classes/
    17:55:45.190 [WARN ] [sphere.cpr.AtmosphereFramework:1154 ] - Missing META-INF/atmosphere.xml but found the Jersey runtime. Starting Jersey
    17:55:45.268 [INFO ] [sphere.cpr.AtmosphereFramework:434 ] - Installed AtmosphereHandler org.atmosphere.handler.ReflectorServletProcessor mapped to context-path: /*
    17:55:45.268 [INFO ] [sphere.cpr.AtmosphereFramework:1561 ] - Auto detecting WebSocketHandler in /WEB-INF/classes/
    17:55:45.268 [INFO ] [sphere.cpr.AtmosphereFramework:1259 ] - Installed WebSocketProtocol org.atmosphere.websocket.protocol.SimpleHttpProtocol
    17:55:45.377 [INFO ] [.a.h.ReflectorServletProcessor:140 ] - Installing Servlet com.sun.jersey.spi.container.servlet.ServletContainer
    17:55:45.564 [INFO ] [c.s.j.s.i.a.WebApplicationImpl:790 ] - Initiating Jersey application, version 'Jersey: 1.17.1 02/28/2013 12:47 PM'
    17:55:45.564 [INFO ] [c.s.j.s.i.a.WebApplicationImpl:801 ] - Adding the following classes declared in META-INF/services/jersey-server-components to the resource configuration:
    class org.atmosphere.jersey.AtmosphereResourceConfigurator
    17:55:45.627 [INFO ] [j.s.i.a.DeferredResourceConfig:101 ] - Instantiated the Application class org.openhab.io.rest.RESTApplication
    17:55:46.734 [INFO ] [sphere.cpr.AtmosphereFramework:2014 ] - Installed AtmosphereInterceptor Default Response's Headers Interceptor with priority AFTER_DEFAULT
    17:55:46.750 [INFO ] [sphere.cpr.AtmosphereFramework:2014 ] - Installed AtmosphereInterceptor Android Interceptor Support with priority AFTER_DEFAULT
    17:55:46.750 [INFO ] [sphere.cpr.AtmosphereFramework:2014 ] - Installed AtmosphereInterceptor SSE Interceptor Support with priority AFTER_DEFAULT
    17:55:46.750 [INFO ] [sphere.cpr.AtmosphereFramework:2014 ] - Installed AtmosphereInterceptor JSONP Interceptor Support with priority AFTER_DEFAULT
    17:55:46.750 [INFO ] [sphere.cpr.AtmosphereFramework:2014 ] - Installed AtmosphereInterceptor Atmosphere JavaScript Protocol with priority AFTER_DEFAULT
    17:55:46.750 [INFO ] [sphere.cpr.AtmosphereFramework:2014 ] - Installed AtmosphereInterceptor Browser disconnection detection with priority AFTER_DEFAULT
    17:55:46.750 [INFO ] [sphere.cpr.AtmosphereFramework:826 ] - Installing Default AtmosphereInterceptor
    17:55:46.765 [WARN ] [sphere.cpr.AtmosphereFramework:703 ] - No BroadcasterCache configured. Broadcasted message between client reconnection will be LOST. It is recommended to configure the org.atmosphere.cache.UUIDBroadcasterCache
    17:55:46.765 [INFO ] [sphere.cpr.AtmosphereFramework:711 ] - Broadcaster Polling Wait Time 100
    17:55:46.765 [INFO ] [sphere.cpr.AtmosphereFramework:712 ] - Shared ExecutorService supported: true
    17:55:46.765 [INFO ] [sphere.cpr.AtmosphereFramework:713 ] - HttpSession supported: false
    17:55:46.765 [INFO ] [sphere.cpr.AtmosphereFramework:714 ] - Using BroadcasterFactory: org.atmosphere.cpr.DefaultBroadcasterFactory
    17:55:46.765 [INFO ] [sphere.cpr.AtmosphereFramework:715 ] - Using WebSocketProcessor: org.atmosphere.websocket.DefaultWebSocketProcessor
    17:55:46.765 [INFO ] [sphere.cpr.AtmosphereFramework:716 ] - Using Broadcaster: org.atmosphere.jersey.JerseyBroadcaster
    17:55:46.781 [INFO ] [sphere.cpr.AtmosphereFramework:717 ] - Atmosphere is using async support: org.atmosphere.container.JettyAsyncSupportWithWebSocket running under container: jetty/8.1.3.v20120522 with WebSocket enabled.
    17:55:46.781 [INFO ] [sphere.cpr.AtmosphereFramework:719 ] - Atmosphere Framework 2.0.9 started.
    17:55:46.781 [INFO ] [sphere.cpr.AtmosphereFramework:721 ] -

    For Atmosphere Framework Commercial Support, visit 
    http://www.async-io.org/ or send an email to support@async-io.org
    

    17:55:46.781 [INFO ] [penhab.io.rest.RESTApplication:143 ] - Started REST API at /rest
    17:55:46.781 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66 ] - Registering new service _openhab-server._tcp.local. at port 8080
    17:55:47.171 [INFO ] [sphere.cpr.AtmosphereFramework:774 ] - Latest version of Atmosphere's JavaScript Client 2.0.9
    17:55:47.171 [INFO ] [sphere.cpr.AtmosphereFramework:781 ] -

    Atmosphere Framework Updates
    Minor available (bugs fixes): 2.0.10
    Major available (new features): 2.1.10
    

    17:55:49.558 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66 ] - Registering new service _openhab-server-ssl._tcp.local. at port 8443
    17:55:50.525 [INFO ] [c.internal.ModelRepositoryImpl:79 ] - Loading model 'test.sitemap'
    17:55:50.759 [INFO ] [c.internal.ModelRepositoryImpl:79 ] - Loading model 'db4o.persist'
    17:55:50.806 [INFO ] [c.internal.ModelRepositoryImpl:79 ] - Loading model 'exec.persist'
    17:55:50.837 [INFO ] [c.internal.ModelRepositoryImpl:79 ] - Loading model 'logging.persist'
    17:55:50.868 [INFO ] [c.internal.ModelRepositoryImpl:79 ] - Loading model 'rrd4j.persist'
    17:55:50.915 [INFO ] [c.internal.ModelRepositoryImpl:79 ] - Loading model 'test.items'
    17:55:50.962 [DEBUG] [i.internal.GenericItemProvider:154 ] - Processing binding configs for items from model 'test.items'
    17:55:50.962 [DEBUG] [i.internal.GenericItemProvider:133 ] - Read items from model 'test.items'
    17:55:52.475 [INFO ] [.o.u.w.i.servlet.WebAppServlet:79 ] - Started Classic UI at /openhab.app
    17:55:52.912 [DEBUG] [.r.internal.RuleModelActivator:42 ] - Registered 'rules' configuration parser
    17:55:52.959 [DEBUG] [m.r.internal.engine.RuleEngine:78 ] - Started rule engine
    17:55:59.776 [DEBUG] [.b.mqtt.internal.MqttActivator:34 ] - MQTT binding has been started.
    17:55:59.791 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'node1_temp (Type=NumberItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.791 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'node1_temp' : 1 subscribers, 0 publishers
    17:55:59.791 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'node1_humid (Type=NumberItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.807 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'node1_humid' : 1 subscribers, 0 publishers
    17:55:59.807 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'node2_sw1 (Type=SwitchItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.807 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'node2_sw1' : 0 subscribers, 2 publishers
    17:55:59.807 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'node2_sw2 (Type=SwitchItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.838 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'node2_sw2' : 1 subscribers, 2 publishers
    17:55:59.838 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'sketch20 (Type=StringItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.838 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'sketch20' : 1 subscribers, 0 publishers
    17:55:59.838 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'sketch21 (Type=StringItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.854 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'sketch21' : 1 subscribers, 0 publishers
    17:55:59.854 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'sketch22 (Type=StringItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.854 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'sketch22' : 1 subscribers, 0 publishers
    17:55:59.854 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'sketch23 (Type=StringItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.854 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'sketch23' : 1 subscribers, 0 publishers
    17:55:59.854 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'sketch24 (Type=StringItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.854 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'sketch24' : 1 subscribers, 0 publishers
    17:55:59.854 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'sketch25 (Type=StringItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.854 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'sketch25' : 1 subscribers, 0 publishers
    17:55:59.854 [DEBUG] [i.internal.GenericItemProvider:334 ] - Start processing binding configuration of Item 'sketch26 (Type=StringItem, State=Uninitialized)' with 'MqttGenericBindingProvider' reader.
    17:55:59.869 [DEBUG] [b.mqtt.internal.MqttItemConfig:71 ] - Loaded MQTT config for item 'sketch26' : 1 subscribers, 0 publishers
    17:55:59.869 [DEBUG] [m.internal.MqttEventBusBinding:61 ] - MQTT: Activating event bus binding.
    17:55:59.869 [DEBUG] [.o.b.ntp.internal.NtpActivator:31 ] - NTP binding has been started.
    17:55:59.901 [INFO ] [.service.AbstractActiveService:169 ] - NTP Refresh Service has been started
    17:55:59.901 [DEBUG] [inding.ntp.internal.NtpBinding:77 ] - There is no existing NTP binding configuration => refresh cycle aborted!
    17:55:59.901 [DEBUG] [.b.http.internal.HttpActivator:34 ] - HTTP binding has been started.
    17:55:59.916 [INFO ] [.service.AbstractActiveService:169 ] - HTTP Refresh Service has been started
    17:55:59.932 [DEBUG] [.i.LoggingPersistenceActivator:31 ] - Logging persistence bundle has been started.
    17:55:59.932 [DEBUG] [.p.internal.PersistenceManager:146 ] - Initializing logging persistence service.
    17:55:59.994 [DEBUG] [xecPersistenceServiceActivator:33 ] - Exec persistence bundle has been started.
    17:56:00.010 [DEBUG] [.p.internal.PersistenceManager:146 ] - Initializing exec persistence service.
    17:56:00.041 [DEBUG] [.p.internal.PersistenceManager:428 ] - Scheduled strategy exec.everyMinute with cron expression 0 * * * * ?
    17:56:00.228 [DEBUG] [.rrd4j.internal.RRD4jActivator:31 ] - RRD4j persistence bundle has been started.
    17:56:00.244 [DEBUG] [.p.internal.PersistenceManager:146 ] - Initializing rrd4j persistence service.
    17:56:00.259 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'DemoSwitch' does not exist.
    17:56:00.259 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'NoOfLights' does not exist.
    17:56:00.259 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'Window_GF_Toilet' does not exist.
    17:56:00.259 [DEBUG] [.p.internal.PersistenceManager:328 ] - Item group 'Heating' does not exist.
    17:56:00.259 [DEBUG] [.p.internal.PersistenceManager:328 ] - Item group 'Temperature' does not exist.
    17:56:00.259 [DEBUG] [.p.internal.PersistenceManager:328 ] - Item group 'Weather_Chart' does not exist.
    17:56:00.259 [DEBUG] [.p.internal.PersistenceManager:428 ] - Scheduled strategy rrd4j.everyMinute with cron expression 0 * * * * ?
    17:56:00.275 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'DemoSwitch' does not exist.
    17:56:00.275 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'NoOfLights' does not exist.
    17:56:00.275 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'Window_GF_Toilet' does not exist.
    17:56:00.275 [DEBUG] [p.r.i.charts.RRD4jChartServlet:113 ] - Starting up rrd chart servlet at /rrdchart.png
    17:56:00.275 [DEBUG] [.p.internal.PersistenceManager:328 ] - Item group 'Heating' does not exist.
    17:56:00.275 [DEBUG] [.p.internal.PersistenceManager:328 ] - Item group 'Temperature' does not exist.
    17:56:00.275 [DEBUG] [.p.internal.PersistenceManager:328 ] - Item group 'Weather_Chart' does not exist.



  • @Jransom2 Unless I'm very much mistaken there is no temperatur message in the lines after "Started!".
    During the last day several users of OpenHAB / MQTT had a problem with the first connection. A good tool for debugging is the MQTT client from jerady.
    See my posting here: http://forum.mysensors.org/topic/953/how-to-know-the-node-ids-assigned-by-the-mqtt-gateway-to-the-sensors/


  • Code Contributor

    When gateway is working as expected, you are going to see something like this on the serial output:

    0;0;3;0;9;read: 20-20-0 s=0,c=1,t=23,pt=2,l=2:58
    MyMQTT/20/0/V_LIGHT_LEVEL
    >>30 1D 00 19 4D 79 4D 51 54 54 2F 32 30 2F 30 2F 56 5F 4C 49 47 48 54 5F 4C 45 56 45 4C 35 38
    


  • r u sure add the addon folder.. org.openhab.binding.mqtt-1.6.2 then check in opehab config.. add ur ip of ur arduino gateway and port thats happens to me then end up got it working byself when restart and disconnect the mqtt client from @Jerady ... i check ur debug too many try remove many addons and focus on one thing a time and will able to help ;-) like i did...


Log in to reply
 

Looks like your connection to MySensors Forum was lost, please wait while we try to reconnect.