centralScene Interview fails on Fibaro FGBS-222

[ibjr]

A S2 inclusion of this device keeps failing on CentralScene. Also a forced interview does not solve this.
Also other, new Fibaro devices (like the Walli series) fail on CentralScene
See attachment for log file of the FGBS-222 inclusion (S2 auth. mode)

[ibjr]

Running version R3.0.0 on Rpi/Stretch

[PoltoS]

We do not see anything critical in the log. Would be nice to get once the device in hands and make all the tests.

[añep]

Hi,

Same problem here. CentralScene interview fail and It generate notifications error when sensor polling (each 10 minutes):

Code: Select all

[2019-12-17 11:30:00.985] [I] [core] ---  ZWayVDev_zway_5-0-50-2 performCommand processing: {"0":"update"}
[2019-12-17 11:30:00.986] [I] [zway] Adding job: Meter Get (v2/3)
[2019-12-17 11:30:00.990] [I] [core] ---  ZWayVDev_zway_5-0-113-8-6-A performCommand processing: {"0":"update"}
[2019-12-17 11:30:00.990] [I] [zway] Adding job: Alarm Get (v3)
[2019-12-17 11:30:00.991] [I] [core] ---  ZWayVDev_zway_5-0-113-8-8-A performCommand processing: {"0":"update"}
[2019-12-17 11:30:00.991] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2019-12-17 11:30:00.993] [I] [core] ---  ZWayVDev_zway_15-0-113-7-2-A performCommand processing: {"0":"update"}
[2019-12-17 11:30:00.993] [D] [zway] SENDING (cb 0x6e): ( 01 0E 00 13 05 07 56 01 32 01 10 D5 08 25 6E 02 )
[2019-12-17 11:30:00.994] [E] [zway] Can not find appropriate security scheme
[2019-12-17 11:30:00.994] [E] [zway] Error returned from __AlarmGet(zway, command, i, 0, NULL, NULL, NULL): Bad arguments (-1)
[2019-12-17 11:30:00.994] [I] [core] ---  ZWayVDev_zway_15-0-49-1 performCommand processing: {"0":"update"}
[2019-12-17 11:30:00.995] [E] [zway] Can not find appropriate security scheme
[2019-12-17 11:30:00.995] [D] [zway] RECEIVED ACK
[2019-12-17 11:30:00.999] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2019-12-17 11:30:00.999] [D] [zway] SENT ACK
[2019-12-17 11:30:01.000] [D] [zway] Delivered to Z-Wave stack
[2019-12-17 11:30:01.007] [I] [core] Notification: error (module): Error durante la ejecución del comando: Error: Invalid argument
[2019-12-17 11:30:01.016] [I] [core] Error: Invalid argument
    at Error (native)
    at self.controller.devices.create.handler (automation/modules/ZWave/index.js:5156:14)
    at _.extend.performCommand (automation/classes/VirtualDevice.js:316:25)
    at automation/modules/SensorsPolling/index.js:82:9
    at Array.forEach (native)
    at AutomationController.onPoll (automation/modules/SensorsPolling/index.js:81:20)
    at AutomationController.EventEmitter.emit (automation/lib/eventemitter2.js:311:33)
    at automation/modules/Cron/index.js:138:27
    at Array.forEach (native)
    at automation/modules/Cron/index.js:126:18
[2019-12-17 11:30:01.016] [I] [core] ---  ZWayVDev_zway_15-1-113-7-2-A performCommand processing: {"0":"update"}
[2019-12-17 11:30:01.017] [E] [zway] Can not find appropriate security scheme
[2019-12-17 11:30:01.017] [E] [zway] Error returned from __AlarmGet(zway, command, i, 0, NULL, NULL, NULL): Bad arguments (-1)
[2019-12-17 11:30:01.017] [I] [core] ---  ZWayVDev_zway_15-2-113-7-2-A performCommand processing: {"0":"update"}
[2019-12-17 11:30:01.017] [E] [zway] Can not find appropriate security scheme
[2019-12-17 11:30:01.017] [E] [zway] Error returned from __AlarmGet(zway, command, i, 0, NULL, NULL, NULL): Bad arguments (-1)
[2019-12-17 11:30:01.018] [I] [core] ---  ZWayVDev_zway_15-7-113-9-3-A performCommand processing: {"0":"update"}
[2019-12-17 11:30:01.018] [E] [zway] Can not find appropriate security scheme
[2019-12-17 11:30:01.018] [E] [zway] Error returned from __AlarmGet(zway, command, i, 0, NULL, NULL, NULL): Bad arguments (-1)
[2019-12-17 11:30:01.018] [I] [core] ---  ZWayVDev_zway_15-3-49-15 performCommand processing: {"0":"update"}
[2019-12-17 11:30:01.019] [E] [zway] Can not find appropriate security scheme
[2019-12-17 11:30:01.024] [I] [core] Notification: error (module): Error durante la ejecución del comando: Error: Invalid argument
[2019-12-17 11:30:01.036] [I] [core] Error: Invalid argument
    at Error (native)
    at self.controller.devices.create.handler (automation/modules/ZWave/index.js:5156:14)
    at _.extend.performCommand (automation/classes/VirtualDevice.js:316:25)
    at automation/modules/SensorsPolling/index.js:82:9
    at Array.forEach (native)
    at AutomationController.onPoll (automation/modules/SensorsPolling/index.js:81:20)
    at AutomationController.EventEmitter.emit (automation/lib/eventemitter2.js:311:33)
    at automation/modules/Cron/index.js:138:27
    at Array.forEach (native)
    at automation/modules/Cron/index.js:126:18
