openbase / bco.device

Other
0 stars 0 forks source link

IllegalMonitorStateException is thrown when the bco.device manager tries to reach a not available openhab instance. #73

Open DivineThreepwood opened 4 years ago

DivineThreepwood commented 4 years ago

Can be reproduced when staring bco-manager-device-openhab and passing it an invalid openhab url.

17:19:15.609 [main] INFO  o.o.j.p.Launcher Start open-habdevice-manager-launcher...
17:19:19.555 [pool-1-thread-1] INFO  o.o.b.d.o.OpenHABRestCommunicator Wait for openHAB...
17:20:15.731 [pool-1-thread-6] WARN  o.o.j.p.Launcher Launcher OpenHABSitemapSynchronizerLauncher startup delay detected!
17:20:15.731 [pool-1-thread-5] WARN  o.o.j.p.Launcher Launcher OpenHABDeviceManagerLauncher startup delay detected!
17:20:15.731 [pool-1-thread-4] WARN  o.o.j.p.Launcher Launcher OpenHABConfigSynchronizerLauncher startup delay detected!
17:20:19.433 [pool-2-thread-7] ERROR o.o.j.s.BundledReentrantReadWriteLock$2 ═╦═⚀ Fatal implementation error in or by using [wait:60000]!
17:20:19.434 [pool-2-thread-7] ERROR o.o.j.s.BundledReentrantReadWriteLock$2  ╚════⚀ WriteLock of GenericDeviceController[DEVICE[Scenen Schalter Marian]] was locked for more than 60 sec by Consumer[GenericDeviceController[DEVICE[Scenen Schalter Marian]]]!
17:20:19.434 [pool-2-thread-7] ERROR o.o.j.s.BundledReentrantReadWriteLock$2 =====================================
17:20:19.439 [pool-2-thread-7] ERROR o.o.j.s.BundledReentrantReadWriteLock$2 Fatal implementation error in or by using [wait:60000]!
org.openbase.jul.exception.FatalImplementationErrorException: Fatal implementation error in or by using [wait:60000]!
    at org.openbase.jul.schedule.BundledReentrantReadWriteLock$2.expired(BundledReentrantReadWriteLock.java:129)
    at org.openbase.jul.schedule.Timeout.lambda$internal_start$0(Timeout.java:241)
    at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
    at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
    at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: java.util.concurrent.TimeoutException: WriteLock of GenericDeviceController[DEVICE[Scenen Schalter Marian]] was locked for more than 60 sec by Consumer[GenericDeviceController[DEVICE[Scenen Schalter Marian]]]!
    ... 7 common frames omitted
17:20:19.439 [pool-2-thread-7] ERROR o.o.j.s.BundledReentrantReadWriteLock$2 =====================================
17:20:19.440 [pool-2-thread-7] WARN  o.o.j.s.Timeout ═╦═⚀ Error during timeout handling!
17:20:19.440 [pool-2-thread-7] WARN  o.o.j.s.Timeout  ╚════⚀ IllegalMonitorStateException
17:20:19.441 [pool-2-thread-7] WARN  o.o.j.s.Timeout =====================================
17:20:19.441 [pool-2-thread-7] WARN  o.o.j.s.Timeout Error during timeout handling!
org.openbase.jul.exception.CouldNotPerformException: Error during timeout handling!
    at org.openbase.jul.schedule.Timeout.lambda$internal_start$0(Timeout.java:243)
    at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
    at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
    at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: java.lang.IllegalMonitorStateException: null
    at java.base/java.util.concurrent.locks.ReentrantReadWriteLock$Sync.tryRelease(ReentrantReadWriteLock.java:372)
    at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer.release(AbstractQueuedSynchronizer.java:1302)
    at java.base/java.util.concurrent.locks.ReentrantReadWriteLock$WriteLock.unlock(ReentrantReadWriteLock.java:1147)
    at org.openbase.jul.schedule.BundledReentrantReadWriteLock.unlockWrite(BundledReentrantReadWriteLock.java:305)
    at org.openbase.jul.schedule.BundledReentrantReadWriteLock$2.expired(BundledReentrantReadWriteLock.java:137)
    at org.openbase.jul.schedule.Timeout.lambda$internal_start$0(Timeout.java:241)
    ... 5 common frames omitted
