UZB Controller keeps going offline

Ontrackx · October 23, 2020, 6:12am

Hello, my UZB keeps going offline
I can se from the console zwave is restarting often. I think i have tried everything in te community forum. Anyone have any pointers? I removed restdocs and the http binding. But still it goes offline util I restart openhab. Oh versiin 2.5.8

Ontrackx · October 23, 2020, 6:47am

sihui · October 23, 2020, 6:59am

Are you using the Amazon Echo Control binding? This could also cause your problem. Amazon changed the login so you need to update your Amazon Echo Control binding.

Ontrackx · October 23, 2020, 8:07am

No the amazon eco binding seems fine. But can that bining interfere with the zwave binding?

Bruce_Osborne · October 23, 2020, 8:16am

If you upgraded from 2.4 or earlier and have invalid addons defined, all addons restart every minute trying to load the invalid one. Restdocs is a common culprit for those who did not read about breaking changes in the 3.5 Release Notes.

OpenHAB stores that information in addons.config in the userdata tree. Stop OpenHAB before modifying.

Ontrackx · October 23, 2020, 8:31am

:org.apache.felix.configadmin.revision:=L"36"

action=""
binding=“zwave,lgwebos,miio,amazonechocontrol,astro,chromecast,hue,network,rfxcom,somfytahoma,spotify,tradfri,wifiled,wol1,netatmo,modbus”
misc=“market,hueemulation,openhabcloud,ruleengine”
package=“expert”
persistence=",mapdb,rrd4j"
service.pid=“org.openhab.addons”
transformation=“map,regex,xslt,exec,javascript,scale,xpath,jsonpath”
ui=“dashboard,classic,basic,paper,habpanel,habmin”
voice=“googletts”

This is how it looks now, should I just try deleting the file itself?

Ontrackx · October 23, 2020, 8:33am

START LEVEL 100 , List Threshold: 50

