Restoring from backup - Backup DB Restore Error: Undefined with Status: Server Error 500

Question

Restoring from backup - Backup DB Restore Error: Undefined with Status: Server Error 500

ma11w00d opened this issue 3 years ago · 2 comments

Hi,

Due to hardware failure I am trying to restore HA-Bridge from a backup (which I have done before) however when I load the device.db backup I get the error above...Backup DB Restore Error: Undefined with Status: Server Error 500. I can restore habridge-config with no issue.

I am using a Pi with a fresh install of Raspbian, HA-Bridge V5.4.1 and java version 11.0.15. I have looked through the issues logs but can't find a solution.

Below is the output of syslog running through starting the service, restoring habridge-config and attempting to restore device.db (the request from 192.168.0.25 are Vera Edge trying to control lights.

Thanks in advance.

May 18 22:25:07 189AusStPi01 java[408]: 2022-05-18 22:25:07,056 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener starting....
May 18 22:25:07 189AusStPi01 java[408]: 2022-05-18 22:25:07,061 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Create and run mDNS service.
May 18 22:25:07 189AusStPi01 java[408]: 2022-05-18 22:25:07,283 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
May 18 22:25:14 189AusStPi01 dhcpcd[422]: eth0: no IPv6 Routers available
May 18 22:25:16 189AusStPi01 kernel: [ 35.049412] cam-dummy-reg: disabling
May 18 22:25:24 189AusStPi01 systemd[1]: systemd-fsckd.service: Succeeded.
May 18 22:25:26 189AusStPi01 pulseaudio[474]: GetManagedObjects() failed: org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
May 18 22:26:11 189AusStPi01 java[408]: 2022-05-18 22:26:11,072 [qtp24260130-14] WARN com.bwssystems.HABridge.hue.HueMulator - Could not find device: 13 for hue state change request: VhCh8zjMx0JwfBHu4uZCAdKRYIycGKm8 from 192.168.0.25 body: {"on":true,"bri":254}
May 18 22:26:25 189AusStPi01 systemd[1]: Started Session 3 of user pi.
May 18 22:26:36 189AusStPi01 systemd[1]: Started Session 4 of user pi.
May 18 22:27:12 189AusStPi01 java[408]: 2022-05-18 22:27:12,257 [qtp24260130-18] WARN com.bwssystems.HABridge.hue.HueMulator - Could not find device: 13 for hue state change request: VhCh8zjMx0JwfBHu4uZCAdKRYIycGKm8 from 192.168.0.25 body: {"on":true,"bri":254}
May 18 22:27:33 189AusStPi01 java[408]: 2022-05-18 22:27:33,596 [qtp24260130-19] WARN com.bwssystems.HABridge.hue.HueMulator - Could not find device: 21 for hue state change request: VhCh8zjMx0JwfBHu4uZCAdKRYIycGKm8 from 192.168.0.25 body: {"on":true,"bri":254}
May 18 22:27:37 189AusStPi01 java[408]: 2022-05-18 22:27:37,198 [qtp24260130-13] WARN com.bwssystems.HABridge.hue.HueMulator - Could not find device: 101 for hue state change request: VhCh8zjMx0JwfBHu4uZCAdKRYIycGKm8 from 192.168.0.25 body: {"on":true,"bri":50}
May 18 22:28:02 189AusStPi01 java[408]: 2022-05-18 22:28:02,962 [qtp24260130-13] WARN com.bwssystems.HABridge.BridgeSettings - Issue loading values from file: file:///home/pi/ha-bridge/data/habridge.config, Gson convert failed. Using default settings.
May 18 22:28:14 189AusStPi01 java[408]: 2022-05-18 22:28:14,360 [qtp24260130-16] WARN com.bwssystems.HABridge.hue.HueMulator - Could not find device: 13 for hue state change request: VhCh8zjMx0JwfBHu4uZCAdKRYIycGKm8 from 192.168.0.25 body: {"on":true,"bri":254}
May 18 22:28:22 189AusStPi01 java[408]: 2022-05-18 22:28:22,239 [qtp24260130-14] ERROR spark.http.matching.GeneralError -
May 18 22:28:22 189AusStPi01 java[408]: com.google.gson.JsonSyntaxException: java.lang.IllegalStateException: Expected BEGIN_ARRAY but was STRING at line 1 column 2 path $
May 18 22:28:22 189AusStPi01 java[408]: #011at com.google.gson.Gson.fromJson(Gson.java:891)
May 18 22:28:22 189AusStPi01 java[408]: #011at com.google.gson.Gson.fromJson(Gson.java:844)
May 18 22:28:22 189AusStPi01 java[408]: #011at com.google.gson.Gson.fromJson(Gson.java:793)
May 18 22:28:22 189AusStPi01 java[408]: #011at com.google.gson.Gson.fromJson(Gson.java:765)
May 18 22:28:22 189AusStPi01 java[408]: #011at com.bwssystems.HABridge.dao.DeviceRepository._loadRepository(DeviceRepository.java:71)
May 18 22:28:22 189AusStPi01 java[408]: #011at com.bwssystems.HABridge.dao.DeviceRepository.loadRepository(DeviceRepository.java:63)
May 18 22:28:22 189AusStPi01 java[408]: #011at com.bwssystems.HABridge.devicemanagmeent.DeviceResource.lambda$setupEndpoints$43(DeviceResource.java:473)
May 18 22:28:22 189AusStPi01 java[408]: #011at spark.ResponseTransformerRouteImpl$1.handle(ResponseTransformerRouteImpl.java:47)
May 18 22:28:22 189AusStPi01 java[408]: #011at spark.http.matching.Routes.execute(Routes.java:61)
May 18 22:28:22 189AusStPi01 java[408]: #011at spark.http.matching.MatcherFilter.doFilter(MatcherFilter.java:130)
May 18 22:28:22 189AusStPi01 java[408]: #011at spark.embeddedserver.jetty.JettyHandler.doHandle(JettyHandler.java:50)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1568)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.server.Server.handle(Server.java:530)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
May 18 22:28:22 189AusStPi01 java[408]: #011at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
May 18 22:28:22 189AusStPi01 java[408]: #011at java.base/java.lang.Thread.run(Thread.java:829)
May 18 22:28:22 189AusStPi01 java[408]: Caused by: java.lang.IllegalStateException: Expected BEGIN_ARRAY but was STRING at line 1 column 2 path $
May 18 22:28:22 189AusStPi01 java[408]: #011at com.google.gson.stream.JsonReader.beginArray(JsonReader.java:351)
May 18 22:28:22 189AusStPi01 java[408]: #011at com.google.gson.internal.bind.ArrayTypeAdapter.read(ArrayTypeAdapter.java:70)
May 18 22:28:22 189AusStPi01 java[408]: #011at com.google.gson.Gson.fromJson(Gson.java:879)
May 18 22:28:22 189AusStPi01 java[408]: #11... 26 common frames omitted
May 18 22:28:36 189AusStPi01 systemd[1]: Stopping HA Bridge...
May 18 22:28:36 189AusStPi01 java[408]: 2022-05-18 22:28:36,546 [Thread-1] INFO com.bwssystems.HABridge.ShutdownHook - Shutdown requested...
May 18 22:28:36 189AusStPi01 java[408]: 2022-05-18 22:28:36,548 [Thread-1] INFO com.bwssystems.HABridge.ShutdownHook - Forcing system stop...
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,565 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener - ended, stop found
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,567 [main] INFO com.bwssystems.HABridge.HABridge - Going to close all homes
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,568 [main] INFO com.bwssystems.HABridge.HomeManager - Manager close homes called....
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,570 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.tcp.TCPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,572 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.NestBridge.NestHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,573 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hal.HalHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,575 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.NestBridge.NestHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,577 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.lifx.LifxHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,579 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.somfy.SomfyHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,580 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.moziot.MozIotHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,582 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,584 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.openhab.OpenHABHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,586 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hal.HalHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,587 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.homewizard.HomeWizardHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,589 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.fhem.FHEMHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,591 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,592 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.udp.UDPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,593 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hal.HalHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,595 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,596 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.exec.CommandHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,598 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,599 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hass.HassHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,600 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.harmony.HarmonyHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,601 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hal.HalHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,602 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.homegenie.HomeGenieHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,604 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.udp.UDPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,605 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hue.HueHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,606 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.harmony.HarmonyHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,607 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.tcp.TCPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,609 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,610 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.mqtt.MQTTHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,627 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.domoticz.DomoticzHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,628 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,629 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.exec.CommandHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,630 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,638 [Thread-18] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - >>> Spark shutting down ...
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39.681:INFO:oejs.AbstractConnector:Thread-18: Stopped ServerConnector@1570c81{HTTP/1.1,[http/1.1]}{0.0.0.0:80}
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39.692:INFO:oejs.session:Thread-18: Stopped scavenging
May 18 22:28:39 189AusStPi01 java[408]: 2022-05-18 22:28:39,708 [Thread-18] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - done
May 18 22:28:41 189AusStPi01 systemd[1]: ha-bridge.service: Main process exited, code=exited, status=143/n/a
May 18 22:28:41 189AusStPi01 systemd[1]: ha-bridge.service: Failed with result 'exit-code'.
May 18 22:28:41 189AusStPi01 systemd[1]: Stopped HA Bridge.
May 18 22:28:41 189AusStPi01 systemd[1]: ha-bridge.service: Consumed 23.899s CPU time.

Answer 1 · 2022-05-18T18:10:14.000Z

Will take alook

Answer 2 · 2022-05-19T12:39:03.000Z

Thanks much appreciated