Binding Modbus 2.5.7. Stops working after unsuccessful read attempt

Tags: #<Tag:0x00007fc8fc6d46c0> #<Tag:0x00007fc8fc6d45d0> #<Tag:0x00007fc8fc6d43f0>

@BHall My trouble is that so far I am not able to reproduce this, making troubleshooting and fixes very slow.

Do you have easy means to get the erratic behavior? Can you please explain the steps, one step at a time

@ssalonen,

Good evening

The behavior is really pretty easy to reproduce on my end. I execute the “start.bat” and the “HVAC_Temp_Last_Success” entries will start showing up in the “events.log” at a very fast rate since I am currently set for 250 ms. I normally will operate an input so the register value will change and trigger an entry in the “events.log”. Then I basically wait for the logging to stop which normally will happen within an hour or so but the last time was a little over an hour.

I executed the full “bundle:list” and “log:display” commands so you can hopefully see how things are set up.

PS C:\openhab2>
PS C:\openhab2> c:\openhab2\start.bat
Launching the openHAB runtime...

                          __  _____    ____      
  ____  ____  ___  ____  / / / /   |  / __ )     
 / __ \/ __ \/ _ \/ __ \/ /_/ / /| | / __  |     
/ /_/ / /_/ /  __/ / / / __  / ___ |/ /_/ /      
\____/ .___/\___/_/ /_/_/ /_/_/  |_/_____/       
    /_/                        2.5.8
                               Release Build     

Hit '<tab>' for a list of available commands
and '[cmd] --help' for help on a specific command.
Hit '<ctrl-d>' or type 'system:shutdown' or 'logout' to shutdown openHAB.

openhab> 
openhab> bundle:list
START LEVEL 100 , List Threshold: 50
 ID │ State    │ Lvl │ Version                 │ Name
────┼──────────┼─────┼─────────────────────────┼─────────────────────────────────────────────────────────────────────────────────────────────────── 
 19 │ Active   │  80 │ 5.3.1.201602281253      │ OSGi JAX-RS Connector
 20 │ Active   │  80 │ 2.8.2.v20180104-1110    │ Gson: Google Json Library for Java
 21 │ Active   │  80 │ 18.0.0                  │ Guava: Google Core Libraries for Java
 22 │ Active   │  80 │ 27.1.0.jre              │ Guava: Google Core Libraries for Java
 23 │ Active   │  80 │ 1.0.1                   │ Guava InternalFutureFailureAccess and InternalFutures
 24 │ Active   │  80 │ 3.0.0.v201312141243     │ Google Guice (No AOP)
 25 │ Active   │  80 │ 4.8.35.v20190528-1517   │ ClassGraph
 26 │ Active   │  80 │ 3.18.1.GA               │ Javassist
 29 │ Active   │  80 │ 3.5.5                   │ JmDNS
 31 │ Active   │  80 │ 1.0.0                   │ Units of Measurement API
 33 │ Active   │  80 │ 1.1.0.Final             │ Bean Validation API
 35 │ Active   │  80 │ 2.0.1                   │ javax.ws.rs-api
 36 │ Active   │  80 │ 2.9.2                   │ Joda-Time
 37 │ Active   │  80 │ 0.5.8                   │ Jollyday
 38 │ Active   │  80 │ 3.2.0.v201101311130     │ ANTLR Runtime
 40 │ Active   │  80 │ 1.1                     │ Commons Exec
 41 │ Active   │  80 │ 2.2.0                   │ Commons IO
 42 │ Active   │  80 │ 2.6                     │ Commons Lang
 51 │ Active   │  80 │ 4.2.7                   │ Apache Karaf :: OSGi Services :: Event
 66 │ Active   │  80 │ 1.4.7.1                 │ Apache ServiceMix :: Bundles :: xstream
 69 │ Active   │  80 │ 4.14.0                  │ Apache XBean OSGI Bundle Utilities
 70 │ Active   │  80 │ 4.14.0                  │ Apache XBean :: Classpath Resource Finder
 71 │ Active   │  80 │ 2.12.0.v20160420-0247   │ EMF Common
 72 │ Active   │  80 │ 2.12.0.v20160420-0247   │ EMF Ecore
 73 │ Active   │  80 │ 2.11.0.v20160420-0247   │ EMF Change Model
 74 │ Active   │  80 │ 2.12.0.v20160420-0247   │ EMF XML/XMI Persistence
 75 │ Active   │  80 │ 3.10.400.v20190516-1504 │ Common Eclipse Runtime
104 │ Active   │  80 │ 0.8.0.v20190822-0801    │ org.eclipse.lsp4j
105 │ Active   │  80 │ 0.8.0.v20190822-0801    │ org.eclipse.lsp4j.jsonrpc
106 │ Active   │  80 │ 2.19.0.v20190902-0728   │ Xtend Runtime Library
107 │ Active   │  80 │ 2.19.0.v20190902-0728   │ Xtend Macro Interfaces
108 │ Active   │  80 │ 2.19.0.v20190902-0737   │ Xtext
109 │ Active   │  80 │ 2.19.0.v20190902-0952   │ Xtext Common Types
110 │ Active   │  80 │ 2.19.0.v20190902-0737   │ Xtext IDE Core
111 │ Active   │  80 │ 2.19.0.v20190902-0737   │ Xtext Utility
112 │ Active   │  80 │ 2.19.0.v20190902-0952   │ Xbase Model
113 │ Active   │  80 │ 2.19.0.v20190902-0952   │ Xbase Generic IDE Services
114 │ Active   │  80 │ 2.19.0.v20190902-0728   │ Xbase Runtime Library
129 │ Active   │  80 │ 1.9.6                   │ MIME streaming extension
131 │ Active   │  80 │ 7.1.0                   │ org.objectweb.asm.commons
132 │ Active   │  80 │ 7.1.0                   │ org.objectweb.asm.tree
133 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Core
134 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Audio
135 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: OAuth2Client
136 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Binding XML
137 │ Active   │  90 │ 2.5.0                   │ openHAB Core :: Bundles :: Boot
138 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Configuration Core
139 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Configuration Discovery
140 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Configuration mDNS Discovery
141 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Configuration Dispatcher
142 │ Active   │  75 │ 2.5.0                   │ openHAB Core :: Bundles :: Configuration XML
143 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Ephemeris
144 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: ID
145 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Console
146 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Console for OSGi runtime Karaf
147 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: HTTP Interface
149 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Monitor
150 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Network I/O
151 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: REST Interface
152 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Audio REST Interface
153 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: REST Interface :: Core
154 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: REST mDNS Announcer
155 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: REST JAX-RS Optimizations
156 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Sitemap REST Interface
157 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: SSE Interface
158 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Voice REST Interface
159 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: mDNS Service
160 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Karaf Integration
161 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Core
162 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Items
163 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Item IDE
164 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Items Runtime
165 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Language Server
166 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Persistence
167 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Persistence IDE
168 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Persistence Runtime
169 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Rules
170 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Rule IDE
171 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Rules Runtime
172 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Script
173 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Script IDE
174 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Script Runtime
175 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Sitemap
176 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Sitemap IDE
177 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Sitemap Runtime
178 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Thing
179 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Thing IDE
180 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Model Thing Runtime
181 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Persistence
182 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Scheduler Service
183 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Semantics
184 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: JSON Storage
185 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Thing
186 │ Active   │  75 │ 2.5.0                   │ openHAB Core :: Bundles :: Thing XML
187 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Transformation Service
188 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: UI
189 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: UI Icon Support
190 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Voice
191 │ Active   │  80 │ 2.5.8                   │ openHAB UI :: Bundles :: Dashboard UI
200 │ Active   │  80 │ 1.4.0                   │ ThreeTen-Extra
201 │ Active   │  80 │ 1.0.3                   │ Units of Measurement Common Library
202 │ Active   │  80 │ 1.0.10                  │ Units of Measurement Implementation for Java SE
203 │ Active   │  80 │ 2.5.8                   │ openHAB Add-ons :: Bundles :: IO :: Java Sound Support
204 │ Active   │  80 │ 2.5.8                   │ openHAB Add-ons :: Bundles :: IO :: Web Audio Support
205 │ Active   │  80 │ 2.5.8                   │ openHAB UI :: Bundles :: Basic UI
206 │ Active   │  80 │ 2.5.8                   │ openHAB UI :: Bundles :: HABPanel UI
207 │ Active   │  80 │ 2.5.8                   │ openHAB UI :: Bundles :: HomeBuilder UI
208 │ Active   │  80 │ 2.5.8                   │ openHAB UI :: Bundles :: Icon Set :: Classic
209 │ Active   │  80 │ 2.5.8                   │ openHAB UI :: Bundles :: Paper UI
222 │ Active   │  80 │ 2.5.9.202009010439      │ openHAB Add-ons :: Bundles :: IO :: Modbus Transport
223 │ Active   │  80 │ 2.5.8                   │ openHAB Add-ons :: Bundles :: Modbus Binding
224 │ Active   │  80 │ 3.15.0.OH2              │ nrjavaserial
225 │ Active   │  80 │ 3.3.0                   │ Commons Net
226 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Configuration USB-Serial Discovery
227 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Configuration USB-Serial Discovery for Linux using sysfs scanning       
228 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Configuration Serial
229 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Serial Transport
230 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Serial Transport for RXTX
231 │ Active   │  80 │ 2.5.0                   │ openHAB Core :: Bundles :: Serial Transport for RFC2217
232 │ Resolved │  80 │ 2.4.3                   │ Apache Commons Pool
openhab>