[2019-12-17 11:30:01.036] [I] [core] ---  ZWayVDev_zway_15-4-49-15 performCommand processing: {"0":"update"}
[2019-12-17 11:30:01.037] [E] [zway] Can not find appropriate security scheme
[2019-12-17 11:30:01.040] [I] [core] Notification: error (module): Error durante la ejecución del comando: Error: Invalid argument
[2019-12-17 11:30:01.047] [I] [core] Error: Invalid argument
    at Error (native)
    at self.controller.devices.create.handler (automation/modules/ZWave/index.js:5156:14)
    at _.extend.performCommand (automation/classes/VirtualDevice.js:316:25)
    at automation/modules/SensorsPolling/index.js:82:9
    at Array.forEach (native)
    at AutomationController.onPoll (automation/modules/SensorsPolling/index.js:81:20)
    at AutomationController.EventEmitter.emit (automation/lib/eventemitter2.js:311:33)
    at automation/modules/Cron/index.js:138:27
    at Array.forEach (native)
    at automation/modules/Cron/index.js:126:18
[2019-12-17 11:30:01.047] [I] [core] ---  ZWayVDev_zway_15-7-49-1 performCommand processing: {"0":"update"}
[2019-12-17 11:30:01.048] [E] [zway] Can not find appropriate security scheme
[2019-12-17 11:30:01.051] [I] [core] Notification: error (module): Error durante la ejecución del comando: Error: Invalid argument
[2019-12-17 11:30:01.055] [D] [zway] RECEIVED: ( 01 18 00 13 6E 00 00 05 01 C4 7F 7F 7F 7F 01 01 03 07 00 00 00 02 01 00 00 5D )
[2019-12-17 11:30:01.056] [D] [zway] SENT ACK
[2019-12-17 11:30:01.056] [I] [zway] Job 0x13 (Meter Get (v2/3)): Delivered
[2019-12-17 11:30:01.056] [D] [zway] SendData Response with callback 0x6e received: received by recipient
[2019-12-17 11:30:01.056] [D] [zway] SETDATA devices.5.data.lastSendInternal = **********
[2019-12-17 11:30:01.057] [D] [zway] SETDATA devices.5.data.lastSend = 2415996 (0x0024dd7c)
[2019-12-17 11:30:01.057] [D] [zway] Job 0x13 (Meter Get (v2/3)): success
[2019-12-17 11:30:01.057] [I] [zway] Waiting for job reply: Meter Get (v2/3)
[2019-12-17 11:30:01.059] [I] [core] Error: Invalid argument
    at Error (native)
    at self.controller.devices.create.handler (automation/modules/ZWave/index.js:5156:14)
    at _.extend.performCommand (automation/classes/VirtualDevice.js:316:25)
    at automation/modules/SensorsPolling/index.js:82:9
    at Array.forEach (native)
    at AutomationController.onPoll (automation/modules/SensorsPolling/index.js:81:20)
    at AutomationController.EventEmitter.emit (automation/lib/eventemitter2.js:311:33)
    at automation/modules/Cron/index.js:138:27
    at Array.forEach (native)
    at automation/modules/Cron/index.js:126:18
[2019-12-17 11:30:01.124] [D] [zway] RECEIVED: ( 01 14 00 04 00 05 0C 56 01 32 02 21 32 00 00 00 00 6B B5 BF 00 F3 )
[2019-12-17 11:30:01.125] [D] [zway] SENT ACK
[2019-12-17 11:30:01.125] [D] [zway] SETDATA devices.5.data.lastReceived = 0 (0x00000000)
[2019-12-17 11:30:01.125] [D] [zway] Received reply on job (Meter Get (v2/3))
[2019-12-17 11:30:01.125] [D] [zway] SETDATA devices.5.instances.0.commandClasses.50.data.2.val = 0.000000
[2019-12-17 11:30:01.125] [D] [zway] SETDATA devices.5.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2019-12-17 11:30:01.125] [D] [zway] SETDATA devices.5.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2019-12-17 11:30:01.125] [D] [zway] SETDATA devices.5.instances.0.commandClasses.50.data.2 = Empty
[2019-12-17 11:30:01.125] [D] [zway] SENDING (cb 0x6f): ( 01 10 00 13 05 09 56 01 71 04 00 08 00 E2 EF 25 6F 9D )
[2019-12-17 11:30:01.134] [D] [zway] RECEIVED ACK
[2019-12-17 11:30:01.134] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2019-12-17 11:30:01.134] [D] [zway] SENT ACK
[2019-12-17 11:30:01.134] [D] [zway] Delivered to Z-Wave stack
[2019-12-17 11:30:01.248] [D] [zway] RECEIVED: ( 01 18 00 13 6F 00 00 0B 01 C4 7F 7F 7F 7F 01 01 03 07 00 00 00 02 01 00 00 52 )
[2019-12-17 11:30:01.248] [D] [zway] SENT ACK
[2019-12-17 11:30:01.248] [I] [zway] Job 0x13 (Alarm Get (v3)): Delivered
[2019-12-17 11:30:01.248] [D] [zway] SendData Response with callback 0x6f received: received by recipient
[2019-12-17 11:30:01.249] [D] [zway] SETDATA devices.5.data.lastSendInternal = **********
[2019-12-17 11:30:01.249] [D] [zway] SETDATA devices.5.data.lastSend = 2416014 (0x0024dd8e)
[2019-12-17 11:30:01.249] [D] [zway] Job 0x13 (Alarm Get (v3)): success
[2019-12-17 11:30:01.249] [I] [zway] Waiting for job reply: Alarm Get (v3)
[2019-12-17 11:30:01.323] [D] [zway] RECEIVED: ( 01 15 00 04 00 05 0D 56 01 71 05 00 00 00 FF 08 00 00 4D 11 C6 00 A8 )
[2019-12-17 11:30:01.324] [D] [zway] SENT ACK
[2019-12-17 11:30:01.324] [D] [zway] SETDATA devices.5.data.lastReceived = 0 (0x00000000)
[2019-12-17 11:30:01.324] [D] [zway] Received reply on job (Alarm Get (v3))
[2019-12-17 11:30:01.324] [D] [zway] SETDATA devices.5.instances.0.commandClasses.113.data.8.eventParameters = byte[0]
[2019-12-17 11:30:01.324] [D] [zway]   ( zero-length buffer )
[2019-12-17 11:30:01.325] [D] [zway] SETDATA devices.5.instances.0.commandClasses.113.data.8.event = 0 (0x00000000)
[2019-12-17 11:30:01.326] [D] [zway] SETDATA devices.5.instances.0.commandClasses.113.data.8.eventString = ""
[2019-12-17 11:30:01.326] [D] [zway] SETDATA devices.5.instances.0.commandClasses.113.data.8.status = 255 (0x000000ff)
[2019-12-17 11:30:01.327] [D] [zway] SETDATA devices.5.instances.0.commandClasses.113.data.8 = Empty
[2019-12-17 11:30:03.224] [D] [zway] Job 0x13: deleted from queue
[2019-12-17 11:30:03.403] [D] [zway] Job 0x13: deleted from queue
[2019-12-17 11:30:09.528] [D] [zway] Job 0x3b: deleted from queue

Razberry V2
Rasbpberry 3B+
Raspbian Stretch
Zway v3.0.2

[PoltoS]

Looks like FGBS-222 messed up security schemes. We need access to the device to test it.

[AlesKO]

Same problem with 'CentralScene' interview on FGR 223 - Fibaro roller shutter 3.
Can someone write bruteforce solution for me: http://ip:8083/JS/Run/zway.devices[24].....

But would be fine to solve incomplete interview normal way...

[PoltoS]

http://ip:8083/JS/Run/zway.devices[24]. ... wDone=true

But some button presses can be reported wrong in this case.

[platypus]

I can confirm this problem. I successfully included FGBS-222, the interview completed 100 %, used it for some days and then had to interrupt it’s power supply. After that the interview only reaches 99 % completion. The interview results show that “CentralScene” class doesn’t work.

[PoltoS]

Could you please share the log when it went to 99% ?

[platypus]

I chose "force interview" and attached the log file with the incomplete interview:

z-way-server.log.zip

(80.91 KiB) Downloaded 434 times