ID │ State │ Lvl │ Version ────┼────── 19 │ Active │ 80 │ 5.3.1.201602281253 20 │ Active │ 80 │ 21 │ Active │ 80 │ 18.0.0 22 │ Active │ 80 │ 27.1.0.jre 23 │ Active │ 80 │ 1.0.1 24 │ Active │ 80 │ 25 │ Active │ 80 │ 29 │ Active │ 80 │ 3.5.5 31 │ Active │ 80 │ 1.0.0 33 │ Active │ 80 │ 1.1.0.Final 35 │ Active │ 80 │ 2.0.1 36 │ Active │ 80 │ 2.9.2 37 │ Active │ 80 │ 0.5.8 38 │ Active │ 80 │ 40 │ Active │ 80 │ 1.1 41 │ Active │ 80 │ 2.2.0 42 │ Active │ 80 │ 2.6 51 │ Active │ 80 │ 4.2.7 66 │ Active │ 80 │ 1.4.7.1 69 │ Active │ 80 │ 4.14.0 70 │ Active │ 80 │ 4.14.0 71 │ Active │ 80 │ 72 │ Active │ 80 │ 73 │ Active │ 80 │ 74 │ Active │ 80 │ 75 │ Active │ 80 │ 104 │ Active │ 80 105 │ Active │ 80 106 │ Active │ 80 107 │ Active │ 80 108 │ Active │ 80 109 │ Active │ 80 110 │ Active │ 80 111 │ Active │ 80 112 │ Active │ 80 113 │ Active │ 80 114 │ Active │ 80 129 │ Active │ 80 │ 1.9.6 131 │ Active │ 80 │ 7.1.0 132 │ Active │ 80 │ 7.1.0 133 │ Active │ 80 │ 2.5.0 134 │ Active │ 80 │ 2.5.0 135 │ Active │ 80 │ 2.5.0 136 │ Active │ 80 │ 2.5.0 137 │ Active │ 90 │ 2.5.0 138 │ Active │ 80 │ 2.5.0 139 │ Active │ 80 │ 2.5.0 140 │ Active │ 80 │ 2.5.0 141 │ Active │ 80 │ 2.5.0 142 │ Active │ 75 │ 2.5.0 143 │ Active │ 80 │ 2.5.0 144 │ Active │ 80 │ 2.5.0 145 │ Active │ 80 │ 2.5.0 146 │ Active │ 80 │ 2.5.0 147 │ Active │ 80 │ 2.5.0 149 │ Active │ 80 │ 2.5.0 150 │ Active │ 80 │ 2.5.0 151 │ Active │ 80 │ 2.5.0 152 │ Active │ 80 │ 2.5.0 153 │ Active │ 80 │ 2.5.0 154 │ Active │ 80 │ 2.5.0 155 │ Active │ 80 │ 2.5.0 156 │ Active │ 80 │ 2.5.0 157 │ Active │ 80 │ 2.5.0 158 │ Active │ 80 │ 2.5.0 159 │ Active │ 80 │ 2.5.0 160 │ Active │ 80 │ 2.5.0 161 │ Active │ 80 │ 2.5.0 162 │ Active │ 80 │ 2.5.0 163 │ Active │ 80 │ 2.5.0 164 │ Active │ 80 │ 2.5.0 165 │ Active │ 80 │ 2.5.0 166 │ Active │ 80 │ 2.5.0 167 │ Active │ 80 │ 2.5.0 168 │ Active │ 80 │ 2.5.0 169 │ Active │ 80 │ 2.5.0 170 │ Active │ 80 │ 2.5.0 171 │ Active │ 80 │ 2.5.0 172 │ Active │ 80 │ 2.5.0 173 │ Active │ 80 │ 2.5.0 174 │ Active │ 80 │ 2.5.0 175 │ Active │ 80 │ 2.5.0 176 │ Active │ 80 │ 2.5.0 177 │ Active │ 80 │ 2.5.0 178 │ Active │ 80 │ 2.5.0 179 │ Active │ 80 │ 2.5.0 180 │ Active │ 80 │ 2.5.0 181 │ Active │ 80 │ 2.5.0 182 │ Active │ 80 │ 2.5.0 183 │ Active │ 80 │ 2.5.0 184 │ Active │ 80 │ 2.5.0 185 │ Active │ 80 │ 2.5.0 186 │ Active │ 75 │ 2.5.0 187 │ Active │ 80 │ 2.5.0 188 │ Active │ 80 │ 2.5.0 189 │ Active │ 80 │ 2.5.0 190 │ Active │ 80 │ 2.5.0 191 │ Active │ 80 │ 2.5.8 200 │ Active │ 80 │ 1.4.0 201 │ Active │ 80 │ 1.0.3 202 │ Active │ 80 │ 1.0.10 203 │ Active │ 80 204 │ Active │ 80 │ 1.9.2 205 │ Active │ 80 │ 1.9.2 207 │ Active │ 80 │ 4.2.3 208 │ Active │ 80 │ 4.2.3 209 │ Active │ 80 │ 3.1.0.7 210 │ Active │ 80 │ 2.5.0 213 │ Active │ 80 214 │ Active │ 80 │ 2.9.10 215 │ Active │ 80 │ 2.9.10 216 │ Active │ 80 │ 2.9.10 217 │ Active │ 80 │ 2.9.10 218 │ Active │ 80 │ 1.7.0 219 │ Active │ 80 │ 1.42.0 220 │ Active │ 80 │ 0.13.0 221 │ Active │ 80 │ 0.13.0 222 │ Active │ 80 │ 20.0.0 223 │ Active │ 80 │ 1.27.0 224 │ Active │ 80 │ 1.27.0 225 │ Active │ 80 │ 3.15.0.OH2 226 │ Active │ 80 │ 2.3.0 227 │ Active │ 80 │ 1.9.0 228 │ Active │ 80 │ 1.0.0 229 │ Active │ 80 │ 1.0.0 230 │ Active │ 80 │ 1.5.8 231 │ Active │ 80 │ 1.5.8 232 │ Active │ 80 │ 1.5.8 233 │ Active │ 80 │ 1.5.8 234 │ Active │ 80 │ 3.19.0.GA 235 │ Active │ 80 │ 20180813.0.0 236 │ Active │ 80 │ 1.10.0 237 │ Active │ 80 │ 3.2.2 238 │ Active │ 80 │ 3.2.1 239 │ Active │ 80 │ 3.6.0 240 │ Active │ 80 │ 2.4.3 241 │ Active │ 80 │ 1.0.0 242 │ Active │ 80 │ 1.0.0 243 │ Active │ 80 │ 3.8.1.1 244 │ Active │ 80 │ 1.13.0.1 245 │ Active │ 80 │ 2.0.0 246 │ Active │ 80 │ 2.0.0 247 │ Active │ 80 │ 2.0.0 248 │ Active │ 80 │ 2.0.0 249 │ Active │ 80 │ 2.5.2 250 │ Active │ 80 │ 2.5.8 251 │ Active │ 80 │ 2.5.8 252 │ Active │ 80 │ 2.5.8 254 │ Active │ 80 │ 2.5.8 255 │ Active │ 80 │ 2.5.8 256 │ Active │ 80 │ 2.5.8 257 │ Active │ 80 │ 2.5.8 258 │ Active │ 80 │ 2.5.8 259 │ Active │ 80 │ 2.5.8 260 │ Active │ 80 │ 2.5.8 261 │ Active │ 80 │ 2.5.8 262 │ Active │ 80 │ 2.5.8 263 │ Active │ 80 │ 2.5.8 264 │ Active │ 80 │ 2.5.8 265 │ Active │ 80 │ 2.5.8 267 │ Active │ 80 │ 2.5.8 268 │ Active │ 80 │ 2.5.0 269 │ Active │ 80 │ 2.5.0 270 │ Active │ 80 │ 2.5.0 271 │ Active │ 80 │ 2.5.0 272 │ Active │ 80 │ 2.5.0 273 │ Active │ 80 │ 2.5.0 274 │ Active │ 80 │ 2.5.0 275 │ Active │ 80 │ 2.5.0 276 │ Active │ 80 │ 2.5.0 277 │ Active │ 80 │ 2.5.0 278 │ Active │ 80 │ 2.5.0 279 │ Active │ 80 │ 2.5.0 280 │ Active │ 80 │ 2.5.0 281 │ Active │ 80 │ 2.5.8 282 │ Active │ 80 │ 2.5.8 283 │ Active │ 80 │ 2.5.8 284 │ Active │ 80 │ 2.5.8 285 │ Active │ 80 │ 2.5.8 286 │ Active │ 80 │ 2.5.8 287 │ Active │ 80 │ 1.14.0 288 │ Active │ 80 │ 1.14.0 289 │ Active │ 75 │ 2.5.8 290 │ Active │ 75 │ 2.5.8 291 │ Active │ 75 │ 2.5.8 292 │ Active │ 75 │ 2.5.8 293 │ Active │ 75 │ 2.5.8 294 │ Active │ 75 │ 2.5.8 295 │ Active │ 75 │ 2.5.8 296 │ Active │ 75 │ 2.5.8 297 │ Active │ 80 │ 2.5.8 298 │ Active │ 80 │ 2.5.8 299 │ Active │ 80 │ 2.5.8 300 │ Active │ 80 │ 2.5.8 301 │ Active │ 80 │ 2.5.8 302 │ Active │ 80 │ 2.5.8 303 │ Active │ 80 │ 2.5.8 304 │ Active │ 80 │ 2.5.8 305 │ Active │ 80 │ 1.3.3 306 │ Active │ 80 │ 1.23.0 307 │ Active │ 80 308 │ Active │ 80 │ Name
──┼─────┼─────────────────────────┼─────────────────────────────────
│ OSGi JAX-RS Connector
2.8.2.v20180104-1110 │ Gson: Google Json Library for J
│ Guava: Google Core Libraries fo
│ Guava: Google Core Libraries fo
│ Guava InternalFutureFailureAcce
3.0.0.v201312141243 │ Google Guice (No AOP)
4.8.35.v20190528-1517 │ ClassGraph
│ JmDNS
│ Units of Measurement API
│ Bean Validation API
│ javax.ws.rs-api
│ Joda-Time
│ Jollyday
3.2.0.v201101311130 │ ANTLR Runtime
│ Commons Exec
│ Commons IO
│ Commons Lang
│ Apache Karaf :: OSGi Services :
│ Apache ServiceMix :: Bundles ::
│ Apache XBean OSGI Bundle Utilit
│ Apache XBean :: Classpath Resou
2.12.0.v20160420-0247 │ EMF Common
2.12.0.v20160420-0247 │ EMF Ecore
2.11.0.v20160420-0247 │ EMF Change Model
2.12.0.v20160420-0247 │ EMF XML/XMI Persistence
3.10.400.v20190516-1504 │ Common Eclipse Runtime
│ 0.8.0.v20190822-0801 │ org.eclipse.lsp4j
│ 0.8.0.v20190822-0801 │ org.eclipse.lsp4j.jsonrpc
│ 2.19.0.v20190902-0728 │ Xtend Runtime Library
│ 2.19.0.v20190902-0728 │ Xtend Macro Interfaces
│ 2.19.0.v20190902-0737 │ Xtext
│ 2.19.0.v20190902-0952 │ Xtext Common Types
│ 2.19.0.v20190902-0737 │ Xtext IDE Core
│ 2.19.0.v20190902-0737 │ Xtext Utility
│ 2.19.0.v20190902-0952 │ Xbase Model
│ 2.19.0.v20190902-0952 │ Xbase Generic IDE Services
│ 2.19.0.v20190902-0728 │ Xbase Runtime Library
│ MIME streaming extension
│ org.objectweb.asm.commons
│ org.objectweb.asm.tree
│ openHAB Core :: Bundles :: Core
│ openHAB Core :: Bundles :: Audio
│ openHAB Core :: Bundles :: OAut
│ openHAB Core :: Bundles :: Bind
│ openHAB Core :: Bundles :: Boot
│ openHAB Core :: Bundles :: Conf
│ openHAB Core :: Bundles :: Conf
│ openHAB Core :: Bundles :: Conf
│ openHAB Core :: Bundles :: Conf
│ openHAB Core :: Bundles :: Conf
│ openHAB Core :: Bundles :: Ephe
│ openHAB Core :: Bundles :: ID
│ openHAB Core :: Bundles :: Cons
│ openHAB Core :: Bundles :: Cons
│ openHAB Core :: Bundles :: HTTP
│ openHAB Core :: Bundles :: Moni
│ openHAB Core :: Bundles :: Netw
│ openHAB Core :: Bundles :: REST
│ openHAB Core :: Bundles :: Audi
│ openHAB Core :: Bundles :: REST
│ openHAB Core :: Bundles :: REST
│ openHAB Core :: Bundles :: REST
│ openHAB Core :: Bundles :: Site
│ openHAB Core :: Bundles :: SSE
│ openHAB Core :: Bundles :: Voic
│ openHAB Core :: Bundles :: mDNS
│ openHAB Core :: Bundles :: Kara
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Lang
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Mode
│ openHAB Core :: Bundles :: Pers
│ openHAB Core :: Bundles :: Sche
│ openHAB Core :: Bundles :: Sema
│ openHAB Core :: Bundles :: JSON
│ openHAB Core :: Bundles :: Thing
│ openHAB Core :: Bundles :: Thin
│ openHAB Core :: Bundles :: Tran
│ openHAB Core :: Bundles :: UI
│ openHAB Core :: Bundles :: UI I
│ openHAB Core :: Bundles :: Voice
│ openHAB UI :: Bundles :: Dashbo
│ ThreeTen-Extra
│ Units of Measurement Common Lib
│ Units of Measurement Implementa
│ 2.5.7.202007120411 │ openHAB Add-ons :: Bundles :: T
│ Jackson JSON processor
│ Data mapper for Jackson JSON pr
│ Apache HttpClient OSGi bundle
│ Apache HttpCore OSGi bundle
│ Apache ServiceMix :: Bundles ::
│ openHAB Core :: Bundles :: 1.x
│ 1.1.1.201605111122 │ Swagger Provider
│ Jackson-annotations
│ Jackson-core
│ jackson-databind
│ Jackson-dataformat-YAML
│ Google API Common
│ Google API GAX
│ Google Auth Credentials
│ Google Auth OAuth2 HTTP
│ Guava: Google Core Libraries fo
│ Google HTTP Client Library for
│ Jackson Extensions
│ nrjavaserial
│ OK HTTP Client
│ OK Retrofit HTTP Client
│ IO.Socket Engine Client
│ IO.Socket Socket Client
│ swagger-annotations
│ swagger-core
│ swagger-jaxrs
│ swagger-models
│ Javassist
│ JSON in Java
│ Apache Commons Codec
│ Apache Commons Collections
│ Apache Commons Lang
│ Apache Commons Net
│ Apache Commons Pool
│ org.apache.oltu.oauth2.client
│ org.apache.oltu.oauth2.common
│ Apache ServiceMix :: Bundles ::
│ Apache ServiceMix :: Bundles ::
│ Californium (Cf) Core
│ Californium (Cf) Element Connec
│ Californium (Cf) OSGi
│ Scandium (Sc) Core
│ JUPnP Library
│ openHAB Add-ons :: Bundles :: A
│ openHAB Add-ons :: Bundles :: A
│ openHAB Add-ons :: Bundles :: C
│ openHAB Add-ons :: Bundles :: h
│ openHAB Add-ons :: Bundles :: L
│ openHAB Add-ons :: Bundles :: X
│ openHAB Add-ons :: Bundles :: M
│ openHAB Add-ons :: Bundles :: S
│ openHAB Add-ons :: Bundles :: N
│ openHAB Add-ons :: Bundles :: N
│ openHAB Add-ons :: Bundles :: R
│ openHAB Add-ons :: Bundles :: S
│ openHAB Add-ons :: Bundles :: S
│ openHAB Add-ons :: Bundles :: T
│ openHAB Add-ons :: Bundles :: W
│ openHAB Add-ons :: Bundles :: Z
│ openHAB Core :: Bundles :: Auto
│ openHAB Core :: Bundles :: Auto
│ openHAB Core :: Bundles :: Auto
│ openHAB Core :: Bundles :: Auto
│ openHAB Core :: Bundles :: Auto
│ openHAB Core :: Bundles :: Conf
│ openHAB Core :: Bundles :: Conf
│ openHAB Core :: Bundles :: Conf
│ openHAB Core :: Bundles :: Conf
│ openHAB Core :: Bundles :: Seri
│ openHAB Core :: Bundles :: Seri
│ openHAB Core :: Bundles :: Seri
│ openHAB Core :: Bundles :: UPnP
│ openHAB Add-ons :: Bundles :: E
│ openHAB Add-ons :: Bundles :: I
│ openHAB Add-ons :: Bundles :: I
│ openHAB Add-ons :: Bundles :: I
│ openHAB Add-ons :: Bundles :: I
│ openHAB Add-ons :: Bundles :: I
│ openHAB MapDB Persistence Bundle
│ openHAB RRD4j Persistence Bundle
│ openHAB Add-ons :: Bundles :: T
│ openHAB Add-ons :: Bundles :: T
│ openHAB Add-ons :: Bundles :: T
│ openHAB Add-ons :: Bundles :: T
│ openHAB Add-ons :: Bundles :: T
│ openHAB Add-ons :: Bundles :: T
│ openHAB Add-ons :: Bundles :: T
│ openHAB Add-ons :: Bundles :: T
│ openHAB UI :: Bundles :: Basic
│ openHAB UI :: Bundles :: Classi
│ openHAB UI :: Bundles :: HABmin
│ openHAB UI :: Bundles :: HABPan
│ openHAB UI :: Bundles :: Icon S
│ openHAB UI :: Bundles :: Paper
│ openHAB UI :: Bundles :: openHA
│ openHAB Add-ons :: Bundles :: V
│ ThreeTen backport
│ SnakeYAML
│ 0.9.10.v20160429-1435 │ reflections (wrap)
│ 1.5.8.v20160511-1038 │ swagger-jersey2-jaxrs (wrap)

