I/O exception Input/output error in writeArray during sending

Add-ons Bindings
1

  • Platform information:

    • Hardware: Rapspberry Pi
    • OS: 4.19.57-v7+
    • Java Runtime Environment: 1.8.0_162-b12
    • openHAB version: 2.5.0 M1

  • zwave serial controller: AEOTEC

  • Switch: 45605 Duplex Receptacles, 45609 On/Off Relay Switch

  • Issue of the topic: Getting the error “I/O exception Input/output error in writeArray during sending.” whenever I change the status of a wave device. Additionally I cannot switch a node on or off.
    I already did a hard reset on the controller and set up all devices again.

Edit: I found that rebooting the Raspi will solve the problem for about one day, after which it will reoccur.
I also replaced the switch with another one, same result.

Here‘s a excerpt of the logfile:

13:48:55.915 [INFO ] [smarthome.event.ItemCommandEvent  ] - Item 'ReceptacleFront_Switch' received command ON

13:48:55.939 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 24: Command received zwave:device:169b6fc851a:node24:switch_binary --> ON [OnOffType]

13:48:55.959 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 24: Creating new message for application command SWITCH_BINARY_SET

13:48:55.989 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY not supported

13:48:56.021 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 24: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured

13:48:56.037 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 24: Adding to device queue

13:48:56.054 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 24: Added 634 to queue - size 2

13:48:56.068 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

13:48:56.093 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 18 03 25 01 FF 25 20 23 

13:48:56.113 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 24: Sending REQUEST Message = 01 0A 00 13 18 03 25 01 FF 25 20 23 

13:48:56.134 [ERROR] [ding.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.

13:48:56.152 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 634: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 32

13:48:56.172 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 24: Polling intialised at 86400 seconds - start in 1500 milliseconds.

13:48:57.672 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 24: Polling...

13:48:57.684 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 24: Polling deferred until initialisation complete

13:48:58.171 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 24: TID 634: Timeout at state WAIT_RESPONSE. 3 retries remaining.

13:48:58.181 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!

13:48:58.190 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 634: Transaction ABORTED

13:48:58.200 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 

13:48:58.213 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

13:48:58.225 [ERROR] [ding.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.

 

13:48:58.239 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

13:49:10.199 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 24: TID 634: Timeout at state ABORTED. 3 retries remaining.

13:49:10.208 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 634: Transaction is current transaction, so clearing!!!!!

13:49:10.220 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 634: Transaction CANCELLED

13:49:10.233 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

13:49:10.245 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 24: notifyTransactionResponse TID:634 CANCELLED

13:49:10.261 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

@chris: can you give me an idea what to look for?

2

Unfortunately, I’ve not a lot of ideas. I would guess that this sort of error comes from issues in the depths of the serial driver, but I’m not really sure.

3

I have the same issue, happens daily now, around 6pm.

Chris, if i’m on 2.4.0 stable with the latest zwave snapshot binding, it wouldnt be a driver issue right? Suspect mines related to socat/ser2net so Im setting up the MQTT event bus as I’m getting too many issues. Hard to tell if they are zwave or ser2net/socat

4

Since I don’t know what the issue is, I’m not really sure I can say it’s not a driver issue. As I said, I’m not really sure what is causing this. I don’t believe it is a binding problem - it could be the openHAB driver or other low level issues. This is the same for 2.4 and 2.5 so I am reallly at a loss as to why you conclude it isn’t a driver problem?

5

I’m not concluding anything - if I knew what the issue is I’m sure it would be fixed :slight_smile: That’s why I’m going to remove socat/ser2net to try and isolate the issue.

6

Thanks guys - just as an update: I installed M5 and that didn‘t change anything either.