My OpenHAB is 'Offline'

Matthew_G · August 10, 2016, 8:35pm

Hello, I have successfully setup OpenHAB locally but am having difficulty getting My.OpenHAB to work. I have checked and double checked the secret and UUID codes but when I navigate to my.openhab.org/openhab.app?sitemap=‘mysitemap’ all I get is ‘openHAB is offline’.

In my log file with debugging on I see these two lines repeated indefinitely:

    09:52:22.291 [DEBUG] [.myopenhab.internal.MyOHClient:143  ] - Transport.EVENT_REQUEST_HEADERS

    09:52:24.085 [DEBUG] [.myopenhab.internal.MyOHClient:138  ] - Manager.EVENT_TRANSPORT

Any ideas?

rlkoshak · August 10, 2016, 10:30pm

The first thing is to make sure you are running your OH on at least Oracle Java 1.8.0_101.

Beyond that make sure you have a myopenhab.persist file.

Matthew_G · August 10, 2016, 11:20pm

Okay, so I updated java. For some reason it wasn’t downloading the latest build via apt-get. I have a myopenhab.persist file. In my log I now see:

12:29:20.737 [DEBUG] [.myopenhab.internal.MyOHClient:478  ] - No connection, Item update is not sent

rlkoshak · August 11, 2016, 2:39pm

A new error is promising. Is your security set to External?

I don’t have a lot of other ideas.

Matthew_G · August 11, 2016, 3:16pm

Yes, security is set to EXTERNAL and my net mask is set. I’ll keep cracking at it. Thanks for the help.

sihui · August 11, 2016, 3:35pm

Correctly? F.e. if your router is on 192.178.2.1, your netmask should be 192.178.2.0/24

Matthew_G · August 11, 2016, 3:47pm

Yes, that is how I’ve set it. Is it possible that something in my router configuration could be causing this? Maybe try resetting it?

Matthew_G · August 11, 2016, 10:32pm

I haven’t had any luck. Here is some of the latest log output. I can’t see anything that would point to a specific problem.
The last two lines just repeat as long as service is running. I should also mention that I am running this on a Raspberry PI

