Help understanding the log

[xibriz]

I have a bug and need help reading the logs.

Device 12 = Z-UNO
Device 13 = Fibaro FGS-222 Double Relay

If -> Then rule #1: if 13 ON then 12 OFF
If -> Then rule #2: if 13 OFF then 12 ON

Problem: Seems like Device 12 turns ON and OFF more than it should creating some sort of toggle situation.

...
[2017-01-17 05:37:07.113] [D] [zway] RECEIVED: ( 01 09 00 04 00 0D 03 25 03 00 DA )
[2017-01-17 05:37:07.113] [D] [zway] SENT ACK
[2017-01-17 05:37:07.113] [D] [zway] SETDATA controller.data.incomingPacket.nodeId = 13 (0x0000000d)
[2017-01-17 05:37:07.114] [D] [zway] SETDATA controller.data.incomingPacket.frameType = "singlecast"
[2017-01-17 05:37:07.114] [D] [zway] SETDATA controller.data.incomingPacket = **********
[2017-01-17 05:37:07.114] [D] [zway] SETDATA devices.13.data.lastReceived = 0 (0x00000000)
[2017-01-17 05:37:07.114] [D] [zway] SETDATA devices.13.instances.0.commandClasses.37.data.level = False
[2017-01-17 05:37:07.128] [core] Notification: device-info (device-OnOff): {"dev":"Verisure Bridge Fibaro","l":"off"}
[2017-01-17 05:37:07.135] [core] --- ZWayVDev_zway_12-1-37 performCommand processing: {"0":"on"}
...
[2017-01-17 05:37:07.591] [D] [zway] RECEIVED: ( 01 09 00 04 00 0D 03 25 03 00 DA )
[2017-01-17 05:37:07.591] [D] [zway] SENT ACK
[2017-01-17 05:37:07.591] [D] [zway] SETDATA controller.data.incomingPacket.nodeId = 13 (0x0000000d)
[2017-01-17 05:37:07.591] [D] [zway] SETDATA controller.data.incomingPacket.frameType = "singlecast"
[2017-01-17 05:37:07.591] [D] [zway] SETDATA controller.data.incomingPacket = **********
[2017-01-17 05:37:07.592] [D] [zway] SETDATA devices.13.data.lastReceived = 0 (0x00000000)
[2017-01-17 05:37:07.592] [D] [zway] SETDATA devices.13.instances.0.commandClasses.37.data.level = False
[2017-01-17 05:37:07.602] [core] --- ZWayVDev_zway_12-1-37 performCommand processing: {"0":"off"}
...

[PoltoS]

Looks like 12 and 13 are binded via some Z-Way module. Isn't it?

[xibriz]

I have a ZWC-K8 wall controller that is assosiated with device 12.

But that controller is battery operated and in deep sleep most of the time.

I think it might be a bug in the "If > Then" app like discussed by IceCreamMan

I changed the event to use the "Logical Rule" app and everything seems to be working as expected.

[xibriz]

I have checked todays log, and it seems like the incomingPacket from Device 13 only comes once (as expected).

Maybe there are some double parsing of the incomingPacket when using the "IF THEN" app?

[2017-01-18 06:06:45.240] [D] [zway] RECEIVED: ( 01 09 00 04 00 0D 03 25 03 00 DA )
[2017-01-18 06:06:45.240] [D] [zway] SENT ACK
[2017-01-18 06:06:45.241] [D] [zway] SETDATA controller.data.incomingPacket.nodeId = 13 (0x0000000d)
[2017-01-18 06:06:45.241] [D] [zway] SETDATA controller.data.incomingPacket.frameType = "singlecast"
[2017-01-18 06:06:45.241] [D] [zway] SETDATA controller.data.incomingPacket = **********
[2017-01-18 06:06:45.241] [D] [zway] SETDATA devices.13.data.lastReceived = 0 (0x00000000)
[2017-01-18 06:06:45.241] [D] [zway] SETDATA devices.13.instances.0.commandClasses.37.data.level = False
[2017-01-18 06:06:45.252] [core] Notification: device-info (device-OnOff): {"dev":"ZW Bridge Fibaro (13.0)","l":"off"}
[2017-01-18 06:06:45.260] [core] --- ZWayVDev_zway_12-0-37 performCommand processing: {"0":"on"}