So I see multiple posts on the Blacklisting problems, and I also got hit with again last night. It seems this time the first black list error hit about midnight. Then the last item in my events log is at 6am.
Per @Kai I also did change this yesterday afternoon, by stopping OH2, making change and restarting.
could you try to edit two lines in the file etc/org.apache.felix.fileinstall-deploy.cfgto:
felix.fileinstall.start.level = 100
felix.fileinstall.active.level = 100
I may back up my system later due a uninstall/purgh of OH2, then reinstall from scratch and restore to see if makes any difference. Perhaps moving from a past stable/beta to testing is doing something?
The Event log around midnight
2017-12-06 00:43:15.049 [vent.ItemStateChangedEvent] - astro_moon_home_position_azimuth changed from 123.68 to 123.98
2017-12-06 00:43:25.854 [vent.ItemStateChangedEvent] - astro_moon_home_position_elevation changed from 55.14 to 55.29
2017-12-06 00:43:25.855 [vent.ItemStateChangedEvent] - Moon_Azimuth changed from 123.68 to 123.98
2017-12-06 00:43:26.664 [vent.ItemStateChangedEvent] - zwave_serial_zstick_1587fb1d72a_serial_sof changed from 2990 to 2991
2017-12-06 00:43:26.669 [vent.ItemStateChangedEvent] - zwave_serial_zstick_1587fb1d72a_serial_ack changed from 2053 to 2054
2017-12-06 00:43:26.669 [vent.ItemStateChangedEvent] - zwave_serial_zstick_1587fb1d72a_serial_sof changed from 2991 to 2992
2017-12-06 00:43:26.674 [vent.ItemStateChangedEvent] - zwave_serial_zstick_1587fb1d72a_serial_ack changed from 2054 to 2055
2017-12-06 00:43:26.675 [vent.ItemStateChangedEvent] - zwave_serial_zstick_1587fb1d72a_serial_sof changed from 2992 to 2993
2017-12-06 00:43:26.746 [vent.ItemStateChangedEvent] - zwave_serial_zstick_1587fb1d72a_serial_ack changed from 2055 to 2056
2017-12-06 00:43:26.748 [vent.ItemStateChangedEvent] - zwave_serial_zstick_1587fb1d72a_serial_sof changed from 2993 to 2994
2017-12-06 00:43:27.276 [vent.ItemStateChangedEvent] - Moon_Elevation changed from 55.14 to 55.29
and OH log at same time.
2017-12-06 00:00:45.377 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest finished.
2017-12-06 00:30:00.015 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest executed...
2017-12-06 00:30:00.066 [INFO ] [lipse.smarthome.io.net.exec.ExecUtil] - executed commandLine '[phantomjs, /etc/openhab2/scripts/weather.js]'
2017-12-06 00:30:42.541 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest finished.
2017-12-06 00:43:26.849 [WARN ] [org.apache.karaf.services.eventadmin] - EventAdmin: Blacklisting ServiceReference [{org.osgi.service.event.EventHandler, org.apache.karaf.event.service.EventCollector}={event.topics=*, service.id=129, service.bundleid=53, service.scope=singleton} | Bundle(org.apache.karaf.event_4.1.3 [53])] due to timeout!
2017-12-06 00:43:27.273 [WARN ] [org.apache.karaf.services.eventadmin] - EventAdmin: Blacklisting ServiceReference [{org.osgi.service.event.EventHandler, org.apache.karaf.event.service.EventCollector}={event.topics=*, service.id=129, service.bundleid=53, service.scope=singleton} | Bundle(org.apache.karaf.event_4.1.3 [53])] due to timeout!
2017-12-06 01:00:00.013 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest executed...
2017-12-06 01:00:00.319 [INFO ] [lipse.smarthome.io.net.exec.ExecUtil] - executed commandLine '[phantomjs, /etc/openhab2/scripts/weather.js]'
2017-12-06 01:00:40.668 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest finished.
And last entries at 6am in the Event
017-12-06 06:00:08.814 [vent.ItemStateChangedEvent] - Wunderground_WindSpeed changed from 5.0 to 8.0
2017-12-06 06:00:11.219 [hingStatusInfoChangedEvent] - 'zwave:device:1587fb1d72a:node20' changed from OFFLINE (COMMUNICATION_ERROR): Node is not communicating with controller to ONLINE
2017-12-06 06:00:17.375 [vent.ItemStateChangedEvent] - zwave_serial_zstick_1587fb1d72a_serial_sof changed from 6732 to 6733
2017-12-06 06:00:17.378 [vent.ItemStateChangedEvent] - Wunderground_WindChill changed from -2 to -4
2017-12-06 06:00:17.411 [vent.ItemStateChangedEvent] - zwave_device_1587fb1d72a_node20_meter_watts changed from 967.123 to 916.646
2017-12-06 06:00:17.411 [vent.ItemStateChangedEvent] - Home_Energy_Watts changed from 967.123 to 916.646
2017-12-06 06:00:17.411 [vent.ItemStateChangedEvent] - Wunderground_FeelTemp changed from -2 to -4
2017-12-06 06:00:17.411 [vent.ItemStateChangedEvent] - ntp_ntp_local_dateTime changed from 2017-12-06T05:59:13.258-0500 to 2017-12-06T06:00:17.371-0500
2017-12-06 06:00:17.411 [vent.ItemStateChangedEvent] - Date changed from 2017-12-06T05:59:13.258-0500 to 2017-12-06T06:00:17.371-0500
2017-12-06 06:00:17.411 [vent.ItemStateChangedEvent] - CTime changed from 2017-12-06T05:59:13.258-0500 to 2017-12-06T06:00:17.371-0500
2017-12-06 06:00:17.412 [vent.ItemStateChangedEvent] - CurrentDate changed from 2017-12-06T05:59:13.258-0500 to 2017-12-06T06:00:17.371-0500
2017-12-06 06:00:17.460 [vent.ItemStateChangedEvent] - ntp_ntp_local_string changed from 2017-12-06 05:59:13 EST to 2017-12-06 06:00:17 EST
And the OH2 log
2017-12-06 05:30:44.878 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest finished.
2017-12-06 06:00:00.008 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest executed...
2017-12-06 06:00:00.098 [INFO ] [lipse.smarthome.io.net.exec.ExecUtil] - executed commandLine '[phantomjs, /etc/openhab2/scripts/weather.js]'
2017-12-06 06:00:17.787 [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=49, service.id=147, service.bundleid=111, service.scope=bundle} | Bundle(org.eclipse.smarthome.core_0.9.0.201712011551 [111])] due to timeout!
2017-12-06 06:00:17.992 [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=49, service.id=147, service.bundleid=111, service.scope=bundle} | Bundle(org.eclipse.smarthome.core_0.9.0.201712011551 [111])] due to timeout!
2017-12-06 06:00:45.963 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest finished.
2017-12-06 06:28:54.843 [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.201712040209]
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
2017-12-06 06:28:55.048 [ERROR] [io.openhabcloud.internal.CloudClient] - Socket.IO error: {}
io.socket.engineio.client.EngineIOException: websocket error
at io.socket.engineio.client.Transport.onError(Transport.java:71) [230:org.openhab.io.openhabcloud:2.2.0.201712041044]
at io.socket.engineio.client.transports.WebSocket.access$500(WebSocket.java:28) [230:org.openhab.io.openhabcloud:2.2.0.201712041044]
at io.socket.engineio.client.transports.WebSocket$2$5.run(WebSocket.java:150) [230:org.openhab.io.openhabcloud:2.2.0.201712041044]
at io.socket.thread.EventThread$2.run(EventThread.java:80) [230:org.openhab.io.openhabcloud:2.2.0.201712041044]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
at java.lang.Thread.run(Thread.java:748) [?:?]
Caused by: java.io.EOFException
at okio.RealBufferedSource.require(RealBufferedSource.java:59) ~[?:?]
at okio.RealBufferedSource.readByte(RealBufferedSource.java:72) ~[?:?]
at okhttp3.internal.ws.WebSocketReader.readHeader(WebSocketReader.java:115) ~[?:?]
at okhttp3.internal.ws.WebSocketReader.processNextFrame(WebSocketReader.java:104) ~[?:?]
at okhttp3.internal.ws.RealWebSocket.readMessage(RealWebSocket.java:97) ~[?:?]
at okhttp3.ws.WebSocketCall.createWebSocket(WebSocketCall.java:152) ~[?:?]
at okhttp3.ws.WebSocketCall.access$000(WebSocketCall.java:41) ~[?:?]
at okhttp3.ws.WebSocketCall$1.onResponse(WebSocketCall.java:97) ~[?:?]
at okhttp3.RealCall$AsyncCall.execute(RealCall.java:126) ~[?:?]
at okhttp3.internal.NamedRunnable.run(NamedRunnable.java:32) ~[?:?]
... 3 more
2017-12-06 06:28:57.516 [ERROR] [t.mqtt.internal.MqttBrokerConnection] - MQTT connection to 'mosquitto' was lost: Connection lost : ReasonCode 32109 : Cause : null
2017-12-06 06:28:57.517 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting connection helper to periodically try restore connection to broker 'mosquitto'
2017-12-06 06:28:58.065 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 5e173177-a9ee-47cc-a2bb-ec0f655ee0f8, base URL = http://localhost:8080)
2017-12-06 06:29:06.812 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 5e173177-a9ee-47cc-a2bb-ec0f655ee0f8, base URL = http://localhost:8080)
2017-12-06 06:29:08.658 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'mosquitto'
2017-12-06 06:30:01.732 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest executed...
2017-12-06 06:30:01.857 [INFO ] [lipse.smarthome.io.net.exec.ExecUtil] - executed commandLine '[phantomjs, /etc/openhab2/scripts/weather.js]'
2017-12-06 06:30:42.025 [INFO ] [arthome.model.script.speedtest.rules] - --> speedtest finished.