[velux] login sequence failed after upgrade to v3.2.0M4

Hi, just upgraded to OH 3.2.0M4 and cannot connect to velux bridge anymore. I get the following error

Login sequence failed.

Tried several reboots, restart of binding through karaf console, restart through suggested rule and reboot of KLF200 but nothing works. The bridge remains in status “offline - configuration error”.
Set log at debug level, here is the log

2021-11-16 12:03:53.811 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - SSLconnection() called.
2021-11-16 12:03:54.934 [DEBUG] [internal.bridge.slip.SCgetLimitation] - SCgetLimitation(Constructor) called.
2021-11-16 12:03:54.936 [DEBUG] [internal.bridge.slip.SCgetLimitation] - SCgetLimitation(): starting sessions with the random number 407.
2021-11-16 12:03:54.950 [DEBUG] [rnal.bridge.slip.SCrunProductCommand] - SCgetProduct(Constructor) called.
2021-11-16 12:03:54.956 [DEBUG] [rnal.bridge.slip.SCrunProductCommand] - SCgetProduct(): starting sessions with the random number 3024.
2021-11-16 12:03:54.963 [DEBUG] [idge.slip.SCrunProductIdentification] - SCrunProductIdentification(Constructor) called.
2021-11-16 12:03:54.965 [DEBUG] [idge.slip.SCrunProductIdentification] - SCrunProductIdentification(): starting sessions with the random number 681.
2021-11-16 12:03:54.970 [DEBUG] [elux.internal.bridge.slip.SCrunScene] - SCrunScene(Constructor) called.
2021-11-16 12:03:54.971 [DEBUG] [elux.internal.bridge.slip.SCrunScene] - SCrunScene(): starting sessions with the random number 1610.
2021-11-16 12:03:54.978 [DEBUG] [internal.bridge.slip.SCsetLimitation] - SCsetLimitation(Constructor) called.
2021-11-16 12:03:54.980 [DEBUG] [internal.bridge.slip.SCsetLimitation] - SCsetLimitation(): starting sessions with the random number 3610.
2021-11-16 12:03:55.005 [DEBUG] [.internal.handler.VeluxBridgeHandler] - Creating a VeluxBridgeHandler for thing 'velux:klf200:velux_klf200'.
2021-11-16 12:03:55.036 [DEBUG] [.binding.velux.internal.VeluxBinding] - veluxConfig[protocol=slip,ipAddress=192.168.2.124,tcpPort=51200,password=**********,timeoutMsecs=3000,retries=5,refreshMsecs=10000,isBulkRetrievalEnabled=true,isSequentialEnforced=false,isProtocolTraceEnabled=false]
2021-11-16 12:03:55.060 [DEBUG] [.velux.internal.handler.VeluxHandler] - initialize(): Initializing thing velux:rollershutter:velux_klf200:velux_est_tent in combination with bridge org.openhab.core.thing.internal.BridgeImpl@1af0df5c.
2021-11-16 12:03:55.082 [WARN ] [ab.binding.mqtt.generic.ChannelState] - Command 'Offline' not supported by type 'OnOffValue': No enum constant org.openhab.core.library.types.OnOffType.Offline
2021-11-16 12:03:55.751 [DEBUG] [.internal.handler.VeluxBridgeHandler] - bridgeParamsUpdated() called.
2021-11-16 12:03:55.752 [DEBUG] [.internal.handler.VeluxBridgeHandler] - bridgeParamsUpdated(): choosing SLIP as communication method.
2021-11-16 12:03:55.754 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.2.124] GW_PASSWORD_ENTER_REQ => started => Thread[OH-thingHandler-5,5,main] 
2021-11-16 12:03:55.760 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.2.124] GW_PASSWORD_ENTER_REQ => GW_PASSWORD_ENTER_REQ => sending data length => 32
2021-11-16 12:03:55.761 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - close() called.
2021-11-16 12:03:55.762 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
2021-11-16 12:03:55.772 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.2.124: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2021-11-16 12:03:58.773 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - close() called.
2021-11-16 12:03:58.774 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
2021-11-16 12:03:58.777 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.2.124: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2021-11-16 12:04:03.588 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): creating a thing of type binding.
2021-11-16 12:04:03.591 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): registering new thing DiscoveryResult [thingUID=velux:binding:org_openhab_binding_velux, properties={bundleVersion=org.openhab.binding.velux_3.2.0.M4 [376]}, representationProperty=bundleVersion, flag=NEW, label=Velux Binding Information Element, bridgeUID=null, ttl=-1, timestamp=1637060643590].
2021-11-16 12:04:03.599 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): VeluxDiscoveryService cannot proceed due to missing Velux bridge(s).
2021-11-16 12:04:03.965 [DEBUG] [.velux.internal.handler.VeluxHandler] - initialize(): Initializing thing velux:rollershutter:velux_klf200:velux_ovest_rollershutter in combination with bridge org.openhab.core.thing.internal.BridgeImpl@1af0df5c.
2021-11-16 12:04:03.974 [DEBUG] [.velux.internal.handler.VeluxHandler] - initialize(): Initializing thing velux:rollershutter:velux_klf200:velux_est_rollershutter in combination with bridge org.openhab.core.thing.internal.BridgeImpl@1af0df5c.
2021-11-16 12:04:03.982 [DEBUG] [.velux.internal.handler.VeluxHandler] - initialize(): Initializing thing velux:scene:velux_klf200:velux_scene_allclosed in combination with bridge org.openhab.core.thing.internal.BridgeImpl@1af0df5c.
2021-11-16 12:04:03.992 [DEBUG] [.velux.internal.handler.VeluxHandler] - initialize(): Initializing thing velux:window:velux_klf200:velux_est_window in combination with bridge org.openhab.core.thing.internal.BridgeImpl@1af0df5c.
2021-11-16 12:04:03.999 [DEBUG] [.velux.internal.handler.VeluxHandler] - initialize(): Initializing thing velux:rollershutter:velux_klf200:velux_ovest_tent in combination with bridge org.openhab.core.thing.internal.BridgeImpl@1af0df5c.
2021-11-16 12:04:04.007 [DEBUG] [.velux.internal.handler.VeluxHandler] - initialize(): Initializing thing velux:window:velux_klf200:velux_ovest_window in combination with bridge org.openhab.core.thing.internal.BridgeImpl@1af0df5c.
2021-11-16 12:04:04.778 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - close() called.
2021-11-16 12:04:04.779 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
2021-11-16 12:04:04.783 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.2.124: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2021-11-16 12:04:16.784 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - close() called.
2021-11-16 12:04:16.787 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
2021-11-16 12:04:16.790 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.2.124: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2021-11-16 12:04:17.346 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-11-16 12:04:40.791 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - close() called.
2021-11-16 12:04:40.792 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
2021-11-16 12:04:40.796 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.2.124: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2021-11-16 12:04:57.952 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-11-16 12:05:28.797 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - close() called.
2021-11-16 12:05:28.798 [DEBUG] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
2021-11-16 12:05:28.803 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.2.124: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).

Anyone having same issue?
thx

Yes exactly the same problem! I did not do an upgrade I am still on M3 and the binding is M3 I just did a reboot and now it won’t login.

update:

I managed to get it back up again - I think the klf200 sometimes gets stuck. I had to unplug it for a while then restarted everything including the openhab server and it finally came back online.

HI Julian, I tried many times to unplug and re-plug with no success. I think the key thing here is unplug it for a while, that works. Thanks!

Yeah. It is the infamous zombie socket problem. You will find more on this in the main Velux thread. But the short answer is to DISABLE the Velux hub things a few minutes before shutting down (or upgrading) your system. And the ENABLE them again once everything else is up and running.