Bruce_Osborne · October 23, 2020, 10:50am

Thanks for posting that. I usually try t o compare with the Breaking Changes listed in the 2.5.0 Release Notes. Has wol1 moved to Legacy Bindings? I do not know but it may be worth checking.

Please do not do that.

I an currently running that controller in OH3 on my test system with no issues.

Ontrackx · October 23, 2020, 10:53am

Should i just move to O3 M1? I have no issues doing that. Somewhat time consuming but no stress. (fresh install with item files copied from oh2)

Bruce_Osborne · October 23, 2020, 11:02am

I would not do that. OH3 is just for testing My personal suggestion on that would be to wait a year for further development and stability fixes. I know others here have a different opinion.

Bruce_Osborne · October 23, 2020, 11:22am

Comparing with the Release Notes, you may want to look at these. Not sure it would cause your issues though.

LGWebOS

The binding parameter 'localIP' has been removed. The binding now uses system defaults for network communication. The thing type parameter 'deviceId' is no longer a parameter, but a property. Parameters 'host' and 'key' have been added.

Somfytahoma Binding

The following channels have been renamed: 'cyclic_button_state' to 'cyclic_button', 'battery_status_state' to 'battery_status' and 'lighting_led_pod_mod_state' to 'lighting_led_pod_mode'

