After the upgrade to 2.2 z-way, IPP, Modus, InfluDB frequently stopping
I’ve tried this temporary fixed, with no effect:
/var/lib/openhab2/etc/org.apache.felix.eventadmin.impl.EventAdmin.cfg
added:
prevent timeout
org.apache.felix.eventadmin.Timeout=0
Running a Raspberry 3with habinan on the lastest stabile version.
In the log this is reported after a reboot:
2017-12-21 19:38:43.856 [WARN ] [ome.core.thing.internal.ThingManager] - Disposing handler for thing ‘network:device:192_168_0_253’ takes more than 5000ms.
2017-12-21 19:39:31.699 [INFO ] [er.internal.HomeBuilderDashboardTile] - Started Home Builder at /homebuilder
2017-12-21 19:39:33.603 [WARN ] [.core.internal.i18n.I18nProviderImpl] - Could not set new location, keeping old one:
2017-12-21 19:39:33.613 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to de_AT, Location set to null, Time zone set to Europe/Vienna
2017-12-21 19:39:35.769 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at http://192.168.0.67:8080
2017-12-21 19:39:35.776 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at https://192.168.0.67:8443
2017-12-21 19:39:46.720 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.items’
2017-12-21 19:39:47.974 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'logging.persist’
2017-12-21 19:39:48.019 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'rrd4j.persist’
2017-12-21 19:39:48.083 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'influxdb.persist’
2017-12-21 19:39:48.135 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'exec.persist’
2017-12-21 19:40:01.894 [INFO ] [el.core.internal.ModelRepositoryImpl] - Validation issues found in configuration model ‘holiday.script’, using it anyway:
The value of the local variable stAdvent is not used
The operator ‘!=’ should be replaced by ‘!==’ when null is one of the arguments.
2017-12-21 19:40:01.903 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'holiday.script’
2017-12-21 19:40:02.241 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2017-12-21 19:40:03.512 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'kaffee.rules’
2017-12-21 19:40:04.233 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'warmwasser.rules’
2017-12-21 19:40:06.118 [INFO ] [el.core.internal.ModelRepositoryImpl] - Validation issues found in configuration model ‘weather.rules’, using it anyway:
The method toDateMidnight() from the type DateTime is deprecated
The method toDateMidnight() from the type DateTime is deprecated
2017-12-21 19:40:06.126 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather.rules’
2017-12-21 19:40:07.962 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'stromueberschuss.rules’
2017-12-21 19:40:08.078 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'holiday.rules’
2017-12-21 19:40:11.186 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'lichterketten.rules’
2017-12-21 19:40:11.401 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'airquality.rules’
2017-12-21 19:40:14.996 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'urlaub.rules’
2017-12-21 19:40:15.301 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'startup.rules’
2017-12-21 19:40:15.560 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'tagnacht.rules’
2017-12-21 19:40:16.722 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.sitemap’
2017-12-21 19:40:17.218 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'airquality.things’
2017-12-21 19:40:17.253 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'yahooweather.things’
2017-12-21 19:40:17.275 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'astro.things’
2017-12-21 19:40:19.025 [INFO ] [thome.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:sun:home
2017-12-21 19:40:19.065 [INFO ] [ding.astro.handler.AstroThingHandler] - Scheduled Positional job astro:sun:home every 60 seconds
2017-12-21 19:40:19.214 [INFO ] [thome.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:moon:home
2017-12-21 19:40:19.222 [INFO ] [ding.astro.handler.AstroThingHandler] - Scheduled Positional job astro:moon:home every 60 seconds
2017-12-21 19:40:19.656 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:19.690 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2017-12-21 19:40:19.769 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:19.949 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:20.134 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:20.255 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:20.594 [INFO ] [nding.zway.handler.ZWayBridgeHandler] - Initializing Z-Way bridge …
2017-12-21 19:40:20.619 [WARN ] [nding.zway.handler.ZWayBridgeHandler] - Refreshing device failed (DeviceHandler is null): Send Email Notification
2017-12-21 19:40:20.878 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:21.026 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:21.233 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:21.458 [INFO ] [nding.zway.handler.ZWayBridgeHandler] - Z-Way bridge successfully authenticated
2017-12-21 19:40:21.999 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:22.146 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:22.224 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:22.448 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:22.585 [WARN ] [lipse.smarthome.io.net.exec.ExecUtil] - Execution failed (Exit value: -559038737. Caused by java.io.IOException: Cannot run program “arping” (in directory “.”): error=2, No such file or directory)
2017-12-21 19:40:27.582 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2017-12-21 19:40:27.832 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2017-12-21 19:40:32.631 [INFO ] [b.core.service.AbstractActiveService] - HTTP Refresh Service has been started
2017-12-21 19:40:32.642 [INFO ] [b.core.service.AbstractActiveService] - Modbus Polling Service has been started
2017-12-21 19:40:39.715 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin
2017-12-21 19:40:39.912 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2017-12-21 19:40:55.202 [INFO ] [lipse.smarthome.model.script.Weather] - Temperature evolved of 0 degrees.
2017-12-21 19:41:04.754 [ERROR] [ntime.internal.engine.RuleEngineImpl] - Rule ‘Compute humidex’: Could not cast NULL to org.eclipse.smarthome.core.library.types.DecimalType; line 41, column 17, length 37
2017-12-21 19:41:04.992 [INFO ] [lipse.smarthome.model.script.Weather] - Temperature evolved of null degrees.
2017-12-21 19:42:27.473 [WARN ] [.internal.ScaleTransformationService] - Scale transform file ‘/etc/openhab2/transform/humidex.scale’ does not comply with syntax for entry : ‘-’, 'undefiniert’
2017-12-21 19:43:11.223 [ERROR] [ome.core.thing.link.ThingLinkManager] - Exception occurred while informing handler: null
java.lang.NumberFormatException: null
at java.math.BigDecimal.(BigDecimal.java:596) ~[?:?]
at java.math.BigDecimal.(BigDecimal.java:383) ~[?:?]
at java.math.BigDecimal.(BigDecimal.java:806) ~[?:?]
at org.eclipse.smarthome.core.library.types.DecimalType.(DecimalType.java:55) ~[?:?]
at org.openhab.binding.zway.handler.ZWayDeviceHandler.refreshChannel(ZWayDeviceHandler.java:360) ~[?:?]
at org.openhab.binding.zway.handler.ZWayDeviceHandler.handleCommand(ZWayDeviceHandler.java:634) ~[?:?]
at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.channelLinked(BaseThingHandler.java:243) ~[?:?]
at org.openhab.binding.zway.handler.ZWayDeviceHandler.channelLinked(ZWayDeviceHandler.java:392) ~[?:?]
at org.eclipse.smarthome.core.thing.link.ThingLinkManager.lambda$0(ThingLinkManager.java:290) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2017-12-21 19:43:49.784 [ERROR] [ome.core.thing.link.ThingLinkManager] - Exception occurred while informing handler: null
java.lang.NumberFormatException: null
at java.math.BigDecimal.(BigDecimal.java:596) ~[?:?]
at java.math.BigDecimal.(BigDecimal.java:383) ~[?:?]
at java.math.BigDecimal.(BigDecimal.java:806) ~[?:?]
at org.eclipse.smarthome.core.library.types.DecimalType.(DecimalType.java:55) ~[?:?]
at org.openhab.binding.zway.handler.ZWayDeviceHandler.refreshChannel(ZWayDeviceHandler.java:360) ~[?:?]
at org.openhab.binding.zway.handler.ZWayDeviceHandler.handleCommand(ZWayDeviceHandler.java:634) ~[?:?]
at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.channelLinked(BaseThingHandler.java:243) ~[?:?]
at org.openhab.binding.zway.handler.ZWayDeviceHandler.channelLinked(ZWayDeviceHandler.java:392) ~[?:?]
at org.eclipse.smarthome.core.thing.link.ThingLinkManager.lambda$0(ThingLinkManager.java:290) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2017-12-21 19:49:44.182 [ERROR] [.zway.handler.ZWayZWaveDeviceHandler] - Error occurred when performing polling:null
2017-12-21 19:57:49.407 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - connect try 1/3 error: Connection timed out (Connection timed out). Connection TCPMasterConnection@6eca6b[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@79ee77[address=192.168.0.232,port=502]
2017-12-21 20:06:11.151 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - connect try 1/3 error: Connection timed out (Connection timed out). Connection TCPMasterConnection@328909[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@54a6cd[address=192.168.0.231,port=502]
2017-12-21 20:18:59.152 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - connect try 1/3 error: Connection timed out (Connection timed out). Connection TCPMasterConnection@6eca6b[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@79ee77[address=192.168.0.232,port=502]
2017-12-21 21:00:38.563 [WARN ] [nternal.profiles.ProfileCallbackImpl] - Handler for thing ‘zway:zwayDevice:192_168_0_67:7’ takes more than 30000ms for handling an update
