Input/output error in writeArray

Hello,

I’m using OpenHAB Version 2.5.0 (Build)

I faced this error yesterday in the openhab.log file:

2019-12-24 01:02:36.097 [ERROR] [internal.handler.RFXComBridgeHandler] - I/O Error
java.io.IOException: Input/output error in writeArray
at gnu.io.RXTXPort.writeArray(Native Method) ~[?:?]
at gnu.io.RXTXPort$SerialOutputStream.write(RXTXPort.java:1171) ~[?:?]
at org.openhab.binding.rfxcom.internal.connector.RFXComSerialConnector.sendMessage(RFXComSerialConnector.java:139) ~[bundleFile:?]
at org.openhab.binding.rfxcom.internal.handler.RFXComBridgeHandler$TransmitQueue.send(RFXComBridgeHandler.java:100) ~[bundleFile:?]
at org.openhab.binding.rfxcom.internal.handler.RFXComBridgeHandler$TransmitQueue.enqueue(RFXComBridgeHandler.java:81) ~[bundleFile:?]
at org.openhab.binding.rfxcom.internal.handler.RFXComBridgeHandler.sendMessage(RFXComBridgeHandler.java:224) [bundleFile:?]
at org.openhab.binding.rfxcom.internal.handler.RFXComHandler.handleCommand(RFXComHandler.java:84) [bundleFile:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_222]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_222]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]
at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [bundleFile:?]
at com.sun.proxy.$Proxy288.handleCommand(Unknown Source) [?:?]
at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:74) [bundleFile:?]
at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:48) [bundleFile:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_222]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_222]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]
at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_222]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_222]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_222]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]

it seems to be related to an event, as I can see in the events.log

2019-12-24 01:02:36.004 [ome.event.ItemCommandEvent] - Item ‘VoletBureau_Shutter’ received command UP
2019-12-24 01:02:36.028 [nt.ItemStatePredictedEvent] - VoletBureau_Shutter predicted to become UP
2019-12-24 01:02:36.062 [vent.ItemStateChangedEvent] - VoletBureau_Shutter changed from NULL to 0
2019-12-24 01:02:36.191 [hingStatusInfoChangedEvent] - ‘rfxcom:bridge:5dac2b60’ changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Input/output error in writeArray
2019-12-24 01:02:36.197 [hingStatusInfoChangedEvent] - ‘rfxcom:rfy:2cd957f2’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.201 [hingStatusInfoChangedEvent] - ‘rfxcom:rfy:6afdd7b0’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.205 [hingStatusInfoChangedEvent] - ‘rfxcom:lighting2:5dac2b60:31568978_2’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.208 [hingStatusInfoChangedEvent] - ‘rfxcom:lighting2:5dac2b60:31568978_1’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.216 [hingStatusInfoChangedEvent] - ‘rfxcom:lighting2:5dac2b60:31568978_0’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.219 [hingStatusInfoChangedEvent] - ‘rfxcom:lighting2:5dac2b60:31568978_3’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.221 [hingStatusInfoChangedEvent] - ‘rfxcom:rfy:5ec28541’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.223 [hingStatusInfoChangedEvent] - ‘rfxcom:temperaturehumidity:5dac2b60:3’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.229 [hingStatusInfoChangedEvent] - ‘rfxcom:temperaturehumidity:5dac2b60:2’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.234 [hingStatusInfoChangedEvent] - ‘rfxcom:rfy:87df3086’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.238 [hingStatusInfoChangedEvent] - ‘rfxcom:rfy:83bf44c8’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.241 [hingStatusInfoChangedEvent] - ‘rfxcom:temperaturehumidity:1377693c’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.245 [hingStatusInfoChangedEvent] - ‘rfxcom:temperaturehumidity:5dac2b60:1’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2019-12-24 01:02:36.248 [hingStatusInfoChangedEvent] - ‘rfxcom:rfy:3a6fa9c6’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

I saw some other people having this issue, but not with RFXCOM. So maybe it’s not RFXCOM related.

I’m posting this to share the issue, and maybe get some ideas!
Thanks in advance!

This looks like the issue where an invalid addon causes the addons to load every minute as OH tries to load the invalid addon. A common culprit is restdocs which was documented as moving from misc to ui. Check the addons.config file in var/lib/openhab2 for invalid addons.

Thanks Bruce, I had already removed this restdocs add-on which caused me lof of trouble when upgrading to 2.5.

The current content of my addons.config file is:

binding="rfxcom,denonmarantz,network"
package="standard"
persistence="influxdb"
service.pid="org.openhab.addons"
ui="basic,paper,habpanel,homebuilder,classic,habmin"

I should mention also that I’m not experiencing this issue regularly so far. It happened only once.

Try uninstalling the binding, stop OH, clean the cache, restart OH and after fully restarted then reinstall the binding.

1 Like