Fibaro FGFS101 500 Series

Discussions about existing Z-Wave device and their usage with Z-Way/Z-Cloud/Z-Box
Post Reply
enco.josh
Posts: 35
Joined: 10 Mar 2014 22:10

Fibaro FGFS101 500 Series

Post by enco.josh » 19 May 2016 21:49

I am testing one of the new Fibaro FGFS-101 500 Series and the isAwake property is not updating when I wake the device by pressing the button. This works perfectly with the older 300 series device. I am running 03.02.2015 v2.0.1-rc15. Any ideas besides updating the software? I would rather not update all of my installations just for this one issue. The isAwake property is currently stuck on true.

[2016-05-19 18:49:12.039] [D] [zway] RECEIVED: ( 01 08 00 04 00 1A 02 98 40 33 )
[2016-05-19 18:49:12.040] [D] [zway] SENT ACK
[2016-05-19 18:49:12.040] [D] [zway] SETDATA devices.26.data.lastReceived = 0 (0x00000000)
[2016-05-19 18:49:12.040] [zway] Node 26:0 CC Security: sending Nonce Report
[2016-05-19 18:49:12.040] [zway] Adding job: Nonce Report
[2016-05-19 18:49:12.040] [D] [zway] SENDING (cb 0x20): ( 01 11 00 13 1A 0A 98 80 33 78 BC 27 7F 2E 38 7E 05 20 17 )
[2016-05-19 18:49:12.050] [D] [zway] RECEIVED ACK
[2016-05-19 18:49:12.050] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-05-19 18:49:12.050] [D] [zway] SENT ACK
[2016-05-19 18:49:12.050] [D] [zway] Delivered to Z-Wave stack
[2016-05-19 18:49:12.081] [D] [zway] RECEIVED: ( 01 07 00 13 20 00 00 04 CF )
[2016-05-19 18:49:12.082] [D] [zway] SENT ACK
[2016-05-19 18:49:12.082] [zway] Job 0x13 (Nonce Report): Delivered
[2016-05-19 18:49:12.082] [D] [zway] SETDATA devices.26.data.lastPacketInfo.delivered = True
[2016-05-19 18:49:12.082] [D] [zway] SETDATA devices.26.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-05-19 18:49:12.082] [D] [zway] SETDATA devices.26.data.lastPacketInfo.deliveryTime = 31 (0x0000001f)
[2016-05-19 18:49:12.082] [D] [zway] SETDATA devices.26.data.lastPacketInfo = **********
[2016-05-19 18:49:12.082] [D] [zway] SendData Response with callback 0x20 received: received by recipient
[2016-05-19 18:49:12.082] [D] [zway] SETDATA devices.26.data.lastSend = 1399938 (0x00155c82)
[2016-05-19 18:49:12.082] [D] [zway] Job 0x13 (Nonce Report): success
[2016-05-19 18:49:12.082] [zway] Removing job: Nonce Report
[2016-05-19 18:49:12.102] [D] [zway] RECEIVED: ( 01 1C 00 04 00 1A 16 98 81 6F 90 A2 C7 67 F6 F9 21 57 54 72 33 FB 9D 08 38 72 C7 24 F7 53 )
[2016-05-19 18:49:12.103] [D] [zway] SENT ACK
[2016-05-19 18:49:12.103] [D] [zway] SETDATA devices.26.data.lastReceived = 0 (0x00000000)
[2016-05-19 18:49:12.103] [zway] Node 26:0 CC Security: Received a secure message
[2016-05-19 18:49:12.103] [D] [zway] SETDATA devices.26.instances.0.commandClasses.152.data.firstPart = **********
[2016-05-19 18:49:12.103] [zway] Node 26:0 CC Security: passing decrypted packet to application level: [ 84 07 ]
[2016-05-19 18:49:12.103] [zway] Node 26:0 CC Wakeup: Wakeup notification
[2016-05-19 18:49:12.103] [D] [zway] SETDATA devices.26.instances.0.commandClasses.132.data.lastWakeup = 1463683752 (0x573e0aa8)
[2016-05-19 18:49:12.104] [D] [zway] SETDATA devices.26.data.lastSend = 1399940 (0x00155c84)
[2016-05-19 18:49:12.104] [D] [zway] SETDATA devices.26.data.lastNonceGet = 1398940 (0x0015589c)
[2016-05-19 18:49:12.104] [D] [zway] Running wakeup handler for node 26
[2016-05-19 18:49:12.104] [D] [zway] SETDATA devices.26.instances.0.commandClasses.132.data.lastSleep = 1463683752 (0x573e0aa8)
[2016-05-19 18:49:12.104] [zway] Node 26:0 CC Wakeup: Send node to sleep
[2016-05-19 18:49:12.104] [zway] Adding job: Wakeup Sleep
[2016-05-19 18:49:12.105] [core] DEBUG --- bindCallback(1, /devices/26/instances/0/commandClasses/132/data/lastSleep) HALO
[2016-05-19 18:49:12.105] [I] [core] DEBUG Processing event for /devices/26/instances/0/commandClasses/132/data/lastSleep HALO
[2016-05-19 18:49:12.106] [I] [core] DEBUG HTTP PUT 127.0.0.1:80/property HALO
[2016-05-19 18:49:12.106] [I] [core] DEBUG {"vDevId":"zWave_26","properties":{"lastSleep":{"invalidateTime":1463682373,"updateTime":1463683752,"type":"int","value":1463683752000}}} HALO
[2016-05-19 18:49:12.123] [I] [zway] Node 26:0 CC Security: sending Nonce Get
[2016-05-19 18:49:12.123] [D] [zway] SETDATA devices.26.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2016-05-19 18:49:12.123] [I] [zway] Job 0x13 (Wakeup Sleep): Cancelling job: Wakeup Sleep
[2016-05-19 18:49:12.123] [I] [zway] Removing job: Wakeup Sleep
[2016-05-19 18:49:12.123] [I] [zway] Adding job: Nonce Get
[2016-05-19 18:49:12.123] [D] [zway] SETDATA devices.26.instances.0.commandClasses.132.data.lastSleep = 1463683752 (0x573e0aa8)
[2016-05-19 18:49:12.123] [I] [zway] Node 26:0 CC Wakeup: Send node to sleep
[2016-05-19 18:49:12.124] [I] [zway] Adding job: Wakeup Sleep
[2016-05-19 18:49:12.124] [D] [zway] SETDATA devices.26.data.lastNonceGet = 1399941 (0x00155c85)
[2016-05-19 18:49:12.130] [I] [core] DEBUG --- bindCallback(65, /devices/26/instances/0/commandClasses/132/data/lastSleep) HALO
[2016-05-19 18:49:12.130] [I] [core] DEBUG Processing event for /devices/26/instances/0/commandClasses/132/data/lastSleep HALO
[2016-05-19 18:49:12.132] [I] [core] DEBUG Queueing 2 HALO
[2016-05-19 18:49:12.164] [I] [core] DEBUG httpCallback response: OK HALO
[2016-05-19 18:49:12.165] [I] [core] DEBUG HTTP PUT 127.0.0.1:80/property HALO
[2016-05-19 18:49:12.165] [I] [core] DEBUG {"vDevId":"zWave_26","properties":{"lastSleep":{"invalidateTime":1463682373,"updateTime":1463683752,"type":"int","value":1463683752000}}} HALO
[2016-05-19 18:49:12.175] [D] [zway] SENDING (cb 0x21): ( 01 09 00 13 1A 02 98 40 05 21 01 )
[2016-05-19 18:49:12.177] [D] [zway] RECEIVED ACK
[2016-05-19 18:49:12.188] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-05-19 18:49:12.188] [D] [zway] SENT ACK
[2016-05-19 18:49:12.188] [D] [zway] Delivered to Z-Wave stack
[2016-05-19 18:49:12.199] [D] [zway] RECEIVED: ( 01 07 00 13 21 00 00 01 CB )
[2016-05-19 18:49:12.199] [D] [zway] SENT ACK
[2016-05-19 18:49:12.199] [I] [zway] Job 0x13 (Nonce Get): Delivered
[2016-05-19 18:49:12.199] [D] [zway] SETDATA devices.26.data.lastPacketInfo.delivered = True
[2016-05-19 18:49:12.200] [D] [zway] SETDATA devices.26.data.lastPacketInfo.packetLength = 5 (0x00000005)
[2016-05-19 18:49:12.200] [D] [zway] SETDATA devices.26.data.lastPacketInfo.deliveryTime = 11 (0x0000000b)
[2016-05-19 18:49:12.200] [D] [zway] SETDATA devices.26.data.lastPacketInfo = **********
[2016-05-19 18:49:12.200] [D] [zway] SendData Response with callback 0x21 received: received by recipient
[2016-05-19 18:49:12.200] [D] [zway] SETDATA devices.26.data.lastSend = 1399949 (0x00155c8d)
[2016-05-19 18:49:12.200] [D] [zway] Job 0x13 (Nonce Get): success
[2016-05-19 18:49:12.200] [D] [zway] SETDATA devices.26.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2016-05-19 18:49:12.200] [I] [zway] Removing job: Nonce Get
[2016-05-19 18:49:12.204] [I] [core] DEBUG httpCallback response: OK HALO
[2016-05-19 18:49:12.209] [D] [zway] RECEIVED: ( 01 10 00 04 00 1A 0A 98 80 61 C5 33 F8 4F D6 19 7C 70 )
[2016-05-19 18:49:12.210] [D] [zway] SENT ACK
[2016-05-19 18:49:12.210] [D] [zway] SETDATA devices.26.data.lastReceived = 0 (0x00000000)
[2016-05-19 18:49:12.210] [I] [zway] Node 26:0 CC Security: received Nonce Report
[2016-05-19 18:49:12.210] [D] [zway] SETDATA devices.26.instances.0.commandClasses.152.data.rNonce = **********
[2016-05-19 18:49:12.210] [I] [zway] Removing job: Wakeup Sleep
[2016-05-19 18:49:12.210] [I] [zway] Node 26:0 CC Security: Sending a secure message
[2016-05-19 18:49:12.210] [I] [zway] Node 26:0 CC Security: sending encrypted packet: Wakeup Sleep
[2016-05-19 18:49:12.210] [D] [zway] SETDATA devices.26.instances.0.commandClasses.152.data.toFollow = False
[2016-05-19 18:49:12.211] [D] [zway] SETDATA devices.26.data.lastNonceGet = Empty
[2016-05-19 18:49:12.211] [I] [zway] Adding job: Security, Wakeup Sleep
[2016-05-19 18:49:12.271] [D] [zway] SENDING (cb 0x22): ( 01 1D 00 13 1A 16 98 81 7C B4 56 F9 3E 60 C3 9A C2 ED B2 61 BA 75 3E 3D CE 19 13 2A 05 22 7D )
[2016-05-19 18:49:12.274] [D] [zway] RECEIVED ACK
[2016-05-19 18:49:12.281] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-05-19 18:49:12.281] [D] [zway] SENT ACK
[2016-05-19 18:49:12.282] [I] [zway] Job 0x13 (Security, Wakeup Sleep): Response received - transfered to encapsulated jobs
[2016-05-19 18:49:12.282] [D] [zway] Delivered to Z-Wave stack
[2016-05-19 18:49:12.282] [D] [zway] Delivered to Z-Wave stack
[2016-05-19 18:49:12.301] [D] [zway] RECEIVED: ( 01 07 00 13 22 00 00 02 CB )
[2016-05-19 18:49:12.301] [D] [zway] SENT ACK
[2016-05-19 18:49:12.301] [I] [zway] Job 0x13 (Security, Wakeup Sleep): Callback received - transfered to encapsulated jobs
[2016-05-19 18:49:12.302] [I] [zway] Job 0x13 (Wakeup Sleep): Delivered
[2016-05-19 18:49:12.302] [D] [zway] SendData Response with callback 0x22 received: received by recipient
[2016-05-19 18:49:12.302] [I] [zway] Job 0x13 (Security, Wakeup Sleep): Delivered
[2016-05-19 18:49:12.302] [D] [zway] SETDATA devices.26.data.lastPacketInfo.delivered = True
[2016-05-19 18:49:12.302] [D] [zway] SETDATA devices.26.data.lastPacketInfo.packetLength = 25 (0x00000019)
[2016-05-19 18:49:12.302] [D] [zway] SETDATA devices.26.data.lastPacketInfo.deliveryTime = 20 (0x00000014)
[2016-05-19 18:49:12.302] [D] [zway] SETDATA devices.26.data.lastPacketInfo = **********
[2016-05-19 18:49:12.302] [D] [zway] SendData Response with callback 0x22 received: received by recipient
[2016-05-19 18:49:12.302] [D] [zway] SETDATA devices.26.data.lastSend = 1399959 (0x00155c97)
[2016-05-19 18:49:12.302] [D] [zway] Job 0x13 (Security, Wakeup Sleep): success
[2016-05-19 18:49:12.303] [I] [zway] Removing job: Security, Wakeup Sleep

enco.josh
Posts: 35
Joined: 10 Mar 2014 22:10

Re: Fibaro FGFS101 500 Series

Post by enco.josh » 20 May 2016 18:17

After updating my software to v2.2.2. I am seeing the isAwake property sticking to true. Is there a bug related to security enabled devices and going to sleep properly?

I did see this in the v2.0.1-rc15 release notes.
Fixes:
- Queueing made more stable. Sleeping secure devices managed better.

User avatar
PoltoS
Posts: 5081
Joined: 26 Jan 2011 19:36

Re: Fibaro FGFS101 500 Series

Post by PoltoS » 04 Aug 2016 20:55

Looks like known problem. Will have a look on how fast we can fix it. This is because WakeUp CC is inside Security

Post Reply