Reliability issues

rossdargan · March 25, 2016, 10:45am

I’m having so many reliability issues with openhab at the minute and I just want to know if others have the same problem.

My zwave configuration is unique in that I’m using com to ip stuff to connect to the machine with the USB stick on so I understand why that could have issues (but actually it’s normally pretty solid)

My hue bindings maybe work after a few reboots, but scripts sometimes also break with this error

Some of the reboots have caused the web guy not to load. Basically I’m on my ninth reboot today and I’m just wondering what will be broken now.

Is anyone else facing problems like this?

rossdargan · March 25, 2016, 2:11pm

This was the log for the last reboot. This time nothing worked. Almost all the statuses had a “-” in them, but web did work. I’ve attached this log as I can’t see anything obvious in it

rossdargan · March 25, 2016, 2:13pm

Looks like I can’t upload a .txt file, so sorry but it’s all here

2016-03-25 10:35:34.037 [INFO ] [.o.core.internal.CoreActivator] - openHAB runtime has been started (v1.8.1).
2016-03-25 10:35:35.797 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - mDNS service has been started
2016-03-25 10:35:35.875 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - Service Discovery initialization completed.
2016-03-25 10:35:35.901 [INFO ] [.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2016-03-25 10:35:35.902 [INFO ] [o.i.t.m.i.MqttBrokerConnection] - Starting MQTT broker connection 'localbroker’
2016-03-25 10:35:37.912 [WARN ] [cpr.DefaultAnnotationProcessor] - Unable to detect annotations. Application may fail to deploy.
2016-03-25 10:35:39.011 [INFO ] [penhab.io.rest.RESTApplication] - Started REST API at /rest
2016-03-25 10:35:45.264 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'rotini.sitemap’
2016-03-25 10:35:45.506 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'default.sitemap’
2016-03-25 10:35:45.729 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'ValidateZWave.script’
2016-03-25 10:35:45.806 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'mysql.persist’
2016-03-25 10:35:45.855 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'myopenhab.persist’
2016-03-25 10:35:45.890 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'alarm.items’
2016-03-25 10:35:45.955 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'plex.items’
2016-03-25 10:35:45.993 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'weather.items’
2016-03-25 10:35:46.027 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'nest.items’
2016-03-25 10:35:46.069 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'owntracks.items’
2016-03-25 10:35:46.087 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'others.items’
2016-03-25 10:35:46.121 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'lights.items’
2016-03-25 10:35:46.153 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'scenes.items’
2016-03-25 10:35:46.189 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'sockets.items’
2016-03-25 10:35:46.410 [INFO ] [.o.u.w.i.servlet.WebAppServlet] - Started Classic UI at /classicui/openhab.app
2016-03-25 10:35:50.280 [INFO ] [t.AbstractSocketChannelBinding] - The maximum buffer will be set to the default value of 1024
2016-03-25 10:35:50.280 [INFO ] [t.AbstractSocketChannelBinding] - The interval to retry connection setups will be set to the default value of 5
2016-03-25 10:35:50.280 [INFO ] [t.AbstractSocketChannelBinding] - The cron job to reset connections will be set to the default value of 0 0 0 * * ?
2016-03-25 10:35:50.281 [INFO ] [t.AbstractSocketChannelBinding] - The setting to queue write operation until a channel gets connected will be set to the default value of true
2016-03-25 10:35:50.281 [INFO ] [t.AbstractSocketChannelBinding] - The port to listen for incoming connections will be set to the default value of 0
2016-03-25 10:35:50.281 [INFO ] [t.AbstractSocketChannelBinding] - The setting to share channels within an Item will be set to the default value of true
2016-03-25 10:35:50.281 [INFO ] [t.AbstractSocketChannelBinding] - The setting to share channels between the items with the same direction will be set to the default value of true
2016-03-25 10:35:50.282 [INFO ] [t.AbstractSocketChannelBinding] - The setting to share channels between directions will be set to the default value of true
2016-03-25 10:35:50.282 [INFO ] [t.AbstractSocketChannelBinding] - The setting to use address masks for incoming connections will be set to the default value of true
2016-03-25 10:35:50.282 [INFO ] [t.protocol.internal.TCPBinding] - The maximum time out for blocking write operations will be set to the default vaulue of 3000
2016-03-25 10:35:50.283 [INFO ] [.service.AbstractActiveService] - TCP Refresh Service has been started
2016-03-25 10:35:50.283 [INFO ] [t.protocol.internal.TCPBinding] - The blocking nature of read/write operations will be set to the default vaulue of false
2016-03-25 10:35:50.283 [INFO ] [t.protocol.internal.TCPBinding] - The preamble for all write operations will be set to the default vaulue of
2016-03-25 10:35:50.283 [INFO ] [t.protocol.internal.TCPBinding] - The postamble for all write operations will be set to the default vaulue of
2016-03-25 10:35:50.283 [INFO ] [t.protocol.internal.TCPBinding] - Updating states with returned values will be set to the default vaulue of true
2016-03-25 10:35:50.284 [INFO ] [t.protocol.internal.TCPBinding] - The characterset will be set to the default vaulue of ASCII
2016-03-25 10:35:50.293 [INFO ] [AbstractDatagramChannelBinding] - The maximum buffer will be set to the default value of 1024
2016-03-25 10:35:50.293 [INFO ] [AbstractDatagramChannelBinding] - The interval to retry connection setups will be set to the default value of 5
2016-03-25 10:35:50.294 [INFO ] [AbstractDatagramChannelBinding] - The cron job to reset connections will be set to the default value of 0 0 0 * * ?
2016-03-25 10:35:50.294 [INFO ] [AbstractDatagramChannelBinding] - The setting to share channels within an Item will be set to the default value of true
2016-03-25 10:35:50.294 [INFO ] [AbstractDatagramChannelBinding] - The setting to share channels between the items with the same direction will be set to the default vaulue of true
2016-03-25 10:35:50.295 [INFO ] [AbstractDatagramChannelBinding] - The setting to share channels between directions will be set to the default vaulue of true
2016-03-25 10:35:50.295 [INFO ] [AbstractDatagramChannelBinding] - The setting to use address masks for incoming connections will be set to the default value of true
2016-03-25 10:35:50.336 [INFO ] [AbstractDatagramChannelBinding] - Listening for incoming data on /0:0:0:0:0:0:0:0:15001
2016-03-25 10:35:50.337 [INFO ] [t.protocol.internal.UDPBinding] - The maximum time out for blocking write operations will be set to the default vaulue of 3000
2016-03-25 10:35:50.337 [INFO ] [.service.AbstractActiveService] - UDP Refresh Service has been started
2016-03-25 10:35:50.337 [INFO ] [t.protocol.internal.UDPBinding] - The blocking nature of read/write operations will be set to the default vaulue of false
2016-03-25 10:35:50.337 [INFO ] [t.protocol.internal.UDPBinding] - The preamble for all write operations will be set to the default vaulue of
2016-03-25 10:35:50.338 [INFO ] [t.protocol.internal.UDPBinding] - The postamble for all write operations will be set to the default vaulue of
2016-03-25 10:35:50.338 [INFO ] [t.protocol.internal.UDPBinding] - Updating states with returned values will be set to the default vaulue of true
2016-03-25 10:35:50.338 [INFO ] [t.protocol.internal.UDPBinding] - The characterset will be set to the default vaulue of ASCII
2016-03-25 10:35:50.341 [INFO ] [AbstractDatagramChannelBinding] - ‘Connecting’ the channel Channel [item=Switch_LoungeLight, command=OFF, direction=OUT, remote=/192.168.1.15:53008, buffer=, isBlocking=false, isReconnecting=false, channel=, host=192.168.1.15, port=53008]
2016-03-25 10:35:50.341 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@46725f27 for the remote end /192.168.1.15:53008
2016-03-25 10:35:50.342 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@46725f27 for the remote end /192.168.1.15:53008
2016-03-25 10:35:50.342 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@46725f27 for the remote end /192.168.1.15:53008
2016-03-25 10:35:50.342 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@46725f27 for the remote end /192.168.1.15:53008
2016-03-25 10:35:50.342 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@46725f27 for the remote end /192.168.1.15:53008
2016-03-25 10:35:50.342 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@46725f27 for the remote end /192.168.1.15:53008
2016-03-25 10:35:50.343 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@46725f27 for the remote end /192.168.1.15:53008
2016-03-25 10:35:50.370 [WARN ] [i.internal.GenericItemProvider] - Attempted to register a second BindingConfigReader of type ‘tcp’. The primaraly reader will remain active!
2016-03-25 10:35:50.382 [WARN ] [i.internal.GenericItemProvider] - Attempted to register a second BindingConfigReader of type ‘udp’. The primaraly reader will remain active!
2016-03-25 10:35:50.428 [WARN ] [cpr.DefaultAnnotationProcessor] - Unable to detect annotations. Application may fail to deploy.
2016-03-25 10:35:50.819 [INFO ] [.o.io.habmin.HABminApplication] - Started HABmin REST API at /services/habmin
2016-03-25 10:35:51.017 [INFO ] [.myopenhab.internal.MyOHClient] - Connected to my.openHAB service (UUID = e9965569-35f9-4b68-8f37-9bd9e75b35de, local base URL = http://localhost:8080)
2016-03-25 10:35:56.487 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'doorbell.rules’
2016-03-25 10:35:56.691 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'owntracks.rules’
2016-03-25 10:35:56.695 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘alarm’.
2016-03-25 10:35:56.746 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'scene.rules’
2016-03-25 10:35:56.953 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘PlexLoungeRokuSelect’.
2016-03-25 10:35:56.955 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘PlexLoungeRokuBack’.
2016-03-25 10:35:56.956 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘PlexLoungeRokuUp’.
2016-03-25 10:35:56.957 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘PlexLoungeRokuDown’.
2016-03-25 10:35:56.959 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘PlexLoungeRokuLeft’.
2016-03-25 10:35:56.960 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘PlexLoungeRokuRight’.
2016-03-25 10:35:56.976 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘RokuPlayroomStartPlex’.
2016-03-25 10:35:56.978 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘PlayPeppaBedroom’.
2016-03-25 10:35:56.979 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘PlayPeppaLounge’.
2016-03-25 10:35:56.981 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘PlayPeppaLounge2’.
2016-03-25 10:35:56.988 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘Weather_Temperature_Min’.
2016-03-25 10:35:56.989 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘Weather_Temperature_Max’.
2016-03-25 10:35:57.026 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'alarm.rules’
2016-03-25 10:35:57.053 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘Weather_Backdrop_Url’.
2016-03-25 10:35:57.166 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘Location_Traffic’.
2016-03-25 10:35:57.194 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'tvOn.rules’
2016-03-25 10:35:57.292 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'porch.rules’
2016-03-25 10:35:57.322 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'nest.rules’
2016-03-25 10:35:57.358 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'plex.rules’
2016-03-25 10:35:57.393 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'tests.rules’
2016-03-25 10:38:16.696 [WARN ] [o.u.i.items.ItemUIRegistryImpl] - Cannot find page for id ‘Home’.
2016-03-25 10:48:46.144 [INFO ] [.o.core.internal.CoreActivator] - openHAB runtime has been started (v1.8.1).
2016-03-25 10:48:47.483 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - mDNS service has been started
2016-03-25 10:48:47.627 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - Service Discovery initialization completed.
2016-03-25 10:48:47.633 [INFO ] [.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2016-03-25 10:48:47.634 [INFO ] [o.i.t.m.i.MqttBrokerConnection] - Starting MQTT broker connection 'localbroker’
2016-03-25 10:48:48.979 [WARN ] [cpr.DefaultAnnotationProcessor] - Unable to detect annotations. Application may fail to deploy.
2016-03-25 10:48:49.878 [INFO ] [penhab.io.rest.RESTApplication] - Started REST API at /rest
2016-03-25 10:48:57.270 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'rotini.sitemap’
2016-03-25 10:48:57.439 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'default.sitemap’
2016-03-25 10:48:57.580 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'ValidateZWave.script’
2016-03-25 10:48:57.644 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'mysql.persist’
2016-03-25 10:48:57.656 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'myopenhab.persist’
2016-03-25 10:48:57.698 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'alarm.items’
2016-03-25 10:48:57.723 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'plex.items’
2016-03-25 10:48:57.773 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'weather.items’
2016-03-25 10:48:57.826 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'nest.items’
2016-03-25 10:48:57.854 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'owntracks.items’
2016-03-25 10:48:57.880 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'others.items’
2016-03-25 10:48:57.913 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'lights.items’
2016-03-25 10:48:57.945 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'scenes.items’
2016-03-25 10:48:57.963 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'sockets.items’
2016-03-25 10:49:01.049 [INFO ] [.o.u.w.i.servlet.WebAppServlet] - Started Classic UI at /classicui/openhab.app
2016-03-25 10:49:04.804 [INFO ] [t.AbstractSocketChannelBinding] - The maximum buffer will be set to the default value of 1024
2016-03-25 10:49:04.804 [INFO ] [t.AbstractSocketChannelBinding] - The interval to retry connection setups will be set to the default value of 5
2016-03-25 10:49:04.805 [INFO ] [t.AbstractSocketChannelBinding] - The cron job to reset connections will be set to the default value of 0 0 0 * * ?
2016-03-25 10:49:04.805 [INFO ] [t.AbstractSocketChannelBinding] - The setting to queue write operation until a channel gets connected will be set to the default value of true
2016-03-25 10:49:04.805 [INFO ] [t.AbstractSocketChannelBinding] - The port to listen for incoming connections will be set to the default value of 0
2016-03-25 10:49:04.805 [INFO ] [t.AbstractSocketChannelBinding] - The setting to share channels within an Item will be set to the default value of true
2016-03-25 10:49:04.805 [INFO ] [t.AbstractSocketChannelBinding] - The setting to share channels between the items with the same direction will be set to the default value of true
2016-03-25 10:49:04.805 [INFO ] [t.AbstractSocketChannelBinding] - The setting to share channels between directions will be set to the default value of true
2016-03-25 10:49:04.806 [INFO ] [t.AbstractSocketChannelBinding] - The setting to use address masks for incoming connections will be set to the default value of true
2016-03-25 10:49:04.806 [INFO ] [t.protocol.internal.TCPBinding] - The maximum time out for blocking write operations will be set to the default vaulue of 3000
2016-03-25 10:49:04.807 [INFO ] [t.protocol.internal.TCPBinding] - The blocking nature of read/write operations will be set to the default vaulue of false
2016-03-25 10:49:04.807 [INFO ] [t.protocol.internal.TCPBinding] - The preamble for all write operations will be set to the default vaulue of
2016-03-25 10:49:04.807 [INFO ] [t.protocol.internal.TCPBinding] - The postamble for all write operations will be set to the default vaulue of
2016-03-25 10:49:04.807 [INFO ] [t.protocol.internal.TCPBinding] - Updating states with returned values will be set to the default vaulue of true
2016-03-25 10:49:04.807 [INFO ] [t.protocol.internal.TCPBinding] - The characterset will be set to the default vaulue of ASCII
2016-03-25 10:49:04.807 [INFO ] [AbstractDatagramChannelBinding] - The maximum buffer will be set to the default value of 1024
2016-03-25 10:49:04.808 [INFO ] [AbstractDatagramChannelBinding] - The interval to retry connection setups will be set to the default value of 5
2016-03-25 10:49:04.808 [INFO ] [AbstractDatagramChannelBinding] - The cron job to reset connections will be set to the default value of 0 0 0 * * ?
2016-03-25 10:49:04.808 [INFO ] [AbstractDatagramChannelBinding] - The setting to share channels within an Item will be set to the default value of true
2016-03-25 10:49:04.808 [INFO ] [AbstractDatagramChannelBinding] - The setting to share channels between the items with the same direction will be set to the default vaulue of true
2016-03-25 10:49:04.808 [INFO ] [AbstractDatagramChannelBinding] - The setting to share channels between directions will be set to the default vaulue of true
2016-03-25 10:49:04.809 [INFO ] [AbstractDatagramChannelBinding] - The setting to use address masks for incoming connections will be set to the default value of true
2016-03-25 10:49:04.809 [INFO ] [.service.AbstractActiveService] - TCP Refresh Service has been started
2016-03-25 10:49:04.829 [INFO ] [AbstractDatagramChannelBinding] - Listening for incoming data on /0:0:0:0:0:0:0:0:15001
2016-03-25 10:49:04.830 [INFO ] [t.protocol.internal.UDPBinding] - The maximum time out for blocking write operations will be set to the default vaulue of 3000
2016-03-25 10:49:04.830 [INFO ] [t.protocol.internal.UDPBinding] - The blocking nature of read/write operations will be set to the default vaulue of false
2016-03-25 10:49:04.831 [INFO ] [t.protocol.internal.UDPBinding] - The preamble for all write operations will be set to the default vaulue of
2016-03-25 10:49:04.831 [INFO ] [t.protocol.internal.UDPBinding] - The postamble for all write operations will be set to the default vaulue of
2016-03-25 10:49:04.831 [INFO ] [t.protocol.internal.UDPBinding] - Updating states with returned values will be set to the default vaulue of true
2016-03-25 10:49:04.831 [INFO ] [t.protocol.internal.UDPBinding] - The characterset will be set to the default vaulue of ASCII
2016-03-25 10:49:04.831 [INFO ] [.service.AbstractActiveService] - UDP Refresh Service has been started
2016-03-25 10:49:04.833 [INFO ] [AbstractDatagramChannelBinding] - ‘Connecting’ the channel Channel [item=Switch_LoungeLight, command=ON, direction=OUT, remote=/192.168.1.15:53008, buffer=, isBlocking=false, isReconnecting=false, channel=, host=192.168.1.15, port=53008]
2016-03-25 10:49:04.834 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@41993ebf for the remote end /192.168.1.15:53008
2016-03-25 10:49:04.834 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@41993ebf for the remote end /192.168.1.15:53008
2016-03-25 10:49:04.834 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@41993ebf for the remote end /192.168.1.15:53008
2016-03-25 10:49:04.835 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@41993ebf for the remote end /192.168.1.15:53008
2016-03-25 10:49:04.835 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@41993ebf for the remote end /192.168.1.15:53008
2016-03-25 10:49:04.835 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@41993ebf for the remote end /192.168.1.15:53008
2016-03-25 10:49:04.835 [INFO ] [AbstractDatagramChannelBinding] - There is already an active channel sun.nio.ch.DatagramChannelImpl@41993ebf for the remote end /192.168.1.15:53008
2016-03-25 10:49:04.981 [WARN ] [i.internal.GenericItemProvider] - Attempted to register a second BindingConfigReader of type ‘tcp’. The primaraly reader will remain active!
2016-03-25 10:49:05.025 [WARN ] [i.internal.GenericItemProvider] - Attempted to register a second BindingConfigReader of type ‘udp’. The primaraly reader will remain active!
2016-03-25 10:49:05.094 [WARN ] [cpr.DefaultAnnotationProcessor] - Unable to detect annotations. Application may fail to deploy.
2016-03-25 10:49:05.558 [INFO ] [.o.io.habmin.HABminApplication] - Started HABmin REST API at /services/habmin
2016-03-25 10:49:06.352 [INFO ] [.myopenhab.internal.MyOHClient] - Connected to my.openHAB service (UUID = e9965569-35f9-4b68-8f37-9bd9e75b35de, local base URL = http://localhost:8080)
2016-03-25 10:49:08.140 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'doorbell.rules’
2016-03-25 10:49:08.526 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'owntracks.rules’
2016-03-25 10:49:08.624 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'scene.rules’
2016-03-25 10:49:08.813 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'alarm.rules’
2016-03-25 10:49:09.005 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'tvOn.rules’
2016-03-25 10:49:09.056 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'porch.rules’
2016-03-25 10:49:09.096 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'nest.rules’
2016-03-25 10:49:09.159 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'plex.rules’
2016-03-25 10:49:09.225 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'tests.rules’
2016-03-25 10:49:10.753 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuPower bound to client Plex4224D4001173 property power
2016-03-25 10:49:10.754 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuStatus bound to client Plex4224D4001173 property state
2016-03-25 10:49:10.754 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuTitle bound to client Plex4224D4001173 property title
2016-03-25 10:49:10.754 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuType bound to client Plex4224D4001173 property type
2016-03-25 10:49:10.755 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuProgress bound to client Plex4224D4001173 property playback/progress
2016-03-25 10:49:10.755 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuVolume bound to client Plex4224D4001173 property playback/volume
2016-03-25 10:49:10.756 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuPause bound to client Plex4224D4001173 property playback/pause
2016-03-25 10:49:10.756 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuPlay bound to client Plex4224D4001173 property playback/play
2016-03-25 10:49:10.757 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuPlayPause bound to client Plex4224D4001173 property playback/play
2016-03-25 10:49:10.757 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuStop bound to client Plex4224D4001173 property playback/stop
2016-03-25 10:49:10.757 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuSelect bound to client Plex4224D4001173 property navigation/select
2016-03-25 10:49:10.758 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuBack bound to client Plex4224D4001173 property navigation/back
2016-03-25 10:49:10.758 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuUp bound to client Plex4224D4001173 property navigation/moveUp
2016-03-25 10:49:10.759 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuDown bound to client Plex4224D4001173 property navigation/moveDown
2016-03-25 10:49:10.759 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuLeft bound to client Plex4224D4001173 property navigation/moveLeft
2016-03-25 10:49:10.759 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuRight bound to client Plex4224D4001173 property navigation/moveRight
2016-03-25 10:49:10.760 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuForward bound to client Plex4224D4001173 property playback/stepForward
2016-03-25 10:49:10.760 [INFO ] [p.i.PlexGenericBindingProvider] - Plex item PlexLoungeRokuReverse bound to client Plex4224D4001173 property playback/stepBack
2016-03-25 10:49:11.668 [INFO ] [.service.AbstractActiveService] - Plex Refresh Service has been started
2016-03-25 10:49:11.780 [INFO ] [.b.plex.internal.PlexConnector] - Plex websocket connected to 192.168.1.11:32400
2016-03-25 10:49:12.066 [INFO ] [.service.AbstractActiveService] - Exec Refresh Service has been started
2016-03-25 10:49:12.112 [INFO ] [.service.AbstractActiveService] - HTTP Refresh Service has been started
2016-03-25 10:49:12.581 [INFO ] [.service.AbstractActiveService] - Hue Refresh Service has been started
2016-03-25 10:49:12.581 [INFO ] [.z.internal.ZWaveActiveBinding] - Update config, port = /dev/ttyUSB01
2016-03-25 10:49:12.581 [INFO ] [.z.internal.ZWaveActiveBinding] - Update config, healtime = 2
2016-03-25 10:49:12.598 [INFO ] [.service.AbstractActiveService] - ZWave Refresh Service has been started
2016-03-25 10:49:12.619 [ERROR] [.p.m.i.MysqlPersistenceService] - mySQL: Unable to find table for query ‘alarm’.
2016-03-25 10:49:12.695 [INFO ] [b.z.i.protocol.ZWaveController] - Starting Z-Wave controller
2016-03-25 10:49:12.697 [INFO ] [b.z.i.protocol.ZWaveController] - Z-Wave timeout is set to 5000ms. Soft reset is false.
2016-03-25 10:49:12.697 [INFO ] [b.z.i.protocol.ZWaveController] - Connecting to serial port /dev/ttyUSB01
2016-03-25 10:49:12.939 [INFO ] [b.z.i.protocol.ZWaveController] - Serial port is initialized
2016-03-25 10:49:13.059 [INFO ] [o.o.b.w.i.common.WeatherConfig] - ProviderConfig[providerName=FORECASTIO,apiKey=1a3223bd0206367e477c83bf07759fc4]
2016-03-25 10:49:13.059 [INFO ] [o.o.b.w.i.common.WeatherConfig] - LocationConfig[providerName=FORECASTIO,language=en,updateInterval=10,latitude=54.8845449,longitude=-1.5076949,woeid=,locationId=home,name=]
2016-03-25 10:49:13.361 [INFO ] [.service.AbstractActiveService] - Nest Refresh Service has been started
2016-03-25 10:49:16.065 [INFO ] [.o.b.w.i.s.WeatherJobScheduler] - Starting and scheduling weatherJob-home with interval of 10 minutes
2016-03-25 10:49:16.514 [INFO ] [rialApiGetInitDataMessageClass] - NODE 1: Node found
2016-03-25 10:49:16.514 [INFO ] [rialApiGetInitDataMessageClass] - NODE 2: Node found
2016-03-25 10:49:16.514 [INFO ] [rialApiGetInitDataMessageClass] - NODE 3: Node found
2016-03-25 10:49:16.514 [INFO ] [rialApiGetInitDataMessageClass] - ZWave Controller using Controller API
2016-03-25 10:49:16.514 [INFO ] [rialApiGetInitDataMessageClass] - ZWave Controller is Primary Controller
2016-03-25 10:49:16.515 [INFO ] [rialApiGetInitDataMessageClass] - ------------Number of Nodes Found Registered to ZWave Controller------------
2016-03-25 10:49:16.515 [INFO ] [rialApiGetInitDataMessageClass] - # Nodes = 3
2016-03-25 10:49:16.515 [INFO ] [rialApiGetInitDataMessageClass] - ----------------------------------------------------------------------------
2016-03-25 10:49:29.215 [WARN ] [o.u.i.items.ItemUIRegistryImpl] - Cannot find page for id ‘Home’.
2016-03-25 12:00:00.613 [ERROR] [.myopenhab.internal.MyOHClient] - Socket.IO error: io.socket.engineio.client.EngineIOException: websocket error
2016-03-25 12:00:00.615 [INFO ] [.myopenhab.internal.MyOHClient] - Disconnected from my.openHAB service (UUID = e9965569-35f9-4b68-8f37-9bd9e75b35de, local base URL = http://localhost:8080)
2016-03-25 12:00:24.165 [INFO ] [.myopenhab.internal.MyOHClient] - Connected to my.openHAB service (UUID = e9965569-35f9-4b68-8f37-9bd9e75b35de, local base URL = http://localhost:8080)
2016-03-25 13:53:43.426 [WARN ] [o.u.i.items.ItemUIRegistryImpl] - Cannot find page for id ‘Home’.

rlkoshak · March 25, 2016, 5:23pm

Except for some errors from MySQL not being able to find a table for some Items and a my.openhab error your log does not indicate anything is wrong.

Try backing out your bindings and adding them back one by one to try and figure out which one is generating the error.

In my experience OH is rock solid so I’m going to assume the problem is in one of the bindings.

One other things, are you using Oracle JRE or OpenJava? OH is known to work better in Oracle’s Java.

rossdargan · March 25, 2016, 5:26pm

How can I tell?

I’m thinking about maybe getting a rasberry pi and sticking openhab 2 on there and porting things over. Not sure if I’m just going to encounter more of the same issues doing that?

rlkoshak · March 25, 2016, 5:36pm

Run java --version. If it says "HotSpot it is the Oracle Java. Otherwise it is OpenJDK.

rossdargan · March 25, 2016, 6:57pm

It’s OpenJDK

rlkoshak · March 25, 2016, 6:58pm

OK, I’d first recommend installing Oracle Java instead. OH is known to sometimes have issues with OpenJDK.

rossdargan · March 25, 2016, 7:04pm

Ok, I’ll take a snapshot of the VM and give that a whirl. Thanks for your help (again :D!)

watou · March 25, 2016, 10:54pm

It looks like you may have more than one TCP binding JAR in your addons folder?

rossdargan · March 26, 2016, 12:51pm

I do! Binned that one - thanks for picking that up!

rossdargan · April 3, 2016, 1:28pm

Fixed the hue issues I think by specifying a fixed ip (Like it says to do in the binding :/).

Also had huge issues with reboots taking ages. It was down to hyper-v. Getting much more reliable now!