Data from MQTT Gateway not pushing to OpenHab

[Jransom2]

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.

[Xander]

@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/

[marceloaqno]

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

[Anthony Straw]

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...