openhab> log:list
Logger                                             │ Level
───────────────────────────────────────────────────┼──────
ROOT                                               │ INFO
javax.jmdns                                        │ ERROR
javax.mail                                         │ ERROR
org.apache.karaf.jaas.modules.audit                │ INFO
org.apache.karaf.kar.internal.KarServiceImpl       │ ERROR
org.apache.karaf.shell.ssh.SshUtils                │ ERROR
org.apache.karaf.shell.support                     │ OFF
org.eclipse.lsp4j                                  │ OFF
org.eclipse.smarthome                              │ INFO
org.jupnp                                          │ ERROR
org.openhab                                        │ INFO
org.openhab.binding.modbus                         │ INFO
org.openhab.io.transport.modbus                    │ INFO
org.openhab.ui.paper                               │ WARN
org.openhab.ui.paper.internal                      │ INFO
org.ops4j.pax.url.mvn.internal.AetherBasedResolver │ ERROR
org.ops4j.pax.web.pax-web-runtime                  │ OFF
smarthome.event                                    │ INFO
smarthome.event.InboxUpdatedEvent                  │ ERROR
smarthome.event.ItemAddedEvent                     │ ERROR
smarthome.event.ItemRemovedEvent                   │ ERROR
smarthome.event.ItemStateEvent                     │ ERROR
smarthome.event.ThingAddedEvent                    │ ERROR
smarthome.event.ThingRemovedEvent                  │ ERROR
smarthome.event.ThingStatusInfoEvent               │ ERROR
openhab>

Were you able to see anything helpful in the information that I sent after it quits logging?

The thought did cross my mind if it might possibly help if you had my entire installation directory “C:\openhab2” . But then I guess there would be the issue of the modbus device that I am reading the single register from. I really think the modbus device is ok because I have been using it with my other software successfully for a long time.

I hope issues you have had with other users have not been this difficult. This seems to have been a tough one, especially since it doesn’t seem to produce errors.

I find it particularly interesting that if I make the two changes shown below to the logging then everything seems to work great and from the several times I tried it did not stop, even after some pretty long periods of time. I was hoping that might have been a clue, it seems almost like if you keep it busy it works better.

log:set DEBUG org.openhab.io.transport.modbus
log:set DEBUG org.openhab.binding.modbus

@ssalonen,

I had it running and saw something different, a bunch of warnings and errors in “openhab.log”:
Will include some of “events.log” in the following post.