11:31:20.803 [DEBUG] [.s.internal.SchedulerActivator:36   ] - Scheduler has been started.
11:31:20.948 [INFO ] [uartz.impl.StdSchedulerFactory:1175 ] - Using default implementation for ThreadExecutor
11:31:21.030 [INFO ] [rtz.core.SchedulerSignalerImpl:61   ] - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
11:31:21.033 [INFO ] [rg.quartz.core.QuartzScheduler:243  ] - Quartz Scheduler v.2.1.7 created.
11:31:21.039 [INFO ] [org.quartz.simpl.RAMJobStore  :154  ] - RAMJobStore initialized.
11:31:21.044 [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.

11:31:21.047 [INFO ] [uartz.impl.StdSchedulerFactory:1324 ] - Quartz scheduler 'openHAB-job-scheduler' initialized from specified file: '/etc/openhab/quartz.properties'
11:31:21.049 [INFO ] [uartz.impl.StdSchedulerFactory:1328 ] - Quartz scheduler version: 2.1.7
11:31:21.051 [INFO ] [rg.quartz.core.QuartzScheduler:534  ] - Scheduler openHAB-job-scheduler_$_NON_CLUSTERED started.
11:31:21.063 [DEBUG] [o.config.core.ConfigDispatcher:146  ] - Processing openHAB default configuration file '/usr/share/openhab/configurations/openhab_default.cfg'.
11:31:21.335 [DEBUG] [o.config.core.ConfigDispatcher:168  ] - Processing openHAB main configuration file '/etc/openhab/configurations/openhab.cfg'.
11:31:21.673 [DEBUG] [.o.core.internal.CoreActivator:93   ] - UUID file already exists at '/usr/share/openhab/webapps/static/uuid' with content 'a2069f8d-a9ff-4d59-beef-5134f688d841'
11:31:21.677 [DEBUG] [.o.core.internal.CoreActivator:115  ] - Created file '/usr/share/openhab/webapps/static/version' with content '1.8.3'
11:31:21.679 [INFO ] [.o.core.internal.CoreActivator:61   ] - openHAB runtime has been started (v1.8.3).
11:31:21.809 [DEBUG] [a.internal.AutoUpdateActivator:31   ] - AutoUpdate binding has been started.
 11:31:24.505 [DEBUG] [.p.i.PersistenceModelActivator:23   ] - Registered 'persistence' configuration parser
11:31:24.568 [DEBUG] [.c.t.i.TransformationActivator:38   ] - Transformation Service has been started.
11:31:24.698 [DEBUG] [m.internal.MultimediaActivator:34   ] - Multimedia I/O bundle has been started.
11:31:24.783 [DEBUG] [.s.i.DiscoveryServiceActivator:27   ] - Discovery service has been started.
11:31:24.805 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl:93   ] - mDNS service has been started
11:31:24.846 [DEBUG] [.io.transport.mqtt.MqttService:123  ] - Starting MQTT Service...
11:31:24.980 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl:53   ] - Service Discovery initialization completed.
11:31:25.215 [DEBUG] [.i.internal.ItemModelActivator:24   ] - Registered 'item' configuration parser
11:31:25.732 [DEBUG] [o.o.c.i.items.ItemRegistryImpl:137  ] - Item provider 'GenericItemProvider' has been added.
11:31:27.506 [DEBUG] [.o.m.s.i.SitemapModelActivator:23   ] - Registered 'sitemap' configuration parser
11:31:27.741 [DEBUG] [io.rest.internal.RESTActivator:33   ] - REST API has been started.
11:31:27.913 [INFO ] [org.atmosphere.util.IOUtils   :370  ] - META-INF/services/org.atmosphere.cpr.AtmosphereFramework not found in class loader
11:31:28.011 [INFO ] [sphere.cpr.AtmosphereFramework:2601 ] - Atmosphere is using org.atmosphere.cpr.DefaultAnnotationProcessor for processing annotation
11:31:28.014 [INFO ] [cpr.DefaultAnnotationProcessor:138  ] - AnnotationProcessor class org.atmosphere.cpr.DefaultAnnotationProcessor$BytecodeBasedAnnotationProcessor being used
11:31:28.120 [WARN ] [cpr.DefaultAnnotationProcessor:178  ] - Unable to detect annotations. Application may fail to deploy.
11:31:28.204 [INFO ] [sphere.cpr.AtmosphereFramework:1891 ] - Auto detecting atmosphere handlers /WEB-INF/classes/
11:31:28.266 [INFO ] [sphere.cpr.AtmosphereFramework:639  ] - Installed AtmosphereHandler org.atmosphere.handler.ReflectorServletProcessor mapped to context-path /* and Broadcaster Class org.atmosphere.j
11:31:28.268 [INFO ] [sphere.cpr.AtmosphereFramework:1945 ] - Auto detecting WebSocketHandler in /WEB-INF/classes/
11:31:28.273 [INFO ] [sphere.cpr.AtmosphereFramework:1602 ] - Installed WebSocketProtocol org.atmosphere.websocket.protocol.SimpleHttpProtocol
11:31:28.378 [INFO ] [.a.h.ReflectorServletProcessor:103  ] - Installing Servlet com.sun.jersey.spi.container.servlet.ServletContainer
11:31:28.736 [INFO ] [c.s.j.s.i.a.WebApplicationImpl:815  ] - Initiating Jersey application, version 'Jersey: 1.18.1 02/19/2014 03:28 AM'
11:31:28.743 [INFO ] [c.s.j.s.i.a.WebApplicationImpl:826  ] - Adding the following classes declared in META-INF/services/jersey-server-components to the resource configuration:
  class org.atmosphere.jersey.AtmosphereResourceConfigurator
11:31:28.928 [INFO ] [j.s.i.a.DeferredResourceConfig:101  ] - Instantiated the Application class org.openhab.io.rest.RESTApplication
11:31:31.115 [INFO ] [sphere.cpr.AtmosphereFramework:2435 ] - AtmosphereInterceptor CORS Interceptor Support will always be executed first
11:31:31.118 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor CORS Interceptor Support with priority FIRST_BEFORE_DEFAULT
11:31:31.121 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Default Response's Headers Interceptor with priority AFTER_DEFAULT
11:31:31.127 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Android Interceptor Support with priority AFTER_DEFAULT
11:31:31.132 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor SSE Interceptor Support with priority AFTER_DEFAULT
11:31:31.136 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor JSONP Interceptor Support with priority AFTER_DEFAULT
11:31:31.142 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Atmosphere JavaScript Protocol with priority AFTER_DEFAULT
11:31:31.146 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Browser disconnection detection with priority AFTER_DEFAULT
11:31:31.159 [INFO ] [sphere.cpr.AtmosphereFramework:935  ] - Using EndpointMapper class org.atmosphere.util.DefaultEndpointMapper
11:31:31.165 [WARN ] [sphere.cpr.AtmosphereFramework:941  ] - No BroadcasterCache configured. Broadcasted message between client reconnection will be LOST. It is recommended to configure the org.atmosphe
11:31:31.168 [INFO ] [sphere.cpr.AtmosphereFramework:949  ] - Default Broadcaster Class: org.atmosphere.jersey.JerseyBroadcaster
11:31:31.171 [INFO ] [sphere.cpr.AtmosphereFramework:950  ] - Broadcaster Polling Wait Time 100
11:31:31.174 [INFO ] [sphere.cpr.AtmosphereFramework:951  ] - Shared ExecutorService supported: true
11:31:31.178 [INFO ] [sphere.cpr.AtmosphereFramework:958  ] - Messaging Thread Pool Size: 10
11:31:31.180 [INFO ] [sphere.cpr.AtmosphereFramework:968  ] - Async I/O Thread Pool Size: 10
11:31:31.182 [INFO ] [sphere.cpr.AtmosphereFramework:979  ] - Using BroadcasterFactory: org.atmosphere.cpr.DefaultBroadcasterFactory
11:31:31.184 [INFO ] [sphere.cpr.AtmosphereFramework:980  ] - Using WebSocketProcessor: org.atmosphere.websocket.DefaultWebSocketProcessor
11:31:31.190 [INFO ] [sphere.cpr.AtmosphereFramework:990  ] - Invoke AtmosphereInterceptor on WebSocket message true
11:31:31.192 [INFO ] [sphere.cpr.AtmosphereFramework:991  ] - HttpSession supported: false
11:31:31.194 [INFO ] [sphere.cpr.AtmosphereFramework:993  ] - Atmosphere is using DefaultAtmosphereObjectFactory for dependency injection and object creation
11:31:31.196 [INFO ] [sphere.cpr.AtmosphereFramework:994  ] - Atmosphere is using async support: org.atmosphere.container.JettyAsyncSupportWithWebSocket running under container: jetty/8.1.3.v20120522 wit
11:31:31.201 [INFO ] [sphere.cpr.AtmosphereFramework:996  ] - Atmosphere Framework 2.2.5 started.
11:31:31.204 [INFO ] [sphere.cpr.AtmosphereFramework:998  ] -

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

11:31:31.209 [INFO ] [penhab.io.rest.RESTApplication:144  ] - Started REST API at /rest
11:31:31.217 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66   ] - Registering new service _openhab-server._tcp.local. at port 8080
11:31:33.848 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66   ] - Registering new service _openhab-server-ssl._tcp.local. at port 8443
11:31:33.962 [INFO ] [c.internal.ModelRepositoryImpl:80   ] - Loading model 'main.sitemap'
11:31:34.407 [INFO ] [c.internal.ModelRepositoryImpl:80   ] - Loading model 'myopenhab.persist'
11:31:34.519 [INFO ] [c.internal.ModelRepositoryImpl:80   ] - Loading model 'main.items'
11:31:34.578 [DEBUG] [i.internal.GenericItemProvider:154  ] - Processing binding configs for items from model 'main.items'
11:31:34.588 [DEBUG] [i.internal.GenericItemProvider:133  ] - Read items from model 'main.items'
https://my.openhab.org/openhab.app?sitemap=main11:31:36.615 [INFO ] [.o.u.w.i.servlet.WebAppServlet:80   ] - Started Classic UI at /classicui/openhab.app
11:31:37.348 [DEBUG] [.r.internal.RuleModelActivator:42   ] - Registered 'rules' configuration parser
11:31:37.388 [DEBUG] [m.r.internal.engine.RuleEngine:77   ] - Started rule engine
11:31:41.265 [DEBUG] [.m.i.MyOpenHABServiceActivator:28   ] - MyOpenHAB service has been started.
11:31:41.272 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:300  ] - setEventPublisher
11:31:41.273 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:135  ] - my.openHAB service activated
11:31:41.281 [DEBUG] [.p.internal.PersistenceManager:146  ] - Initializing myopenhab persistence service.
11:31:41.291 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:118  ] - config is null
11:31:41.296 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:176  ] - UUID file found at '/usr/share/openhab/webapps/static/uuid' with content 'a2069f8d-a9ff-4d59-beef-5134f688d841'
11:31:41.299 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:228  ] - Secret file already exists at '/usr/share/openhab/webapps/static/secret' with content '0W1e9V0yrfGQfzlwRycA'
11:31:41.301 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:120  ] - UUID = a2069f8d-a9ff-4d59-beef-5134f688d841, secret = 0W1e9V0yrfGQfzlwRycA
11:31:41.305 [DEBUG] [.b.exec.internal.ExecActivator:34   ] - Exec binding has been started.
11:31:41.331 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:176  ] - UUID file found at '/usr/share/openhab/webapps/static/uuid' with content 'a2069f8d-a9ff-4d59-beef-5134f688d841'
11:31:41.344 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:228  ] - Secret file already exists at '/usr/share/openhab/webapps/static/secret' with content '0W1e9V0yrfGQfzlwRycA'
11:31:41.347 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:162  ] - Version file found at '/usr/share/openhab/webapps/static/version' with content '1.8.3'
11:31:41.355 [DEBUG] [i.internal.GenericItemProvider:341  ] - Start processing binding configuration of Item 'bdrm_lamp (Type=SwitchItem, State=Uninitialized)' with 'ExecGenericBindingProvider' reader.
11:31:41.372 [DEBUG] [i.internal.GenericItemProvider:341  ] - Start processing binding configuration of Item 'bdrm_fan (Type=SwitchItem, State=Uninitialized)' with 'ExecGenericBindingProvider' reader.
11:31:41.377 [DEBUG] [i.internal.GenericItemProvider:341  ] - Start processing binding configuration of Item 'lvrm_lights (Type=SwitchItem, State=Uninitialized)' with 'ExecGenericBindingProvider' reader.
11:31:41.412 [INFO ] [.service.AbstractActiveService:169  ] - Exec Refresh Service has been started
11:31:41.492 [DEBUG] [.myopenhab.internal.MyOHClient:138  ] - Manager.EVENT_TRANSPORT
11:31:41.792 [DEBUG] [.myopenhab.internal.MyOHClient:143  ] - Transport.EVENT_REQUEST_HEADERS

vzorglub · August 28, 2016, 10:05pm

Hello,

I seem to have the same problem.
Did you find a solution?
I am running a rpi too.

Thanks

jaydee73 · August 29, 2016, 7:24am

Maybe you check your installation steps with some of the tutorials around the web?! Maybe you find out that you missed a step?

I got my my.openhab running with this tutorial:

(scroll down to the my.openhab part).