17:20:19.442 [pool-2-thread-7] WARN  o.o.j.s.Timeout =====================================
17:20:34.893 [pool-1-thread-2] WARN  o.o.b.d.o.m.OpenHABDeviceManager ═╦═⚀ Could not retrieve item states from openHAB!
17:20:34.894 [pool-1-thread-2] WARN  o.o.b.d.o.m.OpenHABDeviceManager  ╚══╦═⚀ Could not get sub-URL[items]
17:20:34.894 [pool-1-thread-2] WARN  o.o.b.d.o.m.OpenHABDeviceManager     ╚══╦═⚀ java.net.ConnectException: Operation timed out (Connection timed out)
17:20:34.894 [pool-1-thread-2] WARN  o.o.b.d.o.m.OpenHABDeviceManager        ╚════⚀ Operation timed out (Connection timed out)
17:20:34.894 [pool-1-thread-2] WARN  o.o.b.d.o.m.OpenHABDeviceManager =====================================
17:20:34.895 [pool-1-thread-2] WARN  o.o.b.d.o.m.OpenHABDeviceManager Could not retrieve item states from openHAB!
org.openbase.jul.exception.CouldNotPerformException: Could not retrieve item states from openHAB!
    at org.openbase.jul.exception.printer.ExceptionPrinter.printHistory(ExceptionPrinter.java:159)
    at org.openbase.bco.device.openhab.manager.OpenHABDeviceManager$2.relay(OpenHABDeviceManager.java:98)
    at org.openbase.jul.schedule.RecurrenceEventFilter.relay(RecurrenceEventFilter.java:245)
    at org.openbase.jul.schedule.RecurrenceEventFilter.callRelay(RecurrenceEventFilter.java:229)
    at org.openbase.jul.schedule.RecurrenceEventFilter.trigger(RecurrenceEventFilter.java:152)
    at org.openbase.jul.schedule.RecurrenceEventFilter.trigger(RecurrenceEventFilter.java:100)
    at org.openbase.bco.device.openhab.manager.OpenHABDeviceManager.lambda$new$0(OpenHABDeviceManager.java:103)
    at org.openbase.jul.pattern.AbstractObservable.notifyObservers(AbstractObservable.java:271)
    at org.openbase.jul.pattern.AbstractObservable.notifyObservers(AbstractObservable.java:200)
    at org.openbase.jul.storage.registry.AbstractRegistry.notifyObservers(AbstractRegistry.java:774)
    at org.openbase.jul.storage.registry.AbstractRegistry.register(AbstractRegistry.java:204)
    at org.openbase.bco.dal.control.layer.unit.device.AbstractDeviceController.applyConfigUpdate(AbstractDeviceController.java:66)
    at org.openbase.bco.dal.control.layer.unit.device.AbstractDeviceController.applyConfigUpdate(AbstractDeviceController.java:43)
    at org.openbase.jul.communication.controller.AbstractConfigurableController.init(AbstractConfigurableController.java:74)
    at org.openbase.bco.dal.control.layer.unit.AbstractUnitController.init(AbstractUnitController.java:305)
    at org.openbase.bco.dal.control.layer.unit.device.AbstractDeviceControllerFactory.newInstance(AbstractDeviceControllerFactory.java:78)
    at org.openbase.bco.dal.control.layer.unit.device.DeviceControllerFactoryImpl.newInstance(DeviceControllerFactoryImpl.java:48)
    at org.openbase.bco.dal.control.layer.unit.device.DeviceControllerFactoryImpl.newInstance(DeviceControllerFactoryImpl.java:35)
    at org.openbase.jul.storage.registry.RegistrySynchronizer.register(RegistrySynchronizer.java:105)
    at org.openbase.jul.storage.registry.ActivatableEntryRegistrySynchronizer.register(ActivatableEntryRegistrySynchronizer.java:60)
    at org.openbase.jul.storage.registry.RegistrySynchronizer.register(RegistrySynchronizer.java:96)
    at org.openbase.jul.storage.registry.RegistrySynchronizer.register(RegistrySynchronizer.java:44)
    at org.openbase.jul.storage.registry.AbstractSynchronizer.registerInternal(AbstractSynchronizer.java:316)
    at org.openbase.jul.storage.registry.AbstractSynchronizer.internalSync(AbstractSynchronizer.java:209)
    at org.openbase.jul.storage.registry.AbstractSynchronizer.activate(AbstractSynchronizer.java:118)
    at org.openbase.jul.storage.registry.ActivatableEntryRegistrySynchronizer.activate(ActivatableEntryRegistrySynchronizer.java:91)
    at org.openbase.bco.dal.control.layer.unit.device.DeviceManagerImpl.activate(DeviceManagerImpl.java:121)
    at org.openbase.bco.device.openhab.manager.OpenHABDeviceManager.activate(OpenHABDeviceManager.java:114)
    at org.openbase.jul.pattern.launch.AbstractLauncher.lambda$launch$0(AbstractLauncher.java:193)
    at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
    at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: org.openbase.jul.exception.CouldNotPerformException: Could not get sub-URL[items]
    at org.openbase.bco.device.openhab.OpenHABRestCommunicator.get(OpenHABRestCommunicator.java:376)
    at org.openbase.bco.device.openhab.OpenHABRestCommunicator.getItems(OpenHABRestCommunicator.java:272)
    at org.openbase.bco.device.openhab.manager.OpenHABDeviceManager$2.relay(OpenHABDeviceManager.java:90)
    ... 31 common frames omitted