2020-09-01 21:50:55.838 [INFO ] [org.quartz.core.QuartzScheduler     ] - Scheduler openHAB-job-scheduler_$_NON_CLUSTERED started.
2020-09-02 21:03:33.006 [INFO ] [port.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
2020-09-02 21:03:33.304 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.shell.commands/4.2.7
2020-09-02 21:03:33.308 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.deployer.kar/4.2.7
2020-09-02 21:03:33.685 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.wrapper.core/4.2.7
2020-09-02 21:03:34.556 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.bundle.core/4.2.7
2020-09-02 21:03:34.601 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.config.core/4.2.7
2020-09-02 21:03:34.959 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.diagnostic.core/4.2.7
2020-09-02 21:03:34.990 [INFO ] [ll.impl.action.osgi.CommandExtension] - Command registration delayed for bundle org.apache.karaf.features.command/4.2.7. Missing dependencies: [org.apache.karaf.features.FeaturesService]
2020-09-02 21:03:35.016 [INFO ] [ll.impl.action.osgi.CommandExtension] - Command registration delayed for bundle org.apache.karaf.http.core/4.2.7. Missing dependencies: [org.apache.karaf.http.core.ProxyService, org.apache.karaf.http.core.ServletService]
2020-09-02 21:03:35.053 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.instance.core/4.2.7
2020-09-02 21:03:35.099 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.jaas.command/4.2.7
2020-09-02 21:03:35.104 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.jaas.command/4.2.7
2020-09-02 21:03:35.105 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.jaas.command/4.2.7
2020-09-02 21:03:35.178 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.jaas.command/4.2.7
2020-09-02 21:03:35.181 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.jaas.command/4.2.7
2020-09-02 21:03:35.199 [INFO ] [ll.impl.action.osgi.CommandExtension] - Updating commands for bundle org.apache.karaf.jaas.command/4.2.7
2020-09-02 21:03:35.218 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.features.command/4.2.7
2020-09-02 21:03:35.228 [INFO ] [raf.deployer.features.osgi.Activator] - Deployment finished. Registering FeatureDeploymentListener
2020-09-02 21:03:35.280 [INFO ] [ll.impl.action.osgi.CommandExtension] - Command registration delayed for bundle org.apache.karaf.kar.core/4.2.7. Missing dependencies: [org.apache.karaf.kar.KarService]
2020-09-02 21:03:35.305 [INFO ] [ll.impl.action.osgi.CommandExtension] - Command registration delayed for bundle org.apache.karaf.log.core/4.2.7. Missing dependencies: [org.apache.karaf.log.core.LogService, org.apache.karaf.log.core.LogEventFormatter]
2020-09-02 21:03:35.319 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.log.core/4.2.7
2020-09-02 21:03:35.334 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.package.core/4.2.7
2020-09-02 21:03:35.338 [INFO ] [ll.impl.action.osgi.CommandExtension] - Unregistering commands for bundle org.apache.karaf.log.core/4.2.7
2020-09-02 21:03:35.341 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.kar.core/4.2.7
2020-09-02 21:03:35.342 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.log.core/4.2.7
2020-09-02 21:03:35.363 [INFO ] [ll.impl.action.osgi.CommandExtension] - Unregistering commands for bundle org.apache.karaf.kar.core/4.2.7
2020-09-02 21:03:35.369 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.kar.core/4.2.7
2020-09-02 21:03:35.370 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.scr.state/4.2.7
2020-09-02 21:03:35.390 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.service.core/4.2.7
2020-09-02 21:03:35.533 [INFO ] [ll.impl.action.osgi.CommandExtension] - Command registration delayed for bundle org.apache.karaf.shell.ssh/4.2.7. Missing dependencies: [org.apache.sshd.server.SshServer]
2020-09-02 21:03:35.552 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.system.core/4.2.7
2020-09-02 21:03:35.762 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Examining bundle for SPI provider: org.eclipse.jetty.http
2020-09-02 21:03:35.764 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Found SPI resource: bundleentry://80.fwk418958713/META-INF/services/org.eclipse.jetty.http.HttpFieldPreEncoder
2020-09-02 21:03:35.815 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.http.Http1FieldPreEncoder
2020-09-02 21:03:35.824 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {org.eclipse.jetty.http.HttpFieldPreEncoder}={serviceloader.mediator=39, service.id=115, service.bundleid=80, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.http.Http1FieldPreEncoder}
2020-09-02 21:03:35.825 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: org.eclipse.jetty.http.HttpFieldPreEncoder in bundle org.eclipse.jetty.http
2020-09-02 21:03:35.845 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Examining bundle for SPI provider: org.eclipse.jetty.websocket.common
2020-09-02 21:03:35.846 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Found SPI resource: bundleentry://98.fwk418958713/META-INF/services/org.eclipse.jetty.websocket.api.extensions.Extension
2020-09-02 21:03:35.867 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.websocket.common.extensions.identity.IdentityExtension
2020-09-02 21:03:35.869 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {org.eclipse.jetty.websocket.api.extensions.Extension}={serviceloader.mediator=39, service.id=116, service.bundleid=98, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.websocket.common.extensions.identity.IdentityExtension}
2020-09-02 21:03:35.870 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2020-09-02 21:03:35.871 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.websocket.common.extensions.fragment.FragmentExtension
2020-09-02 21:03:35.872 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {org.eclipse.jetty.websocket.api.extensions.Extension}={serviceloader.mediator=39, service.id=117, service.bundleid=98, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.websocket.common.extensions.fragment.FragmentExtension}
2020-09-02 21:03:35.874 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2020-09-02 21:03:35.880 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.websocket.common.extensions.compress.PerMessageDeflateExtension
2020-09-02 21:03:35.886 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {org.eclipse.jetty.websocket.api.extensions.Extension}={serviceloader.mediator=39, service.id=118, service.bundleid=98, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.websocket.common.extensions.compress.PerMessageDeflateExtension}
2020-09-02 21:03:35.887 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2020-09-02 21:03:35.889 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.websocket.common.extensions.compress.DeflateFrameExtension
2020-09-02 21:03:35.903 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {org.eclipse.jetty.websocket.api.extensions.Extension}={serviceloader.mediator=39, service.id=119, service.bundleid=98, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.websocket.common.extensions.compress.DeflateFrameExtension}
2020-09-02 21:03:35.904 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2020-09-02 21:03:35.906 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.websocket.common.extensions.compress.XWebkitDeflateFrameExtension
2020-09-02 21:03:35.907 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {org.eclipse.jetty.websocket.api.extensions.Extension}={serviceloader.mediator=39, service.id=120, service.bundleid=98, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.websocket.common.extensions.compress.XWebkitDeflateFrameExtension}
2020-09-02 21:03:35.907 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: org.eclipse.jetty.websocket.api.extensions.Extension in bundle org.eclipse.jetty.websocket.common
2020-09-02 21:03:35.909 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Examining bundle for SPI provider: org.eclipse.jetty.websocket.javax.websocket
2020-09-02 21:03:35.911 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Found SPI resource: bundleentry://99.fwk418958713/META-INF/services/javax.websocket.ContainerProvider
2020-09-02 21:03:35.913 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.websocket.jsr356.JettyClientContainerProvider
2020-09-02 21:03:35.915 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {javax.websocket.ContainerProvider}={serviceloader.mediator=39, service.id=121, service.bundleid=99, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.websocket.jsr356.JettyClientContainerProvider}
2020-09-02 21:03:35.915 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: javax.websocket.ContainerProvider in bundle org.eclipse.jetty.websocket.javax.websocket
2020-09-02 21:03:35.917 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Examining bundle for SPI provider: org.eclipse.jetty.websocket.javax.websocket.server
2020-09-02 21:03:35.917 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Found SPI resource: bundleentry://100.fwk418958713/META-INF/services/javax.servlet.ServletContainerInitializer
2020-09-02 21:03:35.919 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.websocket.jsr356.server.deploy.WebSocketServerContainerInitializer
2020-09-02 21:03:35.921 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {javax.servlet.ServletContainerInitializer}={serviceloader.mediator=39, service.id=122, service.bundleid=100, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.websocket.jsr356.server.deploy.WebSocketServerContainerInitializer}
2020-09-02 21:03:35.921 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: javax.servlet.ServletContainerInitializer in bundle org.eclipse.jetty.websocket.javax.websocket.server
2020-09-02 21:03:35.922 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Found SPI resource: bundleentry://100.fwk418958713/META-INF/services/javax.websocket.server.ServerEndpointConfig$Configurator
2020-09-02 21:03:35.924 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.websocket.jsr356.server.ContainerDefaultConfigurator
2020-09-02 21:03:35.925 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {javax.websocket.server.ServerEndpointConfig$Configurator}={serviceloader.mediator=39, service.id=123, service.bundleid=100, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.websocket.jsr356.server.ContainerDefaultConfigurator}
2020-09-02 21:03:35.926 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: javax.websocket.server.ServerEndpointConfig$Configurator in bundle org.eclipse.jetty.websocket.javax.websocket.server
2020-09-02 21:03:35.927 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Examining bundle for SPI provider: org.eclipse.jetty.websocket.server
2020-09-02 21:03:35.928 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Found SPI resource: bundleentry://101.fwk418958713/META-INF/services/javax.servlet.ServletContainerInitializer
2020-09-02 21:03:35.929 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Loaded SPI provider: class org.eclipse.jetty.websocket.server.NativeWebSocketServletContainerInitializer
2020-09-02 21:03:35.931 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered service: {javax.servlet.ServletContainerInitializer}={serviceloader.mediator=39, service.id=124, service.bundleid=101, service.scope=bundle, .org.apache.aries.spifly.provider.implclass=org.eclipse.jetty.websocket.server.NativeWebSocketServletContainerInitializer}
2020-09-02 21:03:35.931 [INFO ] [rg.apache.aries.spifly.BaseActivator] - Registered provider: javax.servlet.ServletContainerInitializer in bundle org.eclipse.jetty.websocket.server
2020-09-02 21:03:36.051 [INFO ] [org.eclipse.jetty.util.log          ] - Logging initialized @6241ms to org.eclipse.jetty.util.log.Slf4jLog
2020-09-02 21:03:36.054 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.shell.ssh/4.2.7
2020-09-02 21:03:36.093 [INFO ] [j.pax.web.service.internal.Activator] - EventAdmin support enabled, servlet events will be postet to topics.
2020-09-02 21:03:36.095 [INFO ] [j.pax.web.service.internal.Activator] - LogService support enabled, log events will be created.
2020-09-02 21:03:36.099 [INFO ] [j.pax.web.service.internal.Activator] - Pax Web started
2020-09-02 21:03:36.559 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.event/4.2.7
2020-09-02 21:03:37.042 [INFO ] [org.eclipse.jetty.util.TypeUtil     ] - JVM Runtime does not support Modules
2020-09-02 21:03:37.184 [INFO ] [rvice.jetty.internal.JettyServerImpl] - Pax Web available at [0.0.0.0]:[8443]
2020-09-02 21:03:37.188 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to '31.581895999999997,-94.465492'.
2020-09-02 21:03:37.220 [INFO ] [vice.jetty.internal.JettyFactoryImpl] - No ALPN class available
2020-09-02 21:03:37.221 [INFO ] [vice.jetty.internal.JettyFactoryImpl] - HTTP/2 not available, creating standard ServerConnector for Http
2020-09-02 21:03:37.222 [INFO ] [rvice.jetty.internal.JettyServerImpl] - Pax Web available at [0.0.0.0]:[8080]
2020-09-02 21:03:37.226 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.ops4j.pax.web.pax-web-extender-whiteboard_7.2.11 [193]] to http service
2020-09-02 21:03:37.252 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.apache.karaf.http.core_4.2.7 [53]] to http service
2020-09-02 21:03:37.258 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [19]] to http service
2020-09-02 21:03:37.280 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.apache.karaf.http.core/4.2.7
2020-09-02 21:03:38.083 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.core.audio_2.5.0 [134]] to http service
2020-09-02 21:03:38.099 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:38.147 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.core.audio_2.5.0 [134], contextID=default], with context-name: 
2020-09-02 21:03:38.163 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:38.236 [INFO ] [org.eclipse.jetty.server.session    ] - DefaultSessionIdManager workerName=node0
2020-09-02 21:03:38.236 [INFO ] [org.eclipse.jetty.server.session    ] - No SessionScavenger set, using defaults
2020-09-02 21:03:38.239 [INFO ] [org.eclipse.jetty.server.session    ] - node0 Scavenging every 660000ms
2020-09-02 21:03:38.253 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core.audio_2.5.0 [134], contextID=default]}
2020-09-02 21:03:38.264 [INFO ] [org.eclipse.jetty.server.Server     ] - jetty-9.4.20.v20190813; built: 2019-08-13T21:28:18.144Z; git: 84700530e645e812b336747464d6fbbf370c9a20; jvm 1.8.0_252-b14
2020-09-02 21:03:38.287 [INFO ] [.jetty.server.handler.ContextHandler] - Started o.e.j.s.h.ContextHandler@d785f1c{/static,null,AVAILABLE}
2020-09-02 21:03:38.314 [INFO ] [pse.jetty.util.ssl.SslContextFactory] - x509=X509@57251ee5(mykey,h=[openhab.org],w=[]) for Server@4cbca070[provider=null,keyStore=file:///C:/openhab2/userdata/etc/keystore,trustStore=file:///C:/openhab2/userdata/etc/keystore]
2020-09-02 21:03:38.377 [INFO ] [lipse.jetty.server.AbstractConnector] - Started 0.0.0.0:8443@1711946{SSL,[ssl, http/1.1]}{0.0.0.0:8443}
2020-09-02 21:03:38.383 [INFO ] [lipse.jetty.server.AbstractConnector] - Started default@6a2158a0{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
2020-09-02 21:03:38.384 [INFO ] [org.eclipse.jetty.server.Server     ] - Started @8576ms
2020-09-02 21:03:38.858 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:38.859 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [19], contextID=default], with context-name: 
2020-09-02 21:03:38.860 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:38.871 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [19], contextID=default]}
2020-09-02 21:03:38.911 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.core.io.http_2.5.0 [147]] to http service
2020-09-02 21:03:41.400 [INFO ] [ll.impl.action.osgi.CommandExtension] - Registering commands for bundle org.openhab.core.karaf/2.5.0
2020-09-02 21:03:42.720 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'hvac.items'
2020-09-02 21:03:43.274 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'modbus.things'
2020-09-02 21:03:43.371 [INFO ] [org.quartz.impl.StdSchedulerFactory ] - Using default implementation for ThreadExecutor
2020-09-02 21:03:43.393 [INFO ] [rg.quartz.core.SchedulerSignalerImpl] - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2020-09-02 21:03:43.393 [INFO ] [org.quartz.core.QuartzScheduler     ] - Quartz Scheduler v.2.2.1 created.
2020-09-02 21:03:43.395 [INFO ] [org.quartz.simpl.RAMJobStore        ] - RAMJobStore initialized.
2020-09-02 21:03:43.396 [INFO ] [org.quartz.core.QuartzScheduler     ] - Scheduler meta-data: Quartz Scheduler (v2.2.1) 'openHAB-job-scheduler' with instanceId 'NON_CLUSTERED'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads.
  Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

