Just an hour ago my three Z-Wave door locks started locking and unlocking over and over for about a minute. Now they are back working normally. What could be the problem?
Note, I am really “rusty” about OpenHAB. I built my system a few years ago and it has been running fine ever since. And at 74 years of age, my memory isn’t what it used to be. I feel like I should be providing a lot more detail about my setup but frankly it will take me hours to research my setup. So I’m hoping someone can say, “Z-Wave will do that if ???”.
If you folks can’t give me the short answer I will “roll up my sleeves” and figure out what I did.
Unfortunately, the answer is “Z-Wave should not do that”. And the fact that three different locks did this is concerning. I don’t want to alarm you, but my first instinct is that someone has hacked your network or server.
Start by checking your log to see if there were any commands directed from OH to the locks. It should be pretty obvious if they behaved this way for a full minute.
Then you need to check if your server has been exposed to the Internet, in which case it may have been compromised. I’m not an expert in this field, but @rlkoshak is. You might also find some tips in threads like this:
I can’t say for certain that your system has been hacked. It may just be some totally random malfunction, but it’s better to assume that it is and find out later that it is not.
With the current set of information about all I can do is echo what has already been said. Look in events.log to see what the events where. If you see lots of “received command” events on the lock Items then the source of the behavior is probably OH itself. If you only see a lot of “changed” events the source is probably outside of OH itself.
I don’t usually jump right away to the “you’ve been hacked” conclusion without more evidence. But in general there isn’t a short answer. It shouldn’t do that and we can’t tell you why it is doing that right now.
Wow, I can’t believe how much I have forgotten. It took me forever to finally view the logs and figure out what they were saying. They are below. Apparently there was more going on then I knew. I have 4 Z-wave devices. Three locks, Front, Porch, and Garage. I also have a Thermostat and it was getting commands also. (I also have two ZCOMBO alarms that I have never been able to get working.) When this happened I was under the cooking range trying to figure out why one igniter was not working and the door locks started moving and beeping. From the log it looks like it went on for about 2 minutes.
Rich, I think there are commands. What else should I look for.
2022-02-09 12:15:43.588 [ome.event.ItemCommandEvent] - Item 'Garage_Lock_Door' received command OFF
2022-02-09 12:15:43.603 [nt.ItemStatePredictedEvent] - Garage_Lock_Door predicted to become OFF
2022-02-09 12:15:43.646 [vent.ItemStateChangedEvent] - Garage_Lock_Door changed from ON to OFF
2022-02-09 12:15:44.970 [ome.event.ItemCommandEvent] - Item 'Garage_Lock_Door' received command ON
2022-02-09 12:15:44.973 [nt.ItemStatePredictedEvent] - Garage_Lock_Door predicted to become ON
2022-02-09 12:15:44.986 [vent.ItemStateChangedEvent] - Garage_Lock_Door changed from OFF to ON
2022-02-09 12:15:45.752 [ome.event.ItemCommandEvent] - Item 'Garage_Lock_Door' received command OFF
2022-02-09 12:15:45.758 [nt.ItemStatePredictedEvent] - Garage_Lock_Door predicted to become OFF
2022-02-09 12:15:45.770 [vent.ItemStateChangedEvent] - Garage_Lock_Door changed from ON to OFF
2022-02-09 12:15:46.548 [vent.ItemStateChangedEvent] - Garage_Alarm_Raw changed from {"type":"22","value":"1"} to {"type":"25","value":"1"}
2022-02-09 12:15:46.595 [vent.ItemStateChangedEvent] - Garage_Alarm_Virtual changed from Manual unlock to RF unlock
2022-02-09 12:15:46.687 [vent.ItemStateChangedEvent] - Garage_Alarm_Raw changed from {"type":"25","value":"1"} to {"type":"24","value":"1"}
2022-02-09 12:15:46.721 [vent.ItemStateChangedEvent] - Garage_Alarm_Virtual changed from RF unlock to RF lock
2022-02-09 12:15:46.724 [vent.ItemStateChangedEvent] - Garage_Alarm_Virtual_Short changed from Un-Secured to Secured
2022-02-09 12:15:47.464 [vent.ItemStateChangedEvent] - Garage_Lock_Door changed from OFF to ON
2022-02-09 12:15:51.916 [ome.event.ItemCommandEvent] - Item 'Front_Lock_Door' received command ON
2022-02-09 12:15:51.920 [nt.ItemStatePredictedEvent] - Front_Lock_Door predicted to become ON
2022-02-09 12:15:51.939 [vent.ItemStateChangedEvent] - Front_Lock_Door changed from OFF to ON
2022-02-09 12:15:53.795 [ome.event.ItemCommandEvent] - Item 'Front_Lock_Door' received command OFF
2022-02-09 12:15:53.800 [nt.ItemStatePredictedEvent] - Front_Lock_Door predicted to become OFF
2022-02-09 12:15:53.820 [vent.ItemStateChangedEvent] - Front_Lock_Door changed from ON to OFF
2022-02-09 12:15:57.206 [ome.event.ItemCommandEvent] - Item 'Porch_Lock_Door' received command OFF
2022-02-09 12:15:57.211 [nt.ItemStatePredictedEvent] - Porch_Lock_Door predicted to become OFF
2022-02-09 12:15:57.223 [vent.ItemStateChangedEvent] - Porch_Lock_Door changed from ON to OFF
2022-02-09 12:16:00.244 [vent.ItemStateChangedEvent] - Porch_Lock_Door changed from OFF to ON
2022-02-09 12:16:01.315 [vent.ItemStateChangedEvent] - Front_Alarm_Raw changed from {"type":"18","value":"0"} to {"type":"25","value":"1"}
2022-02-09 12:16:01.347 [vent.ItemStateChangedEvent] - Front_Alarm_Virtual changed from Lock Un-Secured using Keyed cylinder or inside thumb-turn to Lock Un-Secured by Controller – Successful (Fully retracted)
2022-02-09 12:16:01.468 [vent.ItemStateChangedEvent] - Porch_Alarm_Raw changed from {"type":"0","value":"0"} to {"type":"25","value":"1"}
2022-02-09 12:16:03.924 [vent.ItemStateChangedEvent] - Porch_Alarm_Virtual changed from RF lock to RF unlock
2022-02-09 12:16:03.935 [vent.ItemStateChangedEvent] - Porch_Alarm_Virtual_Short changed from Secured to Un-Secured
2022-02-09 12:16:08.792 [ome.event.ItemCommandEvent] - Item 'HVAC_Mode' received command 1
2022-02-09 12:16:08.797 [nt.ItemStatePredictedEvent] - HVAC_Mode predicted to become 1
2022-02-09 12:16:08.812 [vent.ItemStateChangedEvent] - HVAC_Mode changed from 0 to 1
2022-02-09 12:16:10.689 [vent.ItemStateChangedEvent] - HVAC_Operating_State changed from 0 to 1
2022-02-09 12:16:10.893 [vent.ItemStateChangedEvent] - Basement_Tstat_CoolSetPoint changed from NULL to 7E+1 °F
2022-02-09 12:16:11.185 [vent.ItemStateChangedEvent] - ThermostatBattery changed from NULL to 100
2022-02-09 12:16:31.394 [ome.event.ItemCommandEvent] - Item 'HVAC_Fan_State' received command 1
2022-02-09 12:16:31.396 [nt.ItemStatePredictedEvent] - HVAC_Fan_State predicted to become 1
2022-02-09 12:16:31.408 [vent.ItemStateChangedEvent] - HVAC_Fan_State changed from 0 to 1
2022-02-09 12:16:33.699 [vent.ItemStateChangedEvent] - HVAC_Fan_State changed from 1 to 0
2022-02-09 12:16:35.769 [ome.event.ItemCommandEvent] - Item 'HVAC_Fan_State' received command 1
2022-02-09 12:16:35.772 [nt.ItemStatePredictedEvent] - HVAC_Fan_State predicted to become 1
2022-02-09 12:16:35.788 [vent.ItemStateChangedEvent] - HVAC_Fan_State changed from 0 to 1
2022-02-09 12:16:38.134 [vent.ItemStateChangedEvent] - HVAC_Fan_State changed from 1 to 0
2022-02-09 12:16:56.172 [ome.event.ItemCommandEvent] - Item 'Garage_Lock_Door' received command OFF
2022-02-09 12:16:56.176 [nt.ItemStatePredictedEvent] - Garage_Lock_Door predicted to become OFF
2022-02-09 12:16:56.191 [vent.ItemStateChangedEvent] - Garage_Lock_Door changed from ON to OFF
2022-02-09 12:16:57.879 [vent.ItemStateChangedEvent] - Garage_Lock_Door changed from OFF to ON
2022-02-09 12:16:58.028 [vent.ItemStateChangedEvent] - Garage_Alarm_Raw changed from {"type":"24","value":"1"} to {"type":"0","value":"0"}
2022-02-09 12:16:59.059 [vent.ItemStateChangedEvent] - Garage_Alarm_Raw changed from {"type":"0","value":"0"} to {"type":"25","value":"1"}
2022-02-09 12:16:59.090 [vent.ItemStateChangedEvent] - Garage_Alarm_Virtual changed from RF lock to RF unlock
2022-02-09 12:16:59.095 [vent.ItemStateChangedEvent] - Garage_Alarm_Virtual_Short changed from Secured to Un-Secured
2022-02-09 12:17:02.944 [ome.event.ItemCommandEvent] - Item 'Porch_Lock_Door' received command OFF
2022-02-09 12:17:02.948 [nt.ItemStatePredictedEvent] - Porch_Lock_Door predicted to become OFF
2022-02-09 12:17:02.962 [vent.ItemStateChangedEvent] - Porch_Lock_Door changed from ON to OFF
2022-02-09 12:17:04.815 [vent.ItemStateChangedEvent] - Porch_Alarm_Raw changed from {"type":"25","value":"1"} to {"type":"0","value":"0"}
2022-02-09 12:17:05.358 [vent.ItemStateChangedEvent] - Porch_Alarm_Raw changed from {"type":"0","value":"0"} to {"type":"25","value":"1"}
2022-02-09 12:17:10.850 [ome.event.ItemCommandEvent] - Item 'Porch_Lock_Door' received command ON
2022-02-09 12:17:10.862 [nt.ItemStatePredictedEvent] - Porch_Lock_Door predicted to become ON
2022-02-09 12:17:10.887 [vent.ItemStateChangedEvent] - Porch_Lock_Door changed from OFF to ON
2022-02-09 12:17:12.571 [vent.ItemStateChangedEvent] - Porch_Lock_Door changed from ON to OFF
2022-02-09 12:17:12.728 [vent.ItemStateChangedEvent] - Porch_Alarm_Raw changed from {"type":"25","value":"1"} to {"type":"0","value":"0"}
2022-02-09 12:17:13.534 [vent.ItemStateChangedEvent] - Porch_Alarm_Raw changed from {"type":"0","value":"0"} to {"type":"24","value":"1"}
2022-02-09 12:17:13.563 [vent.ItemStateChangedEvent] - Porch_Alarm_Virtual changed from RF unlock to RF lock
2022-02-09 12:17:13.574 [vent.ItemStateChangedEvent] - Porch_Alarm_Virtual_Short changed from Un-Secured to Secured
2022-02-09 12:17:14.678 [ome.event.ItemCommandEvent] - Item 'Porch_Lock_Door' received command OFF
2022-02-09 12:17:14.682 [nt.ItemStatePredictedEvent] - Porch_Lock_Door predicted to become OFF
2022-02-09 12:17:16.190 [vent.ItemStateChangedEvent] - Porch_Alarm_Raw changed from {"type":"24","value":"1"} to {"type":"25","value":"1"}
2022-02-09 12:17:16.206 [vent.ItemStateChangedEvent] - Porch_Alarm_Virtual changed from RF lock to RF unlock
2022-02-09 12:17:16.214 [vent.ItemStateChangedEvent] - Porch_Alarm_Virtual_Short changed from Secured to Un-Secured
2022-02-09 12:17:23.788 [ome.event.ItemCommandEvent] - Item 'Front_Lock_Door' received command ON
2022-02-09 12:17:23.791 [nt.ItemStatePredictedEvent] - Front_Lock_Door predicted to become ON
2022-02-09 12:17:23.803 [vent.ItemStateChangedEvent] - Front_Lock_Door changed from OFF to ON
2022-02-09 12:17:27.753 [vent.ItemStateChangedEvent] - Front_Alarm_Raw changed from {"type":"25","value":"1"} to {"type":"24","value":"1"}
2022-02-09 12:17:27.780 [vent.ItemStateChangedEvent] - Front_Alarm_Virtual changed from Lock Un-Secured by Controller – Successful (Fully retracted) to Lock Secured by Controller – Successful (Fully extended)
2022-02-09 12:17:27.801 [vent.ItemStateChangedEvent] - Front_Alarm_Virtual_Short changed from Un-Secured to Secured
2022-02-09 12:17:30.237 [ome.event.ItemCommandEvent] - Item 'Front_Lock_Door' received command OFF
2022-02-09 12:17:30.241 [nt.ItemStatePredictedEvent] - Front_Lock_Door predicted to become OFF
2022-02-09 12:17:30.260 [vent.ItemStateChangedEvent] - Front_Lock_Door changed from ON to OFF
2022-02-09 12:17:32.939 [ome.event.ItemCommandEvent] - Item 'Garage_Lock_Door' received command OFF
2022-02-09 12:17:32.943 [nt.ItemStatePredictedEvent] - Garage_Lock_Door predicted to become OFF
2022-02-09 12:17:32.963 [vent.ItemStateChangedEvent] - Garage_Lock_Door changed from ON to OFF
2022-02-09 12:17:34.797 [vent.ItemStateChangedEvent] - Garage_Alarm_Raw changed from {"type":"25","value":"1"} to {"type":"0","value":"0"}
2022-02-09 12:17:35.314 [vent.ItemStateChangedEvent] - Front_Alarm_Raw changed from {"type":"24","value":"1"} to {"type":"25","value":"1"}
2022-02-09 12:17:35.337 [vent.ItemStateChangedEvent] - Front_Alarm_Virtual changed from Lock Secured by Controller – Successful (Fully extended) to Lock Un-Secured by Controller – Successful (Fully retracted)
2022-02-09 12:17:35.345 [vent.ItemStateChangedEvent] - Front_Alarm_Virtual_Short changed from Secured to Un-Secured
2022-02-09 12:17:35.435 [vent.ItemStateChangedEvent] - Garage_Alarm_Raw changed from {"type":"0","value":"0"} to {"type":"25","value":"1"}
2022-02-09 12:17:42.965 [ome.event.ItemCommandEvent] - Item 'HVAC_Fan_State' received command 1
2022-02-09 12:17:42.969 [nt.ItemStatePredictedEvent] - HVAC_Fan_State predicted to become 1
2022-02-09 12:17:42.997 [vent.ItemStateChangedEvent] - HVAC_Fan_State changed from 0 to 1
2022-02-09 12:17:45.382 [vent.ItemStateChangedEvent] - HVAC_Fan_State changed from 1 to 0
When you manually lock/unlock one of them does that result in a command or just an update? You can tell from the logs.
If it does result in a command . All we know (and probably all we can know) is that the locks triggered.
If it does not result in a command, then we know that the activity came from openHAB. Unfortunately, again there is only so much we can surmise from that. You might have a rule that ran crazy. There might have been some hick-up that will never happen again. Or, indeed, someone may have hacked into your OH and started playing around.
We don’t even know what version of OH you are running nor one what sort of hardware or operating system. And even if we did, there’s only so much we can do through a forum like this.
You can review and remove rules that have anything that messes with the locks. If it happens again after that you know it wasn’t caused by your rules. Review your security posture to make sure your OH isn’t directly exposed to the Internet, you have a secure wifi, stuff like that.
Yes, I should have asked for more system information in my response. Admittedly, “my door locks are acting on their own” led me to click the “Reply” button a little faster than I should have, since that’s a pretty serious risk to your home (as opposed to a lightbulb).
Sorry about not describing my system. OpenHAB 2.5 on a Raspberry Pi 3B.
When I manually turn the bolt no event occurs but the two lines below are added to openhab.log So I looked in openhab.log back at the time of the problem and discovered the second set of Preformatted text below. I have no idea what that means. I guess I should have checked that first (head slap).
2022-02-09 12:13:39.026 [ERROR] [ersey.server.ServerRuntime$Responder] - An I/O error has occurred while writing a response message entity to the container output stream.
org.glassfish.jersey.server.internal.process.MappableException: org.eclipse.jetty.io.EofException
at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:92) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[bundleFile:?]
at org.glassfish.jersey.message.internal.MessageBodyFactory.writeTo(MessageBodyFactory.java:1130) ~[bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.writeResponse(ServerRuntime.java:711) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.processResponse(ServerRuntime.java:444) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.process(ServerRuntime.java:434) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:329) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [bundleFile:?]
at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [bundleFile:?]
at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [bundleFile:?]
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:852) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:544) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:536) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1581) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1307) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:482) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1549) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1204) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [bundleFile:?]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.Server.handle(Server.java:494) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:374) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:268) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:367) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:782) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:918) [bundleFile:9.4.20.v20190813]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]
Caused by: org.eclipse.jetty.io.EofException
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:283) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:378) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:132) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:298) ~[bundleFile:9.4.20.v20190813]
... 6 more
Caused by: java.io.IOException: Broken pipe
at sun.nio.ch.FileDispatcherImpl.write0(Native Method) ~[?:1.8.0_222]
at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47) ~[?:1.8.0_222]
at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93) ~[?:1.8.0_222]
at sun.nio.ch.IOUtil.write(IOUtil.java:51) ~[?:1.8.0_222]
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:471) ~[?:1.8.0_222]
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:261) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:378) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:132) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:298) ~[bundleFile:9.4.20.v20190813]
... 6 more
2022-02-09 12:13:39.156 [ERROR] [ersey.server.ServerRuntime$Responder] - An I/O error has occurred while writing a response message entity to the container output stream.
org.glassfish.jersey.server.internal.process.MappableException: org.eclipse.jetty.io.EofException
at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:92) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[bundleFile:?]
at org.glassfish.jersey.message.internal.MessageBodyFactory.writeTo(MessageBodyFactory.java:1130) ~[bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.writeResponse(ServerRuntime.java:711) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.processResponse(ServerRuntime.java:444) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.process(ServerRuntime.java:434) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:329) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [bundleFile:?]
at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [bundleFile:?]
at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [bundleFile:?]
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:852) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:544) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:536) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1581) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1307) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:482) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1549) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1204) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [bundleFile:?]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.Server.handle(Server.java:494) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:374) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:268) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:367) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:782) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:918) [bundleFile:9.4.20.v20190813]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]
Caused by: org.eclipse.jetty.io.EofException
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:283) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:378) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:132) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:298) ~[bundleFile:9.4.20.v20190813]
... 6 more
Caused by: java.io.IOException: Broken pipe
at sun.nio.ch.FileDispatcherImpl.write0(Native Method) ~[?:1.8.0_222]
at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47) ~[?:1.8.0_222]
at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93) ~[?:1.8.0_222]
at sun.nio.ch.IOUtil.write(IOUtil.java:51) ~[?:1.8.0_222]
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:471) ~[?:1.8.0_222]
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:261) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:378) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:132) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:298) ~[bundleFile:9.4.20.v20190813]
... 6 more
2022-02-09 12:13:39.053 [ERROR] [ersey.server.ServerRuntime$Responder] - An I/O error has occurred while writing a response message entity to the container output stream.
org.glassfish.jersey.server.internal.process.MappableException: org.eclipse.jetty.io.EofException
at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:92) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[bundleFile:?]
at org.glassfish.jersey.message.internal.MessageBodyFactory.writeTo(MessageBodyFactory.java:1130) ~[bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.writeResponse(ServerRuntime.java:711) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.processResponse(ServerRuntime.java:444) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.process(ServerRuntime.java:434) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:329) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [bundleFile:?]
at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [bundleFile:?]
at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [bundleFile:?]
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:852) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:544) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:536) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1581) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1307) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:482) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1549) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1204) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [bundleFile:?]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.Server.handle(Server.java:494) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:374) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:268) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:367) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:782) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:918) [bundleFile:9.4.20.v20190813]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]
Caused by: org.eclipse.jetty.io.EofException
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:283) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:378) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:132) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:298) ~[bundleFile:9.4.20.v20190813]
... 6 more
Caused by: java.io.IOException: Broken pipe
at sun.nio.ch.FileDispatcherImpl.write0(Native Method) ~[?:1.8.0_222]
at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47) ~[?:1.8.0_222]
at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93) ~[?:1.8.0_222]
at sun.nio.ch.IOUtil.write(IOUtil.java:51) ~[?:1.8.0_222]
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:471) ~[?:1.8.0_222]
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:261) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:378) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:132) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:298) ~[bundleFile:9.4.20.v20190813]
... 6 more
2022-02-09 12:13:39.052 [ERROR] [ersey.server.ServerRuntime$Responder] - An I/O error has occurred while writing a response message entity to the container output stream.
org.glassfish.jersey.server.internal.process.MappableException: org.eclipse.jetty.io.EofException
at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:92) ~[?:?]
at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[bundleFile:?]
at org.glassfish.jersey.message.internal.MessageBodyFactory.writeTo(MessageBodyFactory.java:1130) ~[bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.writeResponse(ServerRuntime.java:711) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.processResponse(ServerRuntime.java:444) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$Responder.process(ServerRuntime.java:434) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:329) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [bundleFile:?]
at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [bundleFile:?]
at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [bundleFile:?]
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:852) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:544) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:536) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1581) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1307) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:482) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1549) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1204) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [bundleFile:?]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.Server.handle(Server.java:494) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:374) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:268) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:367) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:782) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:918) [bundleFile:9.4.20.v20190813]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]
Caused by: org.eclipse.jetty.io.EofException
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:283) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:378) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:132) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:298) ~[bundleFile:9.4.20.v20190813]
... 6 more
Caused by: java.io.IOException: Broken pipe
at sun.nio.ch.FileDispatcherImpl.write0(Native Method) ~[?:1.8.0_222]
at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47) ~[?:1.8.0_222]
at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93) ~[?:1.8.0_222]
at sun.nio.ch.IOUtil.write(IOUtil.java:51) ~[?:1.8.0_222]
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:471) ~[?:1.8.0_222]
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:261) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:378) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:132) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) ~[bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:298) ~[bundleFile:9.4.20.v20190813]
... 6 more
2022-02-09 12:15:46.576 [DEBUG] [e.smarthome.model.script.RULE_SYSTEM] - Garage Lock: Alarm events: TFormed State: 25, StateValue: 1
2022-02-09 12:15:46.599 [DEBUG] [e.smarthome.model.script.RULE_SYSTEM] - GarageLock RF unlock
2022-02-09 12:15:46.703 [DEBUG] [e.smarthome.model.script.RULE_SYSTEM] - Garage Lock: Alarm events: TFormed State: 24, StateValue: 1
2022-02-09 12:15:46.717 [DEBUG] [e.smarthome.model.script.RULE_SYSTEM] - GarageLock RF lock
2022-02-09 12:16:01.329 [DEBUG] [e.smarthome.model.script.RULE_SYSTEM] - Lock: Alarm events: TFormed State: 25, StateValue: 1
2022-02-09 12:16:01.344 [DEBUG] [e.smarthome.model.script.RULE_SYSTEM] - FrontLock Lock Un-Secured by Controller – Successful (Fully retracted)
2022-02-09 12:16:03.911 [DEBUG] [e.smarthome.model.script.RULE_SYSTEM] - Porch Lock: Alarm events: TFormed State: 25, StateValue: 1
2022-02-09 12:16:03.926 [DEBUG] [e.smarthome.model.script.RULE_SYSTEM] - PorchLock RF unlock
2022-02-09 12:16:10.875 [WARN ] [al.profiles.MapTransformationProfile] - Please specify a function
I don’t know enough to comment on that log, or if/why a JavaScript exception would cause your locks (and only your locks?) to spontaneously trigger.
You haven’t said anything about your security. Have you intentionally done anything that would make your server visible on the Internet? Or are you only accessing it remotely via myopenHAB?
Going to assume this is OH2, possibly OH1, but would be good to know exactly?
EDIT - okay, 2.5 is good.
Hmm, curious thing in the middle of all that.
Going to assume you have not set up daily reboots or similar?
What can we deduce from this snippet:
Items are born with state NULL, or get set to NULL if you edit them. Editing is unlikely unless really hacked, and a weird thing to do then… But assuming Items are defined from file in this system, the file may have been reloaded causing Item reset. You can set Items NULL from rule or REST, but its really unusual.
Looks like a battery device, maybe z-wave (like the locks) and as such would only periodically report stuff like this - hourly, once a day, whatever.
Taken together, something reset this Item in the last N hours.
We’ve seen some weird zwave mesh catastrophes in the past, but usually this throws up UNDEF states (as binding side stuff should) not NULL. So I think this is a clue it is not a zwave hiccup.
I’ll put my money on a file reload. Something like that could also cause rules to fire as in system startup period. That reload would be triggered by the file watcher service, and involves timestamps. Possible causes then, a filestore glitch - SD Card? Maybe even a system clock glitch.
Worth looking at your openhab.log for the period leading up to first locking, for further clues. Close eye on date/timestamps.
A possible source of Lock/HVAC commands is a GUI or the connection to it messing around - do you have a permanent tablet display or such, a browser window in the background, cat on the keyboard etc.? But I lean against that because its really unlikely to produce those NULL states.
Java error in log looks like the kind of thing UI ‘incidents’ or external comms can throw, not too alarming when there is lots going on - possibly symptom not cause.
[rpwong]
I am using myopenHAB.
I don’t think I have done anything to expose my server. But I don’t know much about that area.
I guess I’ll have to look into it. I’ll start with the Raspberry Pi community.
I was mistaken when I said that manually changing the lock didn’t cause an event. It does create two log entries (below).
It does seem to effect all operating Z-Wave devices. I have three locks and one thermostat which was told to do things like change the set temperature and turn the fan on/off.
[rossko57]
No daily reboots.
I’ll have to give a lot of thought to the rest of your post.
2022-02-10 14:40:52.221 [vent.ItemStateChangedEvent] - Porch_Alarm_Virtual changed from Manual unlock to Manual lock by thumb
2022-02-10 14:40:52.229 [vent.ItemStateChangedEvent] - Porch_Alarm_Virtual_Short changed from Un-Secured to Secured
If you haven’t specifically tried do to anything to make your server visible, then it’s likely fine. Note, however, that there was a recent patch for the log4j2 vulnerability, and you should make sure you have it. I don’t recall if it was deployed for 2.5, though.
Like I said originally, I prefer to be cautious. Kind of like when I take my car to the mechanic. I always assume it’ll cost me a lot, so that I’m pleasantly surprised if/when it doesn’t.
Either way there seems to be a security risk (or an user error). According to the user manual the Yale locks require the relevant command classes to be secured, so I’d tend to agree that the problem is related to OH.
I went through openhab.log and Events.log at the time in question and sumerized what I found below. But I wonder. Did openHAB crash? If so, how did it restart?
Before the problem, normal functioning
2/8 at 10:00 PM openHAB ordered the three doors to lock as it should.
2/9 at 10:11 AM I unlocked the Garage.
2/9 at 10:35 AM I unlocked the Front to let in a visitor.
2/9 at 12:13:39.026 all hell breaks loose.
"An I/O error has occurred while writing a response message entity to the container output stream."
Followed by many lines of information.
2/9 at 12:13:39.156
" An I/O error has occurred while writing a response message entity to the container output stream."
Followed by many lines of information.
2/9 at 12:13:39.053 XXXXX out of time order
"An I/O error has occurred while writing a response message entity to the container output stream."
Followed by many lines of information.
2/9 at 12:13:39.052 XXXXX out of time order
"An I/O error has occurred while writing a response message entity to the container output stream."
Followed by many lines of information.
2/9 at 12:15:46.576 the error messages stop and sequences of:
Garage RF unlock
Garage RF lock
Front unlock by cntroller (same as RF unlock by different company)
Porch RF unlock
Garage RF unlock
Porch RF unlock
These kinds of sequences continue every few milliseconds until 12:17:35.455
which is about 2 minutes. After that things get back to normal. (I think).
However, openHAB.log doesn't show any commands to the HVAC which is the fourth Z-Wave device
Events.log shows, in addition to the door lock actions, a series of commands and actions with the HVAC from 12:16:08.797 to 12:16:38.134