Perfekt! I’ll test it tomorrow!
I’ll do it
NOW!
@Kai @sihui I’d like to test the new build too but I ran into some problems updating my system (running openhabian).
2017-12-02_15:20:08_CET [openHABian] Checking for root privileges... OK
2017-12-02_15:20:08_CET [openHABian] Loading configuration file '/etc/openhabian.conf'... OK
2017-12-02_15:21:00_CET [openHABian] Installing or switching to openHAB 2.2 SNAPSHOT (unstable)...
$ apt update
/usr/lib/apt/methods/https: error while loading shared libraries: labhogweed.co.2: cannot open shared object file: No such file or directory
/usr/lib/apt/methods/https: error while loading shared libraries: labhogweed.co.2: cannot open shared object file: No such file or directory
/usr/lib/apt/methods/https: error while loading shared libraries: labhogweed.co.2: cannot open shared object file: No such file or directory
E: Method https has died unexpectedly!
E: Sub-process https returned an error code (127)
E: Method /usr/lib/apt/methods/https did not start correctly
E: Method https has died unexpectedly!
E: Sub-process https returned an error code (127)
E: Method /usr/lib/apt/methods/https did not start correctly
E: Method https has died unexpectedly!
E: Sub-process https returned an error code (127)
E: Method /usr/lib/apt/methods/https did not start correctly
$ apt -y install openhab2
Reading package lists... Done
Building dependency tree
Reading state information... Done
openhab2 is already the newest version.
The following package was automatically installed and is no longer required:
triggerhappy
Use 'apt-get autoremove' to remove it.
0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
$ adduser openhab dialout
The user `openhab' is already a member of `dialout'.
$ adduser openhab tty
The user `openhab' is already a member of `tty'.
$ systemctl daemon-reload
$ systemctl enable openhab2.service
Synchronizing state for openhab2.service with sysvinit using update-rc.d...
Executing /usr/sbin/update-rc.d openhab2 defaults
Executing /usr/sbin/update-rc.d openhab2 enable
OK
$ systemctl restart openhab2.service
2017-12-02_15:22:07_CET [openHABian] Checking for default openHABian username:password combination... OK
2017-12-02_15:22:07_CET [openHABian] We hope you got what you came for! See you again soon ;)
Can you give me a quick hint how to solve this Problem?
Sorry, I’m not using apt-get nor openHABian, I have no idea.
I upgraded to Build 1104 and Openhab is still locking up and Freezing on me. The last 2 days, this has happened at about 6:30am both times. I’m sure of that time as I see that is the last time my weather was updated and also in the logs.
In my Event log, the last item shown is 6:33am and then corresponding in my Openhab log at 6:33am I see this:
2017-12-03 06:33:15.067 [WARN ] [org.apache.karaf.services.eventadmin] - EventAdmin: Blacklisting ServiceReference [{org.osgi.service.event.EventHandler, org.eclipse.smarthome.core.events.EventPublisher}={event.topics=smarthome, component.name=org.eclipse.smarthome.core.internal.events.OSGiEventManager, component.id=41, service.id=143, service.bundleid=112, service.scope=bundle} | Bundle(org.eclipse.smarthome.core_0.9.0.201712011551 [112])] due to timeout!
2017-12-03 06:33:15.882 [WARN ] [org.apache.karaf.services.eventadmin] - EventAdmin: Blacklisting ServiceReference [{org.osgi.service.event.EventHandler, org.eclipse.smarthome.core.events.EventPublisher}={event.topics=smarthome, component.name=org.eclipse.smarthome.core.internal.events.OSGiEventManager, component.id=41, service.id=143, service.bundleid=112, service.scope=bundle} | Bundle(org.eclipse.smarthome.core_0.9.0.201712011551 [112])] due to timeout!
2017-12-03 06:39:47.675 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 5e173177-a9ee-47cc-a2bb-ec0f655ee0f8, base URL = http://localhost:8080)
2017-12-03 06:39:48.126 [ERROR] [o.client.mqttv3.internal.ClientState] - openhabserver: Timed out as no write activity, keepAlive=60,000 lastOutboundActivity=1,512,300,742,808 lastInboundActivity=1,512,300,742,808 time=1,512,301,184,991 lastPing=1,512,300,742,808
2017-12-03 06:39:48.669 [ERROR] [t.mqtt.internal.MqttBrokerConnection] - MQTT connection to broker was lost
org.eclipse.paho.client.mqttv3.MqttException: Connection lost
at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:146) [231:org.openhab.io.transport.mqtt:1.11.0.201712020209]
at java.lang.Thread.run(Thread.java:748) [?:?]
Caused by: java.io.EOFException
at java.io.DataInputStream.readByte(DataInputStream.java:267) ~[?:?]
at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:65) ~[?:?]
at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:107) ~[?:?]
... 1 more
A restart of my system gets OH2 running again, but obviously you should not need to restart each morning.
The “Blacklisting” error was fixed with #1103, so I guess you should delete tmp and cache (the usual approach if something like this happens) …
My assumption is that our pool size is simply too small - https://github.com/openhab/openhab-distro/pull/594 should hopefully help on that.
Installed #1112 yesterday (full clean installation) and still the same happens. Works approx 2,5 hours and then stops with the Blacklisting errors. Tested several times. Running on i686 synology.
Please post logs of the seconds before the blacklisting happens - best to have openhab.log AND events.log of that time. This would help us with the analysis. Thanks!
P.S.: I hope those commands are also available on your Synology…
P.P.S: Regarding EC KeyPairGenerator not available
: Which JVM are you running? Is that an OpenJDK or OracleJDK?
Here are the logs:
- Full openhab.log since last restart:
Caused by: java.security.NoSuchAlgorithmException: EC KeyPairGenerator not available
at java.security.KeyPairGenerator.getInstance(KeyPairGenerator.java:218) ~[?:?]
at org.openhab.io.jetty.certificate.internal.CertificateGenerator.generateCertificate(CertificateGenerator.java:163) ~[?:?]
... 17 more
2017-12-05 23:37:33.568 [WARN ] [org.apache.karaf.shell.ssh.SshUtils ] - Configured keyexchange 'ecdh-sha2-nistp521' not available
2017-12-05 23:37:33.587 [WARN ] [org.apache.karaf.shell.ssh.SshUtils ] - Configured keyexchange 'ecdh-sha2-nistp384' not available
2017-12-05 23:37:33.593 [WARN ] [org.apache.karaf.shell.ssh.SshUtils ] - Configured keyexchange 'ecdh-sha2-nistp256' not available
2017-12-05 23:37:41.247 [WARN ] [g.eclipse.smarthome.core.net.NetUtil] - Found multiple local interfaces - ignoring 192.123.120.1
2017-12-05 23:37:41.252 [WARN ] [g.eclipse.smarthome.core.net.NetUtil] - Found multiple local interfaces - ignoring 192.123.123.250
2017-12-05 23:37:41.517 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at http://172.17.0.1:8080
2017-12-05 23:37:41.524 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at https://172.17.0.1:8443
2017-12-05 23:37:57.273 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'kodu.items'
2017-12-05 23:38:01.447 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'db4o.persist'
2017-12-05 23:38:01.544 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'mysql.persist'2017-12-05 23:38:01.639 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'influxdb.persist'
2017-12-05 23:38:01.683 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'exec.persist'
2017-12-05 23:38:01.724 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'logging.persist'
2017-12-05 23:38:09.438 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2017-12-05 23:38:27.953 [INFO ] [nternal.connection.OneWireConnection] - Connected to owserver [IP '192.168.1.177' Port '4304']
2017-12-05 23:38:44.987 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin
2017-12-05 23:38:45.445 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'kodu.rules'
2017-12-05 23:38:50.891 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app
2017-12-05 23:38:52.537 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'kodu.sitemap'
2017-12-05 23:38:53.529 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'kodu.things'
2017-12-05 23:38:56.387 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'squeezebox:squeezeboxserver:F7891575-6431-416E-9894-3B125B8D6733' to inbox.
2017-12-05 23:38:58.435 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2017-12-05 23:39:01.573 [ERROR] [core.karaf.internal.FeatureInstaller] - Failed installing 'openhab-binding-http, openhab-action-squeezebox': Error restarting bundles:
Unable to acquire the state change lock for the module: osgi.identity; type="osgi.bundle"; version:Version="0.9.0.201712011551"; osgi.identity="org.eclipse.smarthome.model.script.runtime"; singleton:="true" [id=147] STARTED [STARTED]
2017-12-05 23:39:05.137 [ERROR] [ntime.internal.engine.RuleEngineImpl]
- Rule 'Temperatuuri perioodide seadmine': The name 'Norm_Aja_Lopp_M'
cannot be resolved to an item or type; line 352, column 15, length 15
2017-12-05 23:39:05.141 [ERROR] [ntime.internal.engine.RuleEngineImpl]
- Rule 'Thermostat for Floor_Pesuruum': The name
'Floor_Pesuruum_Temperature' cannot be resolved to an item or type; line
241, column 30, length 26
2017-12-06 02:04:26.170 [WARN ] [org.apache.karaf.services.eventadmin] - EventAdmin: Blacklisting ServiceReference [{org.osgi.service.event.EventHandler, org.eclipse.smarthome.core.events.EventPublisher}={event.topics=smarthome, component.name=org.eclipse.smarthome.core.internal.events.OSGiEventManager, component.id=44, service.id=147, service.bundleid=109, service.scope=bundle} | Bundle(org.eclipse.smarthome.core_0.9.0.201712011551 [109])] due to timeout!
2017-12-06 02:04:27.356 [WARN ] [nal.common.AbstractInvocationHandler]
- Timeout of 5000ms exceeded while calling method
'EventSubscriber.receive()' on 'org.eclipse.smarthome.io.monitor.internal.EventLogger@12d6ac69'. Thread 'safeCall-82' (2904) is in state 'TIMED_WAITING'
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
2017-12-06 02:04:29.514 [WARN ] [org.apache.karaf.services.eventadmin] - EventAdmin: Blacklisting ServiceReference [{org.osgi.service.event.EventHandler, org.eclipse.smarthome.core.events.EventPublisher}={event.topics=smarthome, component.name=org.eclipse.smarthome.core.internal.events.OSGiEventManager, component.id=44, service.id=147, service.bundleid=109, service.scope=bundle} | Bundle(org.eclipse.smarthome.core_0.9.0.201712011551 [109])] due to timeout!
2017-12-06 02:04:29.621 [WARN ] [ore.internal.events.OSGiEventManager] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@12d6ac69' takes more than 5000ms.
Events.log prior crash:
2017-12-06 01:56:17.029 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler_Message changed from 37.0°C, 34.0°C to 37.6°C, 34.0°C
2017-12-06 01:56:45.798 [vent.ItemStateChangedEvent] - Boiler_Room_Temperature changed from 8.0625 to 8
2017-12-06 01:56:45.804 [vent.ItemStateChangedEvent] -
Boiler_Room_LastUpdate changed from 2017-12-06T01:54:45.798+0200 to
2017-12-06T01:56:45.799+0200
2017-12-06 01:57:01.151 [vent.ItemStateChangedEvent] - Date changed from
2017-12-06T01:56:01.131+0200 to 2017-12-06T01:57:01.134+0200
2017-12-06 01:57:14.201 [vent.ItemStateChangedEvent] - Leiliruum_Temperature changed from 21.25 to 21.1875
2017-12-06 01:57:14.220 [vent.ItemStateChangedEvent] -
Leiliruum_LastUpdate changed from 2017-12-06T01:56:14.212+0200 to
2017-12-06T01:57:14.215+0200
2017-12-06 01:57:15.772 [vent.ItemStateChangedEvent] - Boiler_Room_Temperature changed from 8 to 8.0625
2017-12-06 01:57:15.778 [vent.ItemStateChangedEvent] -
Boiler_Room_LastUpdate changed from 2017-12-06T01:56:45.799+0200 to
2017-12-06T01:57:15.773+0200
2017-12-06 01:57:16.839 [vent.ItemStateChangedEvent] - Heating_Burner_Hours changed from 31019.677734 to 31019.695312
2017-12-06 01:57:17.097 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler changed from 37.599998 to 39.299999
2017-12-06 01:57:17.118 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler_Message changed from 37.6°C, 34.0°C to 39.3°C, 34.0°C
2017-12-06 01:57:44.206 [vent.ItemStateChangedEvent] - Leiliruum_Temperature changed from 21.1875 to 21.25
2017-12-06 01:57:44.280 [vent.ItemStateChangedEvent] -
Leiliruum_LastUpdate changed from 2017-12-06T01:57:14.215+0200 to
2017-12-06T01:57:44.273+0200
2017-12-06 01:58:01.163 [vent.ItemStateChangedEvent] - Date changed from
2017-12-06T01:57:01.134+0200 to 2017-12-06T01:58:01.148+0200
2017-12-06 01:58:16.992 [vent.ItemStateChangedEvent] - Heating_Burner_Hours changed from 31019.695312 to 31019.710938
2017-12-06 01:58:17.350 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler changed from 39.299999 to 42.000000
2017-12-06 01:58:17.380 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler_Message changed from 39.3°C, 34.0°C to 42.0°C, 34.0°C
2017-12-06 01:58:17.594 [vent.ItemStateChangedEvent] - Heating_Temperature_Water changed from 52.799999 to 52.700001
2017-12-06 01:58:17.685 [vent.ItemStateChangedEvent] - Heating_Temperature_Water_Message changed from 52.8°C, 60.0°C to 52.7°C, 60.0°C
2017-12-06 01:58:44.269 [vent.ItemStateChangedEvent] - Leiliruum_Temperature changed from 21.25 to 21.1875
2017-12-06 01:58:44.297 [vent.ItemStateChangedEvent] -
Leiliruum_LastUpdate changed from 2017-12-06T01:57:44.273+0200 to
2017-12-06T01:58:44.292+0200
2017-12-06 01:59:01.164 [vent.ItemStateChangedEvent] - Date changed from
2017-12-06T01:58:01.148+0200 to 2017-12-06T01:59:01.151+0200
2017-12-06 01:59:17.064 [vent.ItemStateChangedEvent] - Heating_Burner_Hours changed from 31019.710938 to 31019.728516
2017-12-06 01:59:17.368 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler changed from 42.000000 to 41.299999
2017-12-06 01:59:17.370 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler_Message changed from 42.0°C, 34.0°C to 41.3°C, 34.0°C
2017-12-06 01:59:44.212 [vent.ItemStateChangedEvent] - Leiliruum_Temperature changed from 21.1875 to 21.25
2017-12-06 01:59:44.296 [vent.ItemStateChangedEvent] -
Leiliruum_LastUpdate changed from 2017-12-06T01:58:44.292+0200 to
2017-12-06T01:59:44.291+0200
2017-12-06 02:00:01.166 [vent.ItemStateChangedEvent] - Date changed from
2017-12-06T01:59:01.151+0200 to 2017-12-06T02:00:01.153+0200
2017-12-06 02:00:17.356 [vent.ItemStateChangedEvent] - Heating_Burner_Hours changed from 31019.728516 to 31019.746094
2017-12-06 02:00:17.991 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler changed from 41.299999 to 40.500000
2017-12-06 02:00:18.602 [vent.ItemStateChangedEvent] - Heating_Temperature_Water changed from 52.700001 to 52.599998
2017-12-06 02:00:18.603 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler_Message changed from 41.3°C, 34.0°C to 40.5°C, 34.0°C
2017-12-06 02:00:18.815 [vent.ItemStateChangedEvent] - Heating_Temperature_Water_Message changed from 52.7°C, 60.0°C to 52.6°C, 60.0°C
2017-12-06 02:00:44.233 [vent.ItemStateChangedEvent] - Leiliruum_Temperature changed from 21.25 to 21.1875
2017-12-06 02:00:44.980 [vent.ItemStateChangedEvent] - Floor_Pesuruum_Temperature changed from 24.25 to 24.1875
2017-12-06 02:00:45.184 [vent.ItemStateChangedEvent] -
Leiliruum_LastUpdate changed from 2017-12-06T01:59:44.291+0200 to
2017-12-06T02:00:44.553+0200
2017-12-06 02:00:45.225 [vent.ItemStateChangedEvent] -
Floor_Pesuruum_LastUpdate changed from 2017-12-06T01:54:14.941+0200 to
2017-12-06T02:00:45.044+0200
2017-12-06 02:01:01.820 [vent.ItemStateChangedEvent] - Date changed from
2017-12-06T02:00:01.153+0200 to 2017-12-06T02:01:01.190+0200
2017-12-06 02:01:22.191 [vent.ItemStateChangedEvent] - Heating_Burner_Hours changed from 31019.746094 to 31019.761719
2017-12-06 02:01:44.240 [vent.ItemStateChangedEvent] - Leiliruum_Temperature changed from 21.1875 to 21.25
2017-12-06 02:01:44.400 [vent.ItemStateChangedEvent] -
Leiliruum_LastUpdate changed from 2017-12-06T02:00:44.553+0200 to
2017-12-06T02:01:44.360+0200
2017-12-06 02:02:02.351 [vent.ItemStateChangedEvent] - Date changed from
2017-12-06T02:01:01.190+0200 to 2017-12-06T02:02:01.815+0200
2017-12-06 02:02:14.443 [vent.ItemStateChangedEvent] - Leiliruum_Temperature changed from 21.25 to 21.1875
2017-12-06 02:02:14.741 [vent.ItemStateChangedEvent] -
Leiliruum_LastUpdate changed from 2017-12-06T02:01:44.360+0200 to
2017-12-06T02:02:14.352+0200
2017-12-06 02:02:18.370 [vent.ItemStateChangedEvent] - Heating_Burner_Hours changed from 31019.761719 to 31019.779297
2017-12-06 02:02:18.647 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler changed from 40.500000 to 40.000000
2017-12-06 02:02:18.902 [vent.ItemStateChangedEvent] - Heating_Temperature_Water changed from 52.599998 to 52.500000
2017-12-06 02:02:19.423 [vent.ItemStateChangedEvent] - Heating_Temperature_Water_Message changed from 52.6°C, 60.0°C to 52.5°C, 60.0°C
2017-12-06 02:02:19.439 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler_Message changed from 40.5°C, 34.0°C to 40.0°C, 34.0°C
2017-12-06 02:03:02.148 [vent.ItemStateChangedEvent] - Date changed from
2017-12-06T02:02:01.815+0200 to 2017-12-06T02:03:01.839+0200
2017-12-06 02:03:14.666 [vent.ItemStateChangedEvent] - Leiliruum_Temperature changed from 21.1875 to 21.25
2017-12-06 02:03:16.239 [vent.ItemStateChangedEvent] -
Leiliruum_LastUpdate changed from 2017-12-06T02:02:14.352+0200 to
2017-12-06T02:03:15.472+0200
2017-12-06 02:03:16.928 [vent.ItemStateChangedEvent] - Boiler_Room_Temperature changed from 8.0625 to 8
2017-12-06 02:03:17.194 [vent.ItemStateChangedEvent] -
Boiler_Room_LastUpdate changed from 2017-12-06T01:57:15.773+0200 to
2017-12-06T02:03:17.076+0200
2017-12-06 02:03:20.921 [vent.ItemStateChangedEvent] - Heating_Burner_Hours changed from 31019.779297 to 31019.794922
2017-12-06 02:03:21.214 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler changed from 40.000000 to 39.299999
2017-12-06 02:03:21.585 [vent.ItemStateChangedEvent] - Heating_Temperature_Boiler_Message changed from 40.0°C, 34.0°C to 39.3°C, 34.0°C
2017-12-06 02:03:53.146 [vent.ItemStateChangedEvent] - Leiliruum_Temperature changed from 21.25 to 21.1875
2017-12-06 02:04:08.156 [vent.ItemStateChangedEvent] -
Leiliruum_LastUpdate changed from 2017-12-06T02:03:15.472+0200 to
2017-12-06T02:03:55.555+0200
2017-12-06 02:04:08.417 [vent.ItemStateChangedEvent] - Date changed from
2017-12-06T02:03:01.839+0200 to 2017-12-06T02:04:05.754+0200
2017-12-06 02:04:16.534 [vent.ItemStateChangedEvent] - Boiler_Room_Temperature changed from 8 to 8.0625
2017-12-06 02:04:23.653 [vent.ItemStateChangedEvent] -
Boiler_Room_LastUpdate changed from 2017-12-06T02:03:17.076+0200 to
2017-12-06T02:04:23.566+0200
Many thanks for your logs - they are very helpful, although I have to admit that we do not understand how we get into this situation…
May I hence ask you for some further support? Once your system is in the blacklisted state, could you take a thread dump from it and post it? You can do so by running
jstack <pid> > stack.txt
You can obtain the pid of your Java process through the jps
command. Thanks!
P.S.: I hope those commands are available on your Synology…
P.P.S.: Regarding EC KeyPairGenerator not available
: Which JVM are you running? Is this an OpenJDK or OracleJDK?
It took a bit more time for the state to repeat, but now I got it.
On the PSS, as a surprise yes, it is OpenJDK - some misbehaviour of the Synology java manager as I have for sure installed the Oracle JDK also. Need to investigate, but didn’t want to do before I get you the asked stack. Could it be the reason for freezing?
Stepped at really stupid question now. How shall I share the stack with you? it does not fit to message body here and I cannot find way to attach a file. Sorry.
It potentially could be. We sometimes see weird behavior with the plain OpenJDK, thus we do not recommend its use.
How shall I share the stack with you?
How about using Pastebin?
Same here. Runs for some days.
Then it stops Receiving item Updates in total.
Thanks @Jaak for the stacktrace - unfortunately, there isn’t anything suspicious in it that would point at a concrete problem.
Please note that the blacklisting has been deactivated since build #1117 - so I would assume that your (and @shorty707’s) problems should be solved if you use any version newer than that.
Meanwhile I have moved out from OpenJDK and checked that this did not help. Currently newer build where timeout is disabled is running stable.
hey @Kai I still have the issue on #1143
works flawlessy for a couple days. all of a sudden no items are updated anymore.
any logging that helps here?
this is from this morning (as said its on build #1143)
06:14:04.559 [INFO ] [marthome.event.ItemStateChangedEvent] - XAIR_temp changed from 21.5 to 21.6
06:14:12.300 [WARN ] [org.apache.karaf.services.eventadmin] - EventAdmin: Blacklisting ServiceReference [{org.osgi.service.event.EventHandler, org.eclipse.smarthome.core.events.EventPublisher}={event.topics=smarthome, component.name=org.eclipse.smarthome.core.internal.events.OSGiEventManager, component.id=71, service.id=182, service.bundleid=110, service.scope=bundle} | Bundle(org.eclipse.smarthome.core_0.10.0.b1 [110])] due to timeout!
06:14:12.379 [WARN ] [org.apache.karaf.services.eventadmin] - EventAdmin: Blacklisting ServiceReference [{org.osgi.service.event.EventHandler, org.eclipse.smarthome.core.events.EventPublisher}={event.topics=smarthome, component.name=org.eclipse.smarthome.core.internal.events.OSGiEventManager, component.id=71, service.id=182, service.bundleid=110, service.scope=bundle} | Bundle(org.eclipse.smarthome.core_0.10.0.b1 [110])] due to timeout!
07:30:14.241 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
at su.litvak.chromecast.api.v2.Channel.read(Channel.java:413) [231:org.openhab.binding.chromecast:2.2.0.201712161137]
at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:50) [231:org.openhab.binding.chromecast:2.2.0.201712161137]
at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:132) [231:org.openhab.binding.chromecast:2.2.0.201712161137]
07:30:14.251 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - <-- null payload in message
java.lang.InterruptedException
at java.lang.Object.wait(Native Method)
at su.litvak.chromecast.api.v2.Channel$ResultProcessor.get(Channel.java:239)
at su.litvak.chromecast.api.v2.Channel.send(Channel.java:367)
at su.litvak.chromecast.api.v2.Channel.sendStandard(Channel.java:336)
at su.litvak.chromecast.api.v2.Channel.getStatus(Channel.java:451)
at su.litvak.chromecast.api.v2.ChromeCast.getStatus(ChromeCast.java:179)
at org.openhab.binding.chromecast.internal.ChromecastCommander.handleRefresh(ChromecastCommander.java:87)
at org.openhab.binding.chromecast.handler.ChromecastHandler$Coordinator$1.run(ChromecastHandler.java:231)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
07:35:22.812 [WARN ] [ommandclass.ZWaveBatteryCommandClass] - NODE 12: BATTERY LOW!