2020-09-02 21:03:43.397 [INFO ] [org.quartz.impl.StdSchedulerFactory ] - Quartz scheduler 'openHAB-job-scheduler' initialized from specified file: 'C:\openhab2\runtime/etc/quartz.properties'
2020-09-02 21:03:43.397 [INFO ] [org.quartz.impl.StdSchedulerFactory ] - Quartz scheduler version: 2.2.1
2020-09-02 21:03:43.398 [INFO ] [org.quartz.core.QuartzScheduler     ] - Scheduler openHAB-job-scheduler_$_NON_CLUSTERED started.
2020-09-02 21:03:44.420 [INFO ] [org.quartz.core.QuartzScheduler     ] - JobFactory set to: org.eclipse.smarthome.model.rule.runtime.internal.engine.GuiceAwareJobFactory@5d556418
2020-09-02 21:03:44.421 [INFO ] [org.quartz.core.QuartzScheduler     ] - Scheduler openHAB-job-scheduler_$_NON_CLUSTERED paused.
2020-09-02 21:03:44.499 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2020-09-02 21:03:44.855 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.core.ui_2.5.0 [188]] to http service
2020-09-02 21:03:44.860 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:44.861 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.core.ui_2.5.0 [188], contextID=custom], with context-name: 
2020-09-02 21:03:44.862 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:44.881 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core.ui_2.5.0 [188], contextID=custom]}
2020-09-02 21:03:44.993 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:44.994 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.core.ui_2.5.0 [188], contextID=default], with context-name: 
2020-09-02 21:03:44.995 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:45.056 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core.ui_2.5.0 [188], contextID=default]}
2020-09-02 21:03:45.066 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.core.ui.icon_2.5.0 [189]] to http service
2020-09-02 21:03:45.070 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:45.071 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.core.ui.icon_2.5.0 [189], contextID=default], with context-name: 
2020-09-02 21:03:45.072 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:45.090 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core.ui.icon_2.5.0 [189], contextID=default]}
2020-09-02 21:03:45.136 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.ui.dashboard_2.5.8 [191]] to http service
2020-09-02 21:03:45.143 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:45.144 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.5.8 [191], contextID=default], with context-name: 
2020-09-02 21:03:45.145 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:45.180 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.5.8 [191], contextID=default]}
2020-09-02 21:03:45.185 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:45.186 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.5.8 [191], contextID=custom], with context-name: 
2020-09-02 21:03:45.189 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:45.235 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.5.8 [191], contextID=custom]}
2020-09-02 21:03:45.238 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.1.43:8080
2020-09-02 21:03:45.239 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.1.43:8443
2020-09-02 21:03:45.375 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.ui.basic_2.5.8 [205]] to http service
2020-09-02 21:03:45.380 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:45.381 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.ui.basic_2.5.8 [205], contextID=custom], with context-name: 
2020-09-02 21:03:45.387 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:45.433 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.basic_2.5.8 [205], contextID=custom]}
2020-09-02 21:03:45.483 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:45.484 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.ui.basic_2.5.8 [205], contextID=custom], with context-name: 
2020-09-02 21:03:45.485 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:45.544 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.basic_2.5.8 [205], contextID=custom]}
2020-09-02 21:03:45.554 [INFO ] [d.internal.element.ServletWebElement] - Ignoring servlet [356] without valid alias or url patterns property.
2020-09-02 21:03:45.589 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.ui.habpanel_2.5.8 [206]] to http service
2020-09-02 21:03:45.610 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:45.611 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.ui.habpanel_2.5.8 [206], contextID=default], with context-name: 
2020-09-02 21:03:45.612 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:45.648 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.habpanel_2.5.8 [206], contextID=default]}
2020-09-02 21:03:45.650 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2020-09-02 21:03:45.659 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.ui.homebuilder_2.5.8 [207]] to http service
2020-09-02 21:03:45.663 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:45.664 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.ui.homebuilder_2.5.8 [207], contextID=default], with context-name: 
2020-09-02 21:03:45.664 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:45.687 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.homebuilder_2.5.8 [207], contextID=default]}
2020-09-02 21:03:45.689 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2020-09-02 21:03:45.704 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.ui.paper_2.5.8 [209]] to http service
2020-09-02 21:03:45.708 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:45.709 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.ui.paper_2.5.8 [209], contextID=custom], with context-name: 
2020-09-02 21:03:45.710 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:45.735 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.paper_2.5.8 [209], contextID=custom]}
2020-09-02 21:03:45.737 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2020-09-02 21:03:45.786 [INFO ] [rt.modbus.internal.ModbusManagerImpl] - Modbus manager activated
2020-09-02 21:03:47.929 [INFO ] [vice.internal.HttpServiceFactoryImpl] - Binding bundle: [org.openhab.core.boot_2.5.0 [137]] to http service
2020-09-02 21:03:47.932 [INFO ] [rg.ops4j.pax.web.utils.ClassPathUtil] - Ignoring bundle scan for /META-INF/services javax.servlet.ServletContainerInitializer.
2020-09-02 21:03:47.933 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering context DefaultHttpContext [bundle=org.openhab.core.boot_2.5.0 [137], contextID=default], with context-name: 
2020-09-02 21:03:47.934 [INFO ] [ce.jetty.internal.HttpServiceContext] - registering JasperInitializer
2020-09-02 21:03:47.952 [INFO ] [.jetty.server.handler.ContextHandler] - Started HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core.boot_2.5.0 [137], contextID=default]}
2020-09-02 21:03:49.432 [INFO ] [org.quartz.core.QuartzScheduler     ] - Scheduler openHAB-job-scheduler_$_NON_CLUSTERED started.
2020-09-02 21:03:57.760 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 17af5426-82aa-442e-b4c4-9be183a960b2]
2020-09-02 21:04:05.348 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID d4cbb8b5-ea0e-47c4-85ec-9bf92a102808]
2020-09-02 21:04:09.907 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 8cd598a1-2586-4567-8eb5-fa32c6b589a5]
2020-09-02 21:04:18.341 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 3216ae76-6f37-4a29-96cc-044c2a3f8a7c]
2020-09-02 21:04:29.390 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - connect try 1/1 error: connect timed out. Connection TCPMasterConnection@3d91f308[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:04:29.392 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - re-connect reached max tries 1, throwing last error: connect timed out. Connection TCPMasterConnection@3d91f308[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:04:29.394 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - Error connecting connection TCPMasterConnection@3d91f308[socket=Socket[unconnected]] for endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]: connect timed out
2020-09-02 21:04:29.401 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Could not connect to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] -- aborting request ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3] [operation ID 17af5426-82aa-442e-b4c4-9be183a960b2]
2020-09-02 21:04:29.416 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:HVAC:hvacpoll:reg32768 'Modbus Data' had ModbusConnectionException error on read: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502])
2020-09-02 21:04:34.403 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID d4cbb8b5-ea0e-47c4-85ec-9bf92a102808]
2020-09-02 21:04:42.196 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 8cd598a1-2586-4567-8eb5-fa32c6b589a5]
2020-09-02 21:04:48.992 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 3216ae76-6f37-4a29-96cc-044c2a3f8a7c]
2020-09-02 21:05:02.296 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID c879363c-cdbe-4c36-a439-69ab9e4b0df0]
2020-09-02 21:05:05.123 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID fe77ebe2-edf4-46e4-89b1-75d46f700d11]
2020-09-02 21:05:07.709 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID 4e035d25-a50f-47c7-bfaa-109a5844da4c]
2020-09-02 21:05:40.014 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID 5a16c0fa-3763-47da-a662-fb76af2e5c84]
2020-09-02 21:06:14.713 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID be8b91f8-70cc-45c4-9ca4-bb7ffefb9f44]
2020-09-02 21:09:26.880 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID b3b22032-eca5-4d09-b405-dafac1cbe7d6]
2020-09-02 21:09:39.212 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 3e5a0158-e03f-440f-b0a8-99859020dc4f]
2020-09-02 21:09:51.909 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 3e5a0158-e03f-440f-b0a8-99859020dc4f]
2020-09-02 21:10:01.788 [ERROR] [rt.modbus.internal.ModbusManagerImpl] - Last try 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Aborting. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 3e5a0158-e03f-440f-b0a8-99859020dc4f]
2020-09-02 21:10:02.608 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:HVAC:hvacpoll:reg32768 'Modbus Data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)
2020-09-02 21:10:10.484 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID afc633c3-459f-446d-9a60-81d841c276e4]
2020-09-02 21:10:21.516 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - connect try 1/1 error: connect timed out. Connection TCPMasterConnection@33ee7825[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:10:21.518 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - re-connect reached max tries 1, throwing last error: connect timed out. Connection TCPMasterConnection@33ee7825[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:10:21.519 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - Error connecting connection TCPMasterConnection@33ee7825[socket=Socket[unconnected]] for endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]: connect timed out
2020-09-02 21:10:21.520 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Could not connect to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] -- aborting request ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3] [operation ID afc633c3-459f-446d-9a60-81d841c276e4]
2020-09-02 21:10:21.522 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:HVAC:hvacpoll:reg32768 'Modbus Data' had ModbusConnectionException error on read: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502])
2020-09-02 21:10:31.800 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - connect try 1/1 error: connect timed out. Connection TCPMasterConnection@37a02772[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:10:31.801 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - re-connect reached max tries 1, throwing last error: connect timed out. Connection TCPMasterConnection@37a02772[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:10:31.802 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - Error connecting connection TCPMasterConnection@37a02772[socket=Socket[unconnected]] for endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]: connect timed out
2020-09-02 21:10:31.803 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Could not connect to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] -- aborting request ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3] [operation ID 7bff0744-02b4-4cef-a0e1-4ec6f9a25208]
2020-09-02 21:10:31.805 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:HVAC:hvacpoll:reg32768 'Modbus Data' had ModbusConnectionException error on read: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502])
2020-09-02 21:10:37.793 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID c2061362-790d-4485-a41d-b96eb10b8148]
2020-09-02 21:10:48.853 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - connect try 1/1 error: connect timed out. Connection TCPMasterConnection@4daa2adb[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:10:48.854 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - re-connect reached max tries 1, throwing last error: connect timed out. Connection TCPMasterConnection@4daa2adb[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:10:48.857 [ERROR] [ing.ModbusSlaveConnectionFactoryImpl] - Error connecting connection TCPMasterConnection@4daa2adb[socket=Socket[unconnected]] for endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]: connect timed out
2020-09-02 21:10:48.858 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Could not connect to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] -- aborting request ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3] [operation ID c2061362-790d-4485-a41d-b96eb10b8148]
2020-09-02 21:10:48.860 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:HVAC:hvacpoll:reg32768 'Modbus Data' had ModbusConnectionException error on read: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502])
2020-09-02 21:11:08.757 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID 305234c2-f14d-4f7e-8fa0-2f161b07ccee]
2020-09-02 21:11:16.895 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID 5b2045dc-beb0-431f-af93-1418fbc3fbbf]
2020-09-02 21:17:51.065 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketException Connection reset [operation ID 55b7614f-7d13-43c4-b472-f2ffa1d5887d]
2020-09-02 21:40:57.156 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 0de49bf7-e146-44f1-86f6-09bc2b860d1b]
2020-09-02 21:41:09.817 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 1d7effe0-f57c-4709-854b-c1c26e3be3b5]
2020-09-02 21:41:20.307 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request 

