Vera Plug not switching even when the vera page says transmit OK

Using the latest snapshot of OH2 from cloudbees i have the mios binding setup and items populated. when I cant do is toggle a zwave device on the vera. i see the following in the logs

I see the SUCCESS then I get a NONE and nothing happens i DO get this on the VeraPlus with latest UI7 device page “SUCCESS! Transmit was OK” under the device but it never toggles the plug.

where do I start?

Thanks

’ loop '6104’
2016-12-30 11:52:32.001 [DEBUG] [ding.mios.internal.MiosUnitConnector] - processResponse: success! loadTime=1483047902, dataVersion=47705463 devices(1) scenes(0) rooms(0) sections(0)
2016-12-30 11:52:32.001 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Incr) Updating ‘UpstairsHallPlugDeviceStatus {mios=“unit:C5PO,device:50361/status”}’ to 'SUCCESS’
2016-12-30 11:52:32.002 [DEBUG] [ding.mios.internal.MiosUnitConnector] - run: URI Built was ‘http://10.1.1.2:3480/data_request?id=status2&LoadTime=1483047902&DataVersion=47705463&Timeout=40&MinimumDelay=0’ loop '6105’
2016-12-30 11:52:32.007 [DEBUG] [ding.mios.internal.MiosUnitConnector] - processResponse: success! loadTime=1483047902, dataVersion=47705463 devices(1) scenes(0) rooms(0) sections(0)
2016-12-30 11:52:32.007 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Incr) Updating ‘UpstairsHallPlugDeviceStatus {mios=“unit:C5PO,device:50361/status”}’ to 'SUCCESS’
2016-12-30 11:52:32.008 [DEBUG] [ding.mios.internal.MiosUnitConnector] - run: URI Built was ‘http://10.1.1.2:3480/data_request?id=status2&LoadTime=1483047902&DataVersion=47705463&Timeout=40&MinimumDelay=0’ loop '6075’
2016-12-30 11:52:49.269 [DEBUG] [ding.mios.internal.MiosUnitConnector] - processResponse: success! loadTime=1483047902, dataVersion=47705464 devices(2) scenes(0) rooms(0) sections(0)
2016-12-30 11:52:49.269 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Incr) Updating ‘LivingroomplugLastPollSuccess {mios=“unit:C5PO,device:50363/service/urn:micasaverde-com:serviceId:ZWaveNetwork1/LastPollSuccess”}’ to '2016-12-30T11:52:49’
2016-12-30 11:52:49.269 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Incr) Updating ‘LivingroomplugId {mios=“unit:C5PO,device:50363/id”}’ to '50363’
2016-12-30 11:52:49.269 [DEBUG] [ding.mios.internal.MiosUnitConnector] - run: URI Built was ‘http://10.1.1.2:3480/data_request?id=status2&LoadTime=1483047902&DataVersion=47705464&Timeout=40&MinimumDelay=0’ loop '6106’
2016-12-30 11:52:49.274 [DEBUG] [ding.mios.internal.MiosUnitConnector] - processResponse: success! loadTime=1483047902, dataVersion=47705464 devices(2) scenes(0) rooms(0) sections(0)
2016-12-30 11:52:49.274 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Incr) Updating ‘LivingroomplugLastPollSuccess {mios=“unit:C5PO,device:50363/service/urn:micasaverde-com:serviceId:ZWaveNetwork1/LastPollSuccess”}’ to '2016-12-30T11:52:49’
2016-12-30 11:52:49.275 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Incr) Updating ‘LivingroomplugId {mios=“unit:C5PO,device:50363/id”}’ to '50363’
2016-12-30 11:52:49.275 [DEBUG] [ding.mios.internal.MiosUnitConnector] - run: URI Built was ‘http://10.1.1.2:3480/data_request?id=status2&LoadTime=1483047902&DataVersion=47705464&Timeout=40&MinimumDelay=0’ loop '6076’
2016-12-30 11:53:03.126 [DEBUG] [ding.mios.internal.MiosUnitConnector] - processResponse: success! loadTime=1483047902, dataVersion=47705466 devices(1) scenes(0) rooms(0) sections(0)
2016-12-30 11:53:03.127 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Incr) Updating ‘UpstairsHallPlugDeviceStatus {mios=“unit:C5PO,device:50361/status”}’ to 'NONE’
2016-12-30 11:53:03.128 [DEBUG] [ding.mios.internal.MiosUnitConnector] - run: URI Built was ‘http://10.1.1.2:3480/data_request?id=status2&LoadTime=1483047902&DataVersion=47705466&Timeout=40&MinimumDelay=0’ loop '6077’
2016-12-30 11:53:03.129 [DEBUG] [ding.mios.internal.MiosUnitConnector] - processResponse: success! loadTime=1483047902, dataVersion=47705466 devices(1) scenes(0) rooms(0) sections(0)
2016-12-30 11:53:03.129 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Incr) Updating ‘UpstairsHallPlugDeviceStatus {mios=“unit:C5PO,device:50361/status”}’ to 'NONE’
2016-12-30 11:53:03.130 [DEBUG] [ding.mios.internal.MiosUnitConnector] - run: URI Built was ‘http://10.1.1.2:3480/data_request?id=status2&LoadTime=1483047902&DataVersion=47705466&Timeout=40&MinimumDelay=0’ loop ‘6107’

Not sure it’s related, but do you have the “Eclipse SmartHome Map Transformation Service” listed as Active when you issue bundle:list from an openhab> prompt?

Yes

204 | Active | 75 | 0.9.0.b2 | Eclipse SmartHome Map Transformation Service

and
214 | Active | 80 | 1.9.0.b5 | openHAB MiOS Action
218 | Active | 80 | 1.9.0.b5 | openHAB MiOS Binding

Could it be related to a Vera firmware change? Any way to run an older firmware to see if there is a difference? Or load up openHAB 1.8.3 to see if it’s related to OH2? @guessed might have better suggestions.

@p0lar
I’ll need a more complete log, including the startup logs, and a copy of the generated Items file. The first thing that jumps out is that there appear to be two main-threads running.

ie. loop 6076... and loop 6106

Not sure how it got into that state, since there should only be a single definition of the C5PO deployment.

You should increase the logging level to TRACE, for this particular Binding. I log a few more things at that level which can be handy to see.

In most of the cases where I’ve seen data not picked up, it’s been a typo/syntax error in the Items file, so I’ll need the whole file to double-check if an error crept in during the Item Generation process. This will be more more obvious when running in TRACE mode (I incoming log data that isn’t captured by an Item definition)

It’s been a while since I ran the 1.9 version of the Binding under OH2. It looks like OH2 has cutover to using Text-based config files now (vs Binary/MapDB) so I’ll give it another go in the New Yr.

Ill get these logs. is there a 2.x version ? i installed it from the Paper UI gui. they use the bin/map DB’s for other configs that are not had tweakable. I think I read that somewhere