Bruce_Osborne · October 23, 2020, 11:24am

I assume you have restarted OH. Some have had to do that up to 3 times after upgrade.

Other than that, try collecting Z-Wave debug logs as mentioned in the documentation.

Ontrackx · October 23, 2020, 6:05pm

Haha ive just setup my zwave network in fresh oh3 the controller is behaving the same way. But o my OH3 looks gooood.

Can it be a windows/ firmware problem. Ive just upgraded the firmware with zway.

Bruce_Osborne · October 23, 2020, 6:32pm

I have not seen any firmware upgrades for the HUSBZB-1 controller.

Ontrackx · October 23, 2020, 6:36pm

Role in Network

Node Id:	1
Home Id:	0xeca42bd5
Primary Role:	Yes
Primary Capability:	Yes
SUC/SIS in network:	1 (SIS)

Hardware

Vendor:	Z-Wave.Me
Vendors Product ID:	1024 / 1
Z-Wave Chip:	ZW0500

Firmware

Library Type:	Static Controller
SDK Version:	6.51.09
Serial API Version:	05.07

Bruce_Osborne · October 23, 2020, 6:40pm

Sorry I misread what controller you have.

Ontrackx · October 23, 2020, 6:42pm

edit*

chris · October 23, 2020, 6:43pm

I would suggest to check the logs as that should help understand the problem. The binding docs describe what to do.

Ontrackx · October 23, 2020, 6:58pm

20:55:37.979 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 919: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 248
20:55:39.979 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 28: TID 919: Timeout at state WAIT_RESPONSE. 3 retries remaining.
20:55:39.980 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - Aborting Transaction!
20:55:39.990 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 919: Transaction ABORTED
20:55:39.991 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA
20:55:39.991 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA
20:55:39.992 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:55:39.992 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:55:51.990 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 28: TID 919: Timeout at state ABORTED. 3 retries remaining.
20:55:51.992 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 919: Transaction is current transaction, so clearing!!!
20:55:51.993 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 919: Transaction CANCELLED
20:55:51.993 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
20:55:51.994 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:919 CANCELLED
20:55:51.995 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:55:51.995 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 919: Transaction event listener: DONE: CANCELLED ->
20:55:51.996 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 28: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@60dedad6
20:55:51.996 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 28: Node Init transaction completed with response COMPLETE
20:55:51.997 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: VERSION - checking COMMAND_CLASS_ASSOCIATION, version is 0
20:55:51.998 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 28: Node advancer: VERSION - queued COMMAND_CLASS_ASSOCIATION
20:55:51.998 [DEBUG] [commandclass.ZWaveVersionCommandClass] - NODE 28: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_ASSOCIATION
20:55:51.999 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 28: ZWaveCommandClassTransactionPayload - send to node
20:55:51.999 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 28: SECURITY NOT required on COMMAND_CLASS_VERSION
20:55:52.000 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 28: Command Class COMMAND_CLASS_VERSION is NOT required to be secured
20:55:52.001 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 28: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@7cd03cf5
20:55:52.001 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 28: Adding to device queue
20:55:52.002 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 28: Added 920 to queue - size 3
20:55:52.002 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:55:52.003 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 1C 03 86 13 85 25 F9 35
20:55:52.004 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 28: Sending REQUEST Message = 01 0A 00 13 1C 03 86 13 85 25 F9 35
20:55:52.208 [WARN ] [ding.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
20:55:52.210 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:serial_zstick:16954503’ changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Controller is offline
20:55:52.210 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Controller status changed to OFFLINE.
20:55:52.211 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller status changed to OFFLINE.
20:55:52.211 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 6: Controller status changed to OFFLINE.
20:55:52.211 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Controller status changed to OFFLINE.
20:55:52.211 [DEBUG] [.zwave.handler.ZWaveControllerHandler] - Stopping ZWave network
20:55:52.210 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Controller status changed to OFFLINE.
20:55:52.214 [DEBUG] [ave.internal.protocol.ZWaveController] - Shutting down ZWave controller
20:55:52.214 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Controller is not online.
20:55:52.214 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 6: Controller is not online.
20:55:52.213 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller is not online.
20:55:52.212 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Controller is not online.
20:55:52.212 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node8’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.217 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Controller status changed to OFFLINE.
20:55:52.217 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Controller status changed to OFFLINE.
20:55:52.216 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 14: Controller status changed to OFFLINE.
20:55:52.216 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 13: Controller status changed to OFFLINE.
20:55:52.220 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 14: Controller is not online.
20:55:52.216 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Shutting down transaction manager
20:55:52.215 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 9: Controller is not online.
20:55:52.221 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Exiting ZWave Receive Thread
20:55:52.220 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Controller status changed to OFFLINE.
20:55:52.220 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 13: Controller is not online.
20:55:52.219 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Controller is not online.
20:55:52.218 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Controller is not online.
20:55:52.218 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node5’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.224 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Controller status changed to OFFLINE.
20:55:52.223 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Controller status changed to OFFLINE.
20:55:52.223 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Controller status changed to OFFLINE.
20:55:52.223 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Controller is not online.
20:55:52.222 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Controller status changed to OFFLINE.
20:55:52.226 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 11: Controller status changed to OFFLINE.
20:55:52.226 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Controller is not online.
20:55:52.226 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 10: Controller is not online.
20:55:52.225 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Controller is not online.
20:55:52.224 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node6’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.229 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 19: Controller status changed to OFFLINE.
20:55:52.228 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Controller status changed to OFFLINE.
20:55:52.228 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 18: Controller status changed to OFFLINE.
20:55:52.227 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 11: Controller is not online.
20:55:52.227 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Controller is not online.
20:55:52.271 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 24: Controller status changed to OFFLINE.
20:55:52.271 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 18: Controller is not online.
20:55:52.271 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 17: Controller is not online.
20:55:52.270 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 19: Controller is not online.
20:55:52.270 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node7’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.272 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 20: Controller status changed to OFFLINE.
20:55:52.272 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 21: Controller status changed to OFFLINE.
20:55:52.271 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 26: Controller status changed to OFFLINE.
20:55:52.271 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 24: Controller is not online.
20:55:52.271 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 27: Controller status changed to OFFLINE.
20:55:52.273 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 23: Controller status changed to OFFLINE.
20:55:52.272 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 26: Controller is not online.
20:55:52.272 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 21: Controller is not online.
20:55:52.272 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 20: Controller is not online.
20:55:52.272 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node9’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.273 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Controller status changed to OFFLINE.
20:55:52.273 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 28: Controller status changed to OFFLINE.
20:55:52.273 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 29: Controller status changed to OFFLINE.
20:55:52.273 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 23: Controller is not online.
20:55:52.273 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 27: Controller is not online.
20:55:52.274 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 37: Controller status changed to OFFLINE.
20:55:52.274 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 29: Controller is not online.
20:55:52.274 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 28: Controller is not online.
20:55:52.274 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 35: Controller is not online.
20:55:52.274 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node3’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.275 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 31: Controller status changed to OFFLINE.
20:55:52.275 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 30: Controller status changed to OFFLINE.
20:55:52.275 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 33: Controller status changed to OFFLINE.
20:55:52.276 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 30: Controller is not online.
20:55:52.275 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 37: Controller is not online.
20:55:52.274 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 32: Controller status changed to OFFLINE.
20:55:52.276 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Controller status changed to OFFLINE.
20:55:52.276 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 25: Controller status changed to OFFLINE.
20:55:52.276 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 33: Controller is not online.
20:55:52.275 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 31: Controller is not online.
20:55:52.275 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node4’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.277 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 22: Controller status changed to OFFLINE.
20:55:52.277 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 25: Controller is not online.
20:55:52.277 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 36: Controller is not online.
20:55:52.276 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 32: Controller is not online.
20:55:52.277 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 22: Controller is not online.
20:55:52.277 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node14’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.278 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node13’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.279 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node2’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.279 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node12’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.279 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node10’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.280 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node15’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.280 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node16’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.280 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node11’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.281 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node19’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.281 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node17’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.281 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node18’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.282 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node24’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.282 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node20’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.282 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node21’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.283 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node26’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.283 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node27’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.283 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node23’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.284 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node35’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.284 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node28’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.285 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node29’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.285 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node37’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.285 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node31’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.286 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node30’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.286 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node33’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.286 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node32’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.287 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node36’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.287 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node25’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:52.287 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:device:16954503:node22’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
20:55:57.225 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWave Transaction manager failed to terminate properly, forcing shutdown
20:55:57.227 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction manager shutdown
20:55:57.228 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 25: Node Init transaction completed with response TIMEOUT_WAITING_FOR_CONTROLLER
20:55:57.228 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 28: Node Init transaction completed with response null
20:55:57.233 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 25: Skipping initialization thread, process stopped by controller
20:55:57.233 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 28: Skipping initialization thread, process stopped by controller
20:55:57.228 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 31: sendTransaction exception
java.util.concurrent.ExecutionException: java.lang.InterruptedException
at java.util.concurrent.FutureTask.report(FutureTask.java:122) ~[?:?]
at java.util.concurrent.FutureTask.get(FutureTask.java:191) ~[?:?]
at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1205) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:442) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
Caused by: java.lang.InterruptedException
at java.lang.Object.wait(Native Method) ~[?:?]
at java.lang.Object.wait(Object.java:328) ~[?:?]
at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter.call(ZWaveTransactionManager.java:1134) ~[?:?]
at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter.call(ZWaveTransactionManager.java:1) ~[?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
at java.lang.Thread.run(Thread.java:834) ~[?:?]
20:55:57.228 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 36: sendTransaction exception
java.util.concurrent.ExecutionException: java.lang.InterruptedException
at java.util.concurrent.FutureTask.report(FutureTask.java:122) ~[?:?]
at java.util.concurrent.FutureTask.get(FutureTask.java:191) ~[?:?]
at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1205) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:442) [bundleFile:?]
at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
Caused by: java.lang.InterruptedException
at java.lang.Object.wait(Native Method) ~[?:?]
at java.lang.Object.wait(Object.java:328) ~[?:?]
at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter.call(ZWaveTransactionManager.java:1134) ~[?:?]
at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter.call(ZWaveTransactionManager.java:1) ~[?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
at java.lang.Thread.run(Thread.java:834) ~[?:?]
20:55:57.237 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 31: Node Init transaction completed with response null
20:55:57.239 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init response (0) null
20:55:57.240 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 31: Skipping initialization thread, process stopped by controller
20:55:57.241 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Node Init transaction completed with response null
20:55:57.303 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 36: Skipping initialization thread, process stopped by controller
20:55:57.303 [DEBUG] [ave.internal.protocol.ZWaveController] - ZWave controller shutdown
20:55:57.303 [DEBUG] [.zwave.handler.ZWaveControllerHandler] - ZWave network stopped
20:55:57.303 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Disposing receive thread
20:55:57.484 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Stopped ZWave thread: Receive
20:55:57.484 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Receive thread dispose
20:55:57.484 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Disposing serial connection
20:55:58.286 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Serial connection disposed
20:55:58.286 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 920: Transaction ABORTED
20:56:03.632 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Connecting to serial port ‘COM5’
20:56:03.650 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Starting receive thread
20:56:03.650 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Serial port is initialized
20:56:03.650 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Starting ZWave thread: Receive
20:56:03.650 [DEBUG] [.zwave.handler.ZWaveControllerHandler] - Initialising ZWave controller
20:56:03.651 [INFO ] [ave.internal.protocol.ZWaveController] - Starting ZWave controller
20:56:03.651 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:56:03.651 [INFO ] [ave.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
20:56:03.651 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:56:03.651 [DEBUG] [ave.internal.protocol.ZWaveController] - Event listener added.
20:56:03.652 [DEBUG] [ave.internal.protocol.ZWaveController] - Event listener added.
20:56:03.652 [DEBUG] [.zwave.handler.ZWaveControllerHandler] - Scheduling network mesh heal for 6 hours time.
20:56:04.821 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x10.
20:56:04.822 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
20:56:04.833 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x04.
20:56:04.833 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
20:56:04.834 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x12.
20:56:04.834 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x0A.
20:56:04.834 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x60.
20:56:04.835 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x0D.
20:56:04.836 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x04.
20:56:05.282 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Timeout - Sending NAK
20:56:06.666 [DEBUG] [l.ZWaveController$InitializeDelayTask] - Initialising network
20:56:06.666 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 921 to queue - size 1
20:56:06.666 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:56:06.666 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 15 E9
20:56:06.666 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 15 E9
20:56:06.666 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:56:06.666 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 921: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:56:06.682 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 922 to queue - size 1
20:56:06.682 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:56:06.683 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 923 to queue - size 2
20:56:06.683 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:56:06.684 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 924 to queue - size 3
20:56:06.685 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:56:06.685 [DEBUG] [erialmessage.GetSucNodeIdMessageClass] - Get SUC NodeID
20:56:06.686 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 925 to queue - size 4
20:56:06.686 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:56:08.697 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 255: TID 921: Timeout at state WAIT_RESPONSE. 3 retries remaining.
20:56:08.699 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 921: Transaction is current transaction, so clearing!!!
20:56:08.700 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 921: Transaction CANCELLED
20:56:08.700 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:921 CANCELLED
20:56:08.701 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:56:08.702 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 20 DC
20:56:08.703 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 20 DC
20:56:08.704 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:56:08.704 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 922: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:56:09.280 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
20:56:09.281 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
20:56:09.291 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
20:56:09.291 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
20:56:09.292 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 922: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:56:09.292 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
20:56:09.293 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Holdoff Timer started…
20:56:09.294 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 922: Resetting transaction
20:56:09.294 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 12 0A 60 0D 04 01 31 05 01 22 00 AD 21
20:56:09.294 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:922 WAIT_RESPONSE
20:56:09.295 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 60 0D 04 01 31 05 01 22 00 AD
20:56:09.295 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 922 to queue - size 4
20:56:09.296 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
20:56:09.296 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 F5 01 1B 81 85
20:56:09.297 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
20:56:09.298 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Request[0], dest=1, callback=245, payload=F5 01 1B 81
20:56:09.298 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:56:09.299 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 60 0D 04 01 31 05 01 22 00 AD
20:56:09.299 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 23 06 31 05 04 22 35 22 D7
20:56:09.299 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:56:09.300 [WARN ] [rnal.protocol.ZWaveTransactionManager] - NODE 18: Not initialized (ie node unknown), ignoring message.
20:56:09.300 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=35, callback=0, payload=00 23 06 31 05 04 22 35 22
20:56:09.301 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=245, payload=F5 01 1B 81
20:56:09.302 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:56:09.334 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
20:56:09.334 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
20:56:09.334 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=245, payload=F5 01 1B 81
20:56:09.334 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=35, callback=0, payload=00 23 06 31 05 04 22 35 22
20:56:09.334 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:56:09.334 [WARN ] [rnal.protocol.ZWaveTransactionManager] - NODE 35: Not initialized (ie node unknown), ignoring message.
20:56:09.334 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:56:09.334 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
20:56:09.355 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 04 00 15 12 60 0D 01 01 32 02 21 24 00 00 05 D3 00 00 00 00 00 00 6A
20:56:09.355 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=21, callback=0, payload=00 15 12 60 0D 01 01 32 02 21 24 00 00 05 D3 00 00 00 00 00 00
20:56:09.355 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=21, callback=0, payload=00 15 12 60 0D 01 01 32 02 21 24 00 00 05 D3 00 00 00 00 00 00
20:56:09.355 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:56:09.355 [WARN ] [rnal.protocol.ZWaveTransactionManager] - NODE 21: Not initialized (ie node unknown), ignoring message.
20:56:09.356 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:56:09.356 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
20:56:09.452 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 02 0E 32 02 21 44 00 00 18 84 00 00 00 00 00 00 2A
20:56:09.452 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 0E 32 02 21 44 00 00 18 84 00 00 00 00 00 00
20:56:09.452 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 0E 32 02 21 44 00 00 18 84 00 00 00 00 00 00
20:56:09.452 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
20:56:09.452 [WARN ] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Not initialized (ie node unknown), ignoring message.
20:56:09.452 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:56:09.452 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
20:56:09.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:56:09.551 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 20 DC
20:56:09.551 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 20 DC
20:56:09.551 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:56:09.551 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:56:09.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 922: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:56:09.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:56:09.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:56:09.551 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 01 20 EC A4 2B D5 01 61
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 922: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=MemoryGetId[32], type=Response[1], dest=255, callback=0, payload=EC A4 2B D5 01
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=MemoryGetId[32], type=Response[1], dest=255, callback=0, payload=EC A4 2B D5 01
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 922: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 922: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:56:09.566 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=MemoryGetId[32], type=Response[1], dest=255, callback=0, payload=EC A4 2B D5 01
20:56:09.566 [DEBUG] [serialmessage.MemoryGetIdMessageClass] - Got MessageMemoryGetId response. Home id = 0xeca42bd5, Controller Node id = 1
20:56:09.566 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 922: Transaction COMPLETED
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 15ms
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: TID 922: Transaction completed
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:922 DONE
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
20:56:09.566 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 07 FB
20:56:09.566 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 07 FB
20:56:09.566 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 923: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:56:09.566 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 923: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
20:56:09.566 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 2B 01 07 05 07 01 15 04 00 00 01 FE 83 FF 88 CF 1F 00 00 FB 9F 7D A0 67 00 80 80 00 80 86 00 00 00 E8 73 00 00 0E 00 00 40 1A 00 0C
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
20:56:09.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SerialApiGetCapabilities[7], type=Response[1], dest=255, callback=0, payload=05 07 01 15 04 00 00 01 FE 83 FF 88 CF 1F 00 00 FB 9F 7D A0 67 00 80 80 00 80 86 00 00 00 E8 73 00 00 0E 00 00 40 1A 00
20:56:09.582 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SerialApiGetCapabilities[7], type=Response[1], dest=255, callback=0, payload=05 07 01 15 04 00 00 01 FE 83 FF 88 CF 1F 00 00 FB 9F 7D A0 67 00 80 80 00 80 86 00 00 00 E8 73 00 00 0E 00 00 40 1A 00
20:56:09.619 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 923: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0

Bruce_Osborne · October 23, 2020, 7:28pm

Debug log viewer should help.