Caused by: javax.ws.rs.ProcessingException: java.net.ConnectException: Operation timed out (Connection timed out)
    at org.glassfish.jersey.client.internal.HttpUrlConnector.apply(HttpUrlConnector.java:260)
    at org.glassfish.jersey.client.ClientRuntime.invoke(ClientRuntime.java:254)
    at org.glassfish.jersey.client.JerseyInvocation.lambda$invoke$0(JerseyInvocation.java:736)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:292)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:274)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:205)
    at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:390)
    at org.glassfish.jersey.client.JerseyInvocation.invoke(JerseyInvocation.java:735)
    at org.glassfish.jersey.client.JerseyInvocation$Builder.method(JerseyInvocation.java:395)
    at org.glassfish.jersey.client.JerseyInvocation$Builder.get(JerseyInvocation.java:295)
    at org.openbase.bco.device.openhab.OpenHABRestCommunicator.get(OpenHABRestCommunicator.java:369)
    ... 33 common frames omitted
Caused by: java.net.ConnectException: Operation timed out (Connection timed out)
    at java.base/java.net.PlainSocketImpl.socketConnect(Native Method)
    at java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
    at java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
    at java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
    at java.base/java.net.Socket.connect(Socket.java:591)
    at java.base/sun.net.NetworkClient.doConnect(NetworkClient.java:177)
    at java.base/sun.net.www.http.HttpClient.openServer(HttpClient.java:474)
    at java.base/sun.net.www.http.HttpClient.openServer(HttpClient.java:569)
    at java.base/sun.net.www.http.HttpClient.(HttpClient.java:242)
    at java.base/sun.net.www.http.HttpClient.New(HttpClient.java:341)
    at java.base/sun.net.www.http.HttpClient.New(HttpClient.java:362)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.getNewHttpClient(HttpURLConnection.java:1248)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.plainConnect0(HttpURLConnection.java:1187)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:1081)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.connect(HttpURLConnection.java:1015)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1587)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1515)
    at java.base/java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:527)
    at org.glassfish.jersey.client.internal.HttpUrlConnector._apply(HttpUrlConnector.java:366)
    at org.glassfish.jersey.client.internal.HttpUrlConnector.apply(HttpUrlConnector.java:258)
    ... 43 common frames omitted