@ssalonen,

Here is what “events.log” looks like:

2020-09-02 21:40:50.701 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:40:50.363-0500 to 2020-09-02T21:40:50.700-0500
2020-09-02 21:40:51.002 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:40:50.700-0500 to 2020-09-02T21:40:51.001-0500
2020-09-02 21:40:51.321 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:40:51.001-0500 to 2020-09-02T21:40:51.320-0500
2020-09-02 21:40:51.655 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:40:51.320-0500 to 2020-09-02T21:40:51.653-0500
2020-09-02 21:41:03.427 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:40:51.653-0500 to 2020-09-02T21:41:03.424-0500
2020-09-02 21:41:16.301 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:41:03.424-0500 to 2020-09-02T21:41:16.299-0500
2020-09-02 21:41:30.300 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:10:48.861-0500 to 2020-09-02T21:41:30.299-0500
2020-09-02 21:41:30.301 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:41:30.301 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:41:30.302 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to ONLINE
2020-09-02 21:41:36.461 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to ONLINE
2020-09-02 21:41:36.462 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:41:16.299-0500 to 2020-09-02T21:41:36.459-0500
2020-09-02 21:41:42.995 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:41:36.459-0500 to 2020-09-02T21:41:42.994-0500
2020-09-02 21:41:44.188 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:41:42.994-0500 to 2020-09-02T21:41:44.185-0500
2020-09-02 21:41:58.999 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:41:44.185-0500 to 2020-09-02T21:41:58.994-0500
2020-09-02 21:42:26.378 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:41:30.299-0500 to 2020-09-02T21:42:26.376-0500
2020-09-02 21:42:26.379 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:42:26.381 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:42:26.382 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:42:55.925 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:42:55.926 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:42:26.376-0500 to 2020-09-02T21:42:55.924-0500
2020-09-02 21:43:20.822 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:42:55.924-0500 to 2020-09-02T21:43:20.821-0500
2020-09-02 21:43:26.846 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:43:26.848 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:43:26.849 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:41:58.994-0500 to 2020-09-02T21:43:26.845-0500
2020-09-02 21:43:39.415 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:26.845-0500 to 2020-09-02T21:43:39.415-0500
2020-09-02 21:43:41.017 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:39.415-0500 to 2020-09-02T21:43:41.015-0500
2020-09-02 21:43:44.037 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:41.015-0500 to 2020-09-02T21:43:44.037-0500
2020-09-02 21:43:44.339 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:44.037-0500 to 2020-09-02T21:43:44.338-0500
2020-09-02 21:43:45.667 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:44.338-0500 to 2020-09-02T21:43:45.667-0500
2020-09-02 21:43:45.973 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:45.667-0500 to 2020-09-02T21:43:45.971-0500
2020-09-02 21:43:46.289 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:45.971-0500 to 2020-09-02T21:43:46.287-0500
2020-09-02 21:43:46.591 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:46.287-0500 to 2020-09-02T21:43:46.591-0500
2020-09-02 21:43:46.878 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:46.591-0500 to 2020-09-02T21:43:46.877-0500
2020-09-02 21:43:56.066 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:46.877-0500 to 2020-09-02T21:43:56.064-0500
2020-09-02 21:44:15.009 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:43:20.821-0500 to 2020-09-02T21:44:15.008-0500
2020-09-02 21:44:15.010 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:44:15.011 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:44:15.011 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:44:36.978 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:44:36.979 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:43:56.064-0500 to 2020-09-02T21:44:36.977-0500
2020-09-02 21:44:46.101 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:44:46.101 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:44:15.008-0500 to 2020-09-02T21:44:46.100-0500
2020-09-02 21:44:46.102 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:44:46.103 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to ONLINE
2020-09-02 21:45:01.018 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:44:46.100-0500 to 2020-09-02T21:45:01.017-0500
2020-09-02 21:45:01.018 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:45:01.018 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:45:01.019 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:45:29.983 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:45:29.984 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:45:01.017-0500 to 2020-09-02T21:45:29.982-0500
2020-09-02 21:45:56.509 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:45:56.510 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:45:56.510 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:45:29.982-0500 to 2020-09-02T21:45:56.508-0500
2020-09-02 21:45:56.511 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to ONLINE
2020-09-02 21:46:03.613 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to ONLINE
2020-09-02 21:46:03.614 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:44:36.977-0500 to 2020-09-02T21:46:03.612-0500
2020-09-02 21:46:11.542 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:03.612-0500 to 2020-09-02T21:46:11.542-0500
2020-09-02 21:46:11.842 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:11.542-0500 to 2020-09-02T21:46:11.841-0500
2020-09-02 21:46:12.136 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:11.841-0500 to 2020-09-02T21:46:12.134-0500
2020-09-02 21:46:13.260 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:12.134-0500 to 2020-09-02T21:46:13.258-0500
2020-09-02 21:46:13.553 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:13.258-0500 to 2020-09-02T21:46:13.552-0500
2020-09-02 21:46:14.048 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:13.552-0500 to 2020-09-02T21:46:14.046-0500
2020-09-02 21:46:14.565 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:14.046-0500 to 2020-09-02T21:46:14.563-0500
2020-09-02 21:46:14.885 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:14.563-0500 to 2020-09-02T21:46:14.884-0500
2020-09-02 21:46:15.204 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:14.884-0500 to 2020-09-02T21:46:15.202-0500
2020-09-02 21:46:15.502 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:15.202-0500 to 2020-09-02T21:46:15.501-0500
2020-09-02 21:46:16.228 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:15.501-0500 to 2020-09-02T21:46:16.227-0500
2020-09-02 21:46:37.428 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:46:37.429 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:45:56.508-0500 to 2020-09-02T21:46:37.427-0500
2020-09-02 21:46:37.430 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:46:37.431 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:46:54.675 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:46:54.676 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:16.227-0500 to 2020-09-02T21:46:54.673-0500
2020-09-02 21:47:09.947 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:47:09.947 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:46:37.427-0500 to 2020-09-02T21:47:09.946-0500
2020-09-02 21:47:09.947 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:47:09.947 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:47:40.598 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:47:09.946-0500 to 2020-09-02T21:47:40.597-0500
2020-09-02 21:47:40.598 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:47:46.311 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:47:46.312 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:47:46.312 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:46:54.673-0500 to 2020-09-02T21:47:46.310-0500
2020-09-02 21:47:47.194 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:46.310-0500 to 2020-09-02T21:47:47.193-0500
2020-09-02 21:47:47.676 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:47.193-0500 to 2020-09-02T21:47:47.675-0500
2020-09-02 21:47:48.020 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:47.675-0500 to 2020-09-02T21:47:48.018-0500
2020-09-02 21:47:48.313 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:48.018-0500 to 2020-09-02T21:47:48.312-0500
2020-09-02 21:47:49.202 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:48.312-0500 to 2020-09-02T21:47:49.200-0500
2020-09-02 21:47:49.515 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:49.200-0500 to 2020-09-02T21:47:49.512-0500
2020-09-02 21:47:49.984 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:49.512-0500 to 2020-09-02T21:47:49.982-0500
2020-09-02 21:47:50.312 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:49.982-0500 to 2020-09-02T21:47:50.312-0500
2020-09-02 21:47:50.604 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:50.312-0500 to 2020-09-02T21:47:50.602-0500
2020-09-02 21:47:51.053 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:50.602-0500 to 2020-09-02T21:47:51.052-0500
2020-09-02 21:47:51.320 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:51.052-0500 to 2020-09-02T21:47:51.319-0500
2020-09-02 21:47:51.633 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:51.319-0500 to 2020-09-02T21:47:51.632-0500
2020-09-02 21:48:17.942 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:48:17.943 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:47:40.597-0500 to 2020-09-02T21:48:17.941-0500
2020-09-02 21:48:17.943 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:48:17.944 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:48:28.220 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusConnectionException) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]). Message: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:48:28.220 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.exception.ModbusConnectionException: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:48:28.220 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:48:17.941-0500 to 2020-09-02T21:48:28.219-0500
2020-09-02 21:48:28.220 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusConnectionException) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]). Message: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] to ONLINE
2020-09-02 21:48:59.278 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:48:28.219-0500 to 2020-09-02T21:48:59.278-0500
2020-09-02 21:48:59.279 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:48:59.279 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.exception.ModbusConnectionException: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:48:59.280 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:49:07.221 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:49:07.221 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:47:51.632-0500 to 2020-09-02T21:49:07.220-0500
2020-09-02 21:49:07.963 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:49:07.220-0500 to 2020-09-02T21:49:07.962-0500
2020-09-02 21:49:08.347 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:49:07.962-0500 to 2020-09-02T21:49:08.346-0500
2020-09-02 21:49:09.094 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:49:08.346-0500 to 2020-09-02T21:49:09.093-0500
2020-09-02 21:49:22.224 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:49:22.225 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:49:22.226 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:48:59.278-0500 to 2020-09-02T21:49:22.224-0500
2020-09-02 21:49:22.226 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to ONLINE
2020-09-02 21:49:26.255 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to ONLINE
2020-09-02 21:49:26.256 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:49:09.093-0500 to 2020-09-02T21:49:26.254-0500
2020-09-02 21:49:56.312 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:49:56.312 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:49:22.224-0500 to 2020-09-02T21:49:56.311-0500
2020-09-02 21:49:56.312 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null
2020-09-02 21:49:56.312 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE
2020-09-02 21:50:19.941 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusConnectionException) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]). Message: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:50:19.942 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:49:56.311-0500 to 2020-09-02T21:50:19.939-0500
2020-09-02 21:50:19.943 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.exception.ModbusConnectionException: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:50:19.944 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusConnectionException) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]). Message: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] to ONLINE
2020-09-02 21:50:30.211 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusConnectionException) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]). Message: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:50:30.211 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:50:19.939-0500 to 2020-09-02T21:50:30.210-0500
2020-09-02 21:50:40.491 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:50:30.210-0500 to 2020-09-02T21:50:40.489-0500
2020-09-02 21:50:59.353 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusConnectionException) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]). Message: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] to OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:50:59.354 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:50:40.489-0500 to 2020-09-02T21:50:59.353-0500
2020-09-02 21:50:59.354 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.exception.ModbusConnectionException: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null
2020-09-02 21:50:59.354 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusSlaveIOExceptionImpl) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null). Message: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to ONLINE
2020-09-02 21:51:06.163 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketException Connection reset', cause2=null to ONLINE
2020-09-02 21:51:06.165 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Success changed from 2020-09-02T21:49:26.254-0500 to 2020-09-02T21:51:06.160-0500
2020-09-02 21:51:25.731 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusConnectionException) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]). Message: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:51:25.732 [vent.ItemStateChangedEvent] - HVAC_Temp_Last_Error changed from 2020-09-02T21:50:59.353-0500 to 2020-09-02T21:51:25.730-0500
2020-09-02 21:51:25.733 [hingStatusInfoChangedEvent] - 'modbus:poller:HVAC:hvacpoll' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.exception.ModbusConnectionException: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]
2020-09-02 21:51:25.733 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from OFFLINE (COMMUNICATION_ERROR): Error (ModbusConnectionException) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]). Message: Error connecting to endpoint ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502] to ONLINE
2020-09-02 21:51:36.006 [hingStatusInfoChangedEvent] - 'modbus:data:HVAC:hvacpoll:reg32768' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error (ModbusConnectionException) with read. Request: ModbusPollerThingHandler.ModbusPollerReadRequest@327b1bb1[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=32768,length=1,maxTries=3]. Description: ModbusConnectionException(Error connecting to endpoint=ModbusTCPSlaveEndpoint@5064b4a1[address=192.168.1.111,port=502]). Message: Error connecting to endpoint 

Is there any specific reason why you have set the polling to 250ms?? Its very fast, and in my opinion may cause this issue you have with the communicating stop and your things going offline.

1 Like

It’s going to be a timing race issue, I’m sure. Subtle changes in the events processing requested will affect who wins each “race”.
Not directly connected, but I can report that us Window users do throw up a bunch of timing problems that most openHAB (Pi based) users never see, purely because of power of the host.

As @Kim_Andersen says, these are “ordinary enough” warnings and not connected to the ‘falling silent’ stoppage issue at hand.
The retry always seems to work, so it’s not serious. Most likely due to hammering the target with TCP disconnect/reconnect cycles. If you want to address this see -


I would recommend read the whole thing anyway if you expect to use a 250mS poll cycle ordinarily.

1 Like

I do need fast poll times but I believe I had poll time very slow during some testing before when I had the extra logging off and it would still stop.

If memory serves I made it progressively faster during testing trying to see if it would stall while I had the extra logging on (debug) and it never failed even at 250 ms.

Also I would have to go back and look again but I was thinking that the other user that was experiencing the stopping issue like me was not a Windows user, but again I would have to confirm that.

Normally it just stops without seeing any errors when the extra logging is off but I thought I should report this. Maybe this is a side issue?

I would believe so.
There is another thread regarding modbus stopping without any errors. Maybe your issues are both timing issues as well as that issue in the modbus binding which others are having atm (including me).

It’s not about Winows hosting, my timing comment was about the horsepower of the host box. A Windows box, even an old laptop, will outperform the Pi that hosts most openHAB instances. And hence run into different internal timing issues.

I have experimented with polling values between 1 and 10 seconds. I noticed no difference.

you still get the same communication issue?

It may be the same as one of my devices where I use a ethernet<>rs485 (modbus RTU) as mentioned, (except I dont loos connection to the poller). I just live with it.

Yes, the issue stays the same.

I’ve re-read that topic, changed reconnectAfterMillis to 60000 (tried this also previously) and added updateUnchangedValuesEveryMillis=60000 (haven’t tried this before).

I haven’t had a problem for 39 hours, so far looking good!

@stfn82 I presume that setting makes the problem more unlikely but still does not resolve the real root cause. I would appreciate if you can try out the following still, with the “bad” default settings, reconnecting the connection all the time.

@stfn82 @BHall

I would like to try one more thing, replacing one of the libraries used by the binding (pool2) to latest version with bug fixes.

Please find the instructions in this post : Modbus stops without any errors

1 Like
openhab> bundle:list -s|grep pool
307 │ Resolved │  80 │ 2.8.1                   │ org.apache.commons.commons-pool2

openhab> bundle:list -s|grep modbus
303 │ Active   │  80 │ 2.5.8                   │ org.openhab.binding.modbus
306 │ Active   │  80 │ 2.5.8                   │ org.openhab.io.transport.modbus

An changed my thing back to:

Bridge modbus:tcp:SE7K [ host="10.0.0.34", port=502, id=1, timeBetweenTransactionsMillis=250, 
timeBetweenReconnectMillis=1000, connectMaxTries=3, reconnectAfterMillis=1000, connectTimeoutMillis=2500 /*, updateUnchangedValuesEveryMillis=60000*/ ] { 

Logging set to default.

Let’s see if we can break this too.

EDIT: Added per request @ssalonen

openhab> bundle:tree-show 306 |grep pool
+- org.apache.commons.commons-pool2 [307]
1 Like

Can you also print out the bundle tree show command as well? Just to confirm the right version of pool2 is used by the binding

Edit : Yes, looks good!

1 Like

@ssalonen,

I am sorry I have not yet done this requested procedure, have had some rather heavy family obligations the last few days that have occupied most of my time.

I can try to get this done tomorrow evening if you still need me to. I noticed that @stfn82 tried the procedure and it apparently did not help.

Has anything else surfaced that provides any clues?

Please advise how I should proceed.

Thanks!

@BHall I haven’t got any test results yet from @stfn82, no.

However, it did not work in the other thread (user jomojomo95). Modbus stops without any errors . So I assume that mere update of commons-pool2 to 2.8.1 is not enough.

I would like to move the discussion to there, to make it easier to communicate and to clarify that we are not discussing the original issue anymore of “2.5.7 stops working after unsuccessful read attempt”.

Check out the other thread for modbus-fix-hang version, once again attempt to fix the issue with another way.

The new pool doesn’t help. Binding still stops working multiple times a day.

[WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandler.ModbusPollerReadRequest@47edcab[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=69,length=50,maxTries=3]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 19f23777-6ebc-4152-ac6e-a6f272162fd0]

I’ll move over to the other thread.

1 Like

This is the binding working as it should. Your device failed to answer for some reason.

This is not “binding stops working”, not least because you said you get it multiple times a day (so it hasn’t stopped). Let’s not muddy up that investigation.

You already know you can avoid this with TCP timing changes, but have chosen to undo those changes. That’s fine, a while back you had the “silent stoppage” with 2.5.8 and are now trying to recreate the conditions you had then.

Comment - I hadn’t look closely at this before, please note that TCP Bridge Things don’t have an updateUnchangedValuesEveryMillis parameter. That belongs to data Things. (that won’t have done any harm)

I think I’d try it with timeBetweenTransactionsMillis=100 to speed things up a bit.
And of course a much bigger timeBetweenReconnectMillis when you’re ready to fix the retries…

2 Likes

OK let me rephrase that: My thing still stops working multiple times a day. :stuck_out_tongue_winking_eye:

This was per request, today I changed it back till I have time to test the latest modbus-fix-hang version.

I will also try this, but I already have experimented with many different settings. I didn’t notice any difference.