17:20:34.895 [pool-1-thread-2] WARN  o.o.b.d.o.m.OpenHABDeviceManager =====================================
17:20:38.468 [pool-1-thread-2] INFO  o.o.b.d.c.l.u.UnitControllerRegistrySynchronizer 100 changes synchronized.
17:21:15.738 [pool-1-thread-4] WARN  o.o.j.p.Launcher Launcher OpenHABConfigSynchronizerLauncher startup delay detected!
17:21:15.738 [pool-1-thread-6] WARN  o.o.j.p.Launcher Launcher OpenHABSitemapSynchronizerLauncher startup delay detected!
17:21:15.738 [pool-1-thread-5] WARN  o.o.j.p.Launcher Launcher OpenHABDeviceManagerLauncher startup delay detected!
17:21:57.310 [pool-2-thread-6] WARN  o.o.b.d.o.m.OpenHABDeviceManager ═╦═⚀ Could not retrieve item states from openHAB!
17:21:57.310 [pool-2-thread-6] WARN  o.o.b.d.o.m.OpenHABDeviceManager  ╚══╦═⚀ Could not get sub-URL[items]
17:21:57.310 [pool-2-thread-6] WARN  o.o.b.d.o.m.OpenHABDeviceManager     ╚══╦═⚀ java.net.ConnectException: Operation timed out (Connection timed out)
17:21:57.310 [pool-2-thread-6] WARN  o.o.b.d.o.m.OpenHABDeviceManager        ╚════⚀ Operation timed out (Connection timed out)
17:21:57.310 [pool-2-thread-6] WARN  o.o.b.d.o.m.OpenHABDeviceManager =====================================
17:21:57.312 [pool-2-thread-6] WARN  o.o.b.d.o.m.OpenHABDeviceManager Could not retrieve item states from openHAB!
org.openbase.jul.exception.CouldNotPerformException: Could not retrieve item states from openHAB!
    at org.openbase.jul.exception.printer.ExceptionPrinter.printHistory(ExceptionPrinter.java:159)
    at org.openbase.bco.device.openhab.manager.OpenHABDeviceManager$2.relay(OpenHABDeviceManager.java:98)
    at org.openbase.jul.schedule.RecurrenceEventFilter.relay(RecurrenceEventFilter.java:245)
    at org.openbase.jul.schedule.RecurrenceEventFilter.callRelay(RecurrenceEventFilter.java:229)
    at org.openbase.jul.schedule.RecurrenceEventFilter.access$100(RecurrenceEventFilter.java:43)
    at org.openbase.jul.schedule.RecurrenceEventFilter$1.expired(RecurrenceEventFilter.java:86)
    at org.openbase.jul.schedule.Timeout.lambda$internal_start$0(Timeout.java:241)
    at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
    at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
    at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: org.openbase.jul.exception.CouldNotPerformException: Could not get sub-URL[items]
    at org.openbase.bco.device.openhab.OpenHABRestCommunicator.get(OpenHABRestCommunicator.java:376)
    at org.openbase.bco.device.openhab.OpenHABRestCommunicator.getItems(OpenHABRestCommunicator.java:272)
    at org.openbase.bco.device.openhab.manager.OpenHABDeviceManager$2.relay(OpenHABDeviceManager.java:90)
    ... 10 common frames omitted
Caused by: javax.ws.rs.ProcessingException: java.net.ConnectException: Operation timed out (Connection timed out)
    at org.glassfish.jersey.client.internal.HttpUrlConnector.apply(HttpUrlConnector.java:260)
    at org.glassfish.jersey.client.ClientRuntime.invoke(ClientRuntime.java:254)
    at org.glassfish.jersey.client.JerseyInvocation.lambda$invoke$0(JerseyInvocation.java:736)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:292)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:274)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:205)
    at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:390)
    at org.glassfish.jersey.client.JerseyInvocation.invoke(JerseyInvocation.java:735)
    at org.glassfish.jersey.client.JerseyInvocation$Builder.method(JerseyInvocation.java:395)
    at org.glassfish.jersey.client.JerseyInvocation$Builder.get(JerseyInvocation.java:295)
    at org.openbase.bco.device.openhab.OpenHABRestCommunicator.get(OpenHABRestCommunicator.java:369)
    ... 12 common frames omitted
Caused by: java.net.ConnectException: Operation timed out (Connection timed out)
    at java.base/java.net.PlainSocketImpl.socketConnect(Native Method)
    at java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
    at java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
    at java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
    at java.base/java.net.Socket.connect(Socket.java:591)
    at java.base/sun.net.NetworkClient.doConnect(NetworkClient.java:177)
    at java.base/sun.net.www.http.HttpClient.openServer(HttpClient.java:474)
    at java.base/sun.net.www.http.HttpClient.openServer(HttpClient.java:569)
    at java.base/sun.net.www.http.HttpClient.(HttpClient.java:242)
    at java.base/sun.net.www.http.HttpClient.New(HttpClient.java:341)
    at java.base/sun.net.www.http.HttpClient.New(HttpClient.java:362)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.getNewHttpClient(HttpURLConnection.java:1248)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.plainConnect0(HttpURLConnection.java:1187)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:1081)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.connect(HttpURLConnection.java:1015)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1587)
    at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1515)
    at java.base/java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:527)
    at org.glassfish.jersey.client.internal.HttpUrlConnector._apply(HttpUrlConnector.java:366)
    at org.glassfish.jersey.client.internal.HttpUrlConnector.apply(HttpUrlConnector.java:258)
    ... 22 common frames omitted
17:21:57.313 [pool-2-thread-6] WARN  o.o.b.d.o.m.OpenHABDeviceManager =====================================
17:22:15.743 [pool-1-thread-6] WARN  o.o.j.p.Launcher Launcher OpenHABSitemapSynchronizerLauncher startup delay detected!
17:22:15.743 [pool-1-thread-4] WARN  o.o.j.p.Launcher Launcher OpenHABConfigSynchronizerLauncher startup delay detected!
17:22:15.743 [pool-1-thread-5] WARN  o.o.j.p.Launcher Launcher OpenHABDeviceManagerLauncher startup delay detected!
17:23:15.746 [pool-1-thread-4] WARN  o.o.j.p.Launcher Launcher OpenHABConfigSynchronizerLauncher startup delay detected!
17:23:15.746 [pool-1-thread-6] WARN  o.o.j.p.Launcher Launcher OpenHABSitemapSynchronizerLauncher startup delay detected!
17:23:15.746 [pool-1-thread-5] WARN  o.o.j.p.Launcher Launcher OpenHABDeviceManagerLauncher startup delay detected!
17:24:15.749 [pool-1-thread-6] WARN  o.o.j.p.Launcher Launcher OpenHABSitemapSynchronizerLauncher startup delay detected!
17:24:15.749 [pool-1-thread-5] WARN  o.o.j.p.Launcher Launcher OpenHABDeviceManagerLauncher startup delay detected!
17:24:15.749 [pool-1-thread-4] WARN  o.o.j.p.Launcher Launcher OpenHABConfigSynchronizerLauncher startup delay detected!
17:25:15.755 [pool-1-thread-6] WARN  o.o.j.p.Launcher Launcher OpenHABSitemapSynchronizerLauncher startup delay detected!
17:25:15.755 [pool-1-thread-5] WARN  o.o.j.p.Launcher Launcher OpenHABDeviceManagerLauncher startup delay detected!
17:25:15.755 [pool-1-thread-4] WARN  o.o.j.p.Launcher Launcher OpenHABConfigSynchronizerLauncher startup delay detected!
17:26:15.762 [pool-1-thread-4] WARN  o.o.j.p.Launcher Launcher OpenHABConfigSynchronizerLauncher startup delay detected!
17:26:15.762 [pool-1-thread-6] WARN  o.o.j.p.Launcher Launcher OpenHABSitemapSynchronizerLauncher startup delay detected!
17:26:15.762 [pool-1-thread-5] WARN  o.o.j.p.Launcher Launcher OpenHABDeviceManagerLauncher startup delay detected!
17:27:15.765 [pool-1-thread-5] WARN  o.o.j.p.Launcher Launcher OpenHABDeviceManagerLauncher startup delay detected!
17:27:15.765 [pool-1-thread-6] WARN  o.o.j.p.Launcher Launcher OpenHABSitemapSynchronizerLauncher startup delay detected!
17:27:15.765 [pool-1-thread-4] WARN  o.o.j.p.Launcher Launcher OpenHABConfigSynchronizerLauncher startup delay detected!