Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[bug] Aeotec Smart Switch 6 - Fails to complete Interview #1369

Closed
1 of 3 tasks
tommyjlong opened this issue Jan 12, 2021 · 39 comments · Fixed by #1505
Closed
1 of 3 tasks

[bug] Aeotec Smart Switch 6 - Fails to complete Interview #1369

tommyjlong opened this issue Jan 12, 2021 · 39 comments · Fixed by #1505
Labels
bug Something isn't working interview overhaul 🔬 Depends on a reworked interview process

Comments

@tommyjlong
Copy link
Contributor

tommyjlong commented Jan 12, 2021

Version
1.0.0-alpha.2.51f715c

Build/Run method

  • Docker
  • PKG
  • Manually built (git clone - npm install - npm run build )

zwavejs2mqtt version: 1.0.0-alpha.2.51f715c

Describe the bug
After a few hours, all my devices have reached "Complete" but
the Aeotec Smart Switch 6 still never reaches "Complete".
Note: This is a case of transitioning from OZW/zwave2mqtt to zwavejs2mqtt.
Note: The Smart Switch 6 has been operational with OZW/zwave2mqtt.
The Smart Switch 6 reports power/energy meter updates about every 60 sec.

To Reproduce
Steps to reproduce the behavior:
(With "Log to file" set to on)

  1. GUI Refresh Info (in my case the Smart Switch 6 is node 6).
    The following zwave-js2mqtt logs:
Logs
14:22:01.691 CNTRLR   [Node 006] Beginning interview - last completed stage: None
14:22:01.692 CNTRLR   [Node 006] new node, doing a full interview...
14:22:01.693 CNTRLR » [Node 006] querying protocol info...
14:22:01.719 CNTRLR « [Node 006] received response for protocol info:
                      basic device class:    Static Controller
                      generic device class:  Binary Switch
                      specific device class: Binary Power Switch
                      is a listening device: true
                      is frequent listening: false
                      is a routing device:   true
                      is a secure device:    unknown
                      is a beaming device:   true
                      maximum baud rate:     40000 kbps
                      version:               4
14:22:01.719 CNTRLR   [Node 006] Interview stage completed: ProtocolInfo
14:22:01.720 CNTRLR » [Node 006] pinging the node...
14:22:01.859 CNTRLR « [Node 006] ping successful
14:22:01.860 CNTRLR » [Node 006] querying node info...
14:22:02.165 CNTRLR « [Node 006] node info received
                      supported CCs:
                      · Z-Wave Plus Info
                      · Binary Switch
                      · Multilevel Switch
                      · Color Switch
                      · Configuration
                      · All Switch
                      · Meter
                      · Clock
                      · Association
                      · Association Group Information
                      · Manufacturer Specific
                      · Version
                      · Firmware Update Meta Data
                      · Powerlevel
                      controlled CCs:
                      · Device Reset Locally
                      · Hail
14:22:02.199 CNTRLR   [Node 006] Interview stage completed: NodeInfo
14:22:02.204 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a complete interview...
14:22:02.204 CNTRLR » [Node 006] querying manufacturer information...
14:22:02.509 CNTRLR « [Node 006] received response for manufacturer information:
                        manufacturer: AEON Labs (0x86)
                        product type: 0x0103
                        product id:   0x60
14:22:02.510 CNTRLR   [Node 006] trying to load device config
14:22:02.511 CNTRLR   [Node 006] no device config loaded
14:22:02.540 CNTRLR   [Node 006] VersionCC: doing a complete interview...
14:22:02.541 CNTRLR » [Node 006] querying node versions...
14:22:02.860 CNTRLR « [Node 006] received response for node versions:
                        library type:      Enhanced Slave (0x03)
                        protocol version:  4.5
                        firmware versions: 1.3
14:22:02.860 CNTRLR » [Node 006] querying CC versions...
14:22:02.860 CNTRLR » [Node 006]   querying the CC version for Binary Switch...
14:22:03.205 CNTRLR   [Node 006]   supports CC Binary Switch (0x25) in version 1
14:22:03.206 CNTRLR   [Node 006]   skipping query for All Switch (0x27) because max implemented vers
                      ion is 0
14:22:03.207 CNTRLR » [Node 006]   querying the CC version for Z-Wave Plus Info...
14:22:03.555 CNTRLR   [Node 006]   supports CC Z-Wave Plus Info (0x5e) in version 2
14:22:03.556 CNTRLR » [Node 006]   querying the CC version for Multilevel Switch...
14:22:03.904 CNTRLR   [Node 006]   supports CC Multilevel Switch (0x26) in version 2
14:22:03.904 CNTRLR » [Node 006]   querying the CC version for Color Switch...
14:22:04.253 CNTRLR   [Node 006]   supports CC Color Switch (0x33) in version 1
14:22:04.253 CNTRLR » [Node 006]   querying the CC version for Configuration...
14:22:04.609 CNTRLR   [Node 006]   supports CC Configuration (0x70) in version 1
14:22:04.615 CNTRLR » [Node 006]   querying the CC version for Meter...
14:22:04.956 CNTRLR   [Node 006]   supports CC Meter (0x32) in version 3
14:22:04.957 CNTRLR   [Node 006]   skipping query for Clock (0x81) because max implemented version i
                      s 1
14:22:04.957 CNTRLR » [Node 006]   querying the CC version for Association...
14:22:05.309 CNTRLR   [Node 006]   supports CC Association (0x85) in version 2
14:22:05.310 CNTRLR » [Node 006]   querying the CC version for Association Group Information...
14:22:05.655 CNTRLR   [Node 006]   supports CC Association Group Information (0x59) in version 1
14:22:05.655 CNTRLR » [Node 006]   querying the CC version for Manufacturer Specific...
14:22:06.008 CNTRLR   [Node 006]   supports CC Manufacturer Specific (0x72) in version 2
14:22:06.009 CNTRLR » [Node 006]   querying the CC version for Version...
14:22:06.357 CNTRLR   [Node 006]   supports CC Version (0x86) in version 2
14:22:06.358 CNTRLR » [Node 006]   querying the CC version for Firmware Update Meta Data...
14:22:06.717 CNTRLR   [Node 006]   supports CC Firmware Update Meta Data (0x7a) in version 2
14:22:06.718 CNTRLR   [Node 006]   skipping query for Powerlevel (0x73) because max implemented vers
                      ion is 0
14:22:06.718 CNTRLR   [Node 006]   skipping query for Device Reset Locally (0x5a) because max implem
                      ented version is 1
14:22:06.719 CNTRLR   [Node 006]   skipping query for Hail (0x82) because max implemented version is
                       1
14:22:06.720 CNTRLR   [Node 006] trying to load device config
14:22:06.738 CNTRLR   [Node 006] device config loaded
14:22:06.755 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
14:22:06.756 CNTRLR » [Node 006] querying Z-Wave+ information...
14:22:16.960 CNTRLR   [Node 006] Interview attempt 1/5 failed, retrying in 5000 ms...
14:22:21.961 CNTRLR   [Node 006] Beginning interview - last completed stage: NodeInfo
14:22:21.967 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a partial interview...
14:22:21.968 CNTRLR   [Node 006] trying to load device config
14:22:21.970 CNTRLR   [Node 006] no device config loaded
14:22:21.996 CNTRLR   [Node 006] VersionCC: doing a partial interview...
14:22:21.997 CNTRLR   [Node 006] trying to load device config
14:22:22.011 CNTRLR   [Node 006] device config loaded
14:22:22.012 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
14:22:22.013 CNTRLR » [Node 006] querying Z-Wave+ information...
14:22:32.158 CNTRLR   [Node 006] Interview attempt 2/5 failed, retrying in 10000 ms...
14:22:42.160 CNTRLR   [Node 006] Beginning interview - last completed stage: NodeInfo
14:22:42.165 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a partial interview...
14:22:42.166 CNTRLR   [Node 006] trying to load device config
14:22:42.167 CNTRLR   [Node 006] no device config loaded
14:22:42.191 CNTRLR   [Node 006] VersionCC: doing a partial interview...
14:22:42.192 CNTRLR   [Node 006] trying to load device config
14:22:42.203 CNTRLR   [Node 006] device config loaded
14:22:42.204 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
14:22:42.204 CNTRLR » [Node 006] querying Z-Wave+ information...
14:22:52.353 CNTRLR   [Node 006] Interview attempt 3/5 failed, retrying in 15000 ms...
14:23:07.354 CNTRLR   [Node 006] Beginning interview - last completed stage: NodeInfo
14:23:07.360 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a partial interview...
14:23:07.361 CNTRLR   [Node 006] trying to load device config
14:23:07.362 CNTRLR   [Node 006] no device config loaded
14:23:07.386 CNTRLR   [Node 006] VersionCC: doing a partial interview...
14:23:07.387 CNTRLR   [Node 006] trying to load device config
14:23:07.403 CNTRLR   [Node 006] device config loaded
14:23:07.404 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
14:23:07.405 CNTRLR » [Node 006] querying Z-Wave+ information...
14:23:17.555 CNTRLR   [Node 006] Interview attempt 4/5 failed, retrying in 20000 ms...
14:23:37.556 CNTRLR   [Node 006] Beginning interview - last completed stage: NodeInfo
14:23:37.562 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a partial interview...
14:23:37.564 CNTRLR   [Node 006] trying to load device config
14:23:37.565 CNTRLR   [Node 006] no device config loaded
14:23:37.587 CNTRLR   [Node 006] VersionCC: doing a partial interview...
14:23:37.588 CNTRLR   [Node 006] trying to load device config
14:23:37.599 CNTRLR   [Node 006] device config loaded
14:23:37.600 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
14:23:37.600 CNTRLR » [Node 006] querying Z-Wave+ information...
14:23:47.745 CNTRLR   [Node 006] Failed all interview attempts, giving up.


@blhoward2
Copy link
Collaborator

Weird, mine appear to be fine though I’ll rediscover them later and confirm. Can you verify what firmware version you have?

@tommyjlong
Copy link
Contributor Author

Managed to get the following:

2021-01-12 15:35:16.097 INFO APP: Zwave api call: refreshInfo [ 6, [length]: 1 ]
2021-01-12 15:35:16.134 INFO ZWAVE: Success zwave api call refreshInfo 
2021-01-12 15:35:16.987 INFO ZWAVE: Node 6: value added: 114-0-manufacturerId => 134
2021-01-12 15:35:16.989 INFO ZWAVE: Node 6: value added: 114-0-productType => 259
2021-01-12 15:35:16.992 INFO ZWAVE: Node 6: value added: 114-0-productId => 96
2021-01-12 15:35:17.388 INFO ZWAVE: Node 6: value added: 134-0-libraryType => 3
2021-01-12 15:35:17.390 INFO ZWAVE: Node 6: value added: 134-0-protocolVersion => 4.5
2021-01-12 15:35:17.391 INFO ZWAVE: Node 6: value added: 134-0-firmwareVersions => 1.3

I'll also throw this corresponding driver log which shows something about invalid data received (and not sure it is from node 6).
I do see this from time to time, but it doesn't always show up.

15:35:17.036 CNTRLR » [Node 006] querying node versions...
15:35:17.138 DRIVER   Message with invalid data received. Dropping it:
                      0x0120000400021a98815814daa89f4d9d886c7332621905f02d91cc35a326d3509e5d
15:35:17.402 CNTRLR « [Node 006] received response for node versions:
                        library type:      Enhanced Slave (0x03)
                        protocol version:  4.5
                        firmware versions: 1.3

@robertsLando robertsLando transferred this issue from zwave-js/zwave-js-ui Jan 13, 2021
@robertsLando
Copy link
Member

cc @AlCalzone

@AlCalzone
Copy link
Member

That invalid message looks like a security encapsulated command which fails to deserialize. Could be due to incorrect network key, could be something else.
Can you send me the network key on a secure channel and make sure the log is complete or at least contains the previously sent NonceReport?

@robertsLando
Copy link
Member

@tommyjlong Could it be you added the device with a network key and then you changed it?

@tommyjlong
Copy link
Contributor Author

I went back to OZW and captured its logs to confirm that my Smart Switch 6 does not use security keys (nonce is not used).
The other devices that do use encryption, I have not found any issues with them when using zwavejs2mqtt.
However I do NOT see any NonceReports in any the zwavejs logs for any of these secured devices.
Is there a log level setting I need to set?

@AlCalzone I will send you separate info to help figure out the deserialization issue

@robertsLando
Copy link
Member

Is there a log level setting I need to set?

@tommyjlong You should set loglevel to silly level in zwave settings. Also enable log to file and then send us that file

@tommyjlong
Copy link
Contributor Author

I set it to silly, "logEnabled":true,"logLevel":"silly","logToFile":true
and did the GUI->refresh Info and here is what came out of the log:
Note: Node 6 is the Aeotec Smart Switch 6. Node 2 is an Aeotec Multisensor6 (encrypted). You will also see in the log where Node 6 and Node2 are periodically sending updates autonomously.

16:18:30.819 CNTRLR   [Node 006] Interview stage completed: ProtocolInfo
16:18:30.819 CNTRLR » [Node 006] pinging the node...
16:18:30.827 SERIAL » 0x01080013060100258e48                                              (10 bytes)
16:18:30.827 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      142
                      └─[NoOperationCC]
16:18:30.831 SERIAL « [ACK]                                                                   (0x06)
16:18:30.838 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:30.839 SERIAL » [ACK]                                                                   (0x06)
16:18:30.844 DRIVER « [RES] [SendData]
                        was sent: true
16:18:30.952 SERIAL « 0x010700138e00000c69                                                 (9 bytes)
16:18:30.952 SERIAL » [ACK]                                                                   (0x06)
16:18:30.954 DRIVER « [REQ] [SendData]
                        callback id:     142
                        transmit status: OK
16:18:30.958 CNTRLR « [Node 006] ping successful
16:18:30.959 CNTRLR » [Node 006] querying node info...
16:18:30.965 SERIAL » 0x01040060069d                                                       (6 bytes)
16:18:30.966 DRIVER » [Node 006] [REQ] [RequestNodeInfo]
                        payload: 0x06
16:18:30.968 SERIAL « [ACK]                                                                   (0x06)
16:18:30.996 SERIAL « 0x01040160019b                                                       (6 bytes)
16:18:30.997 SERIAL » [ACK]                                                                   (0x06)
16:18:30.999 DRIVER « [RES] [RequestNodeInfo]
                        payload: 0x01
16:18:31.251 SERIAL « 0x011a00498406140410015e25263370273281855972867a73ef5a82b3          (28 bytes)
16:18:31.255 SERIAL » [ACK]                                                                   (0x06)
16:18:31.258 DRIVER « [Node 006] [REQ] [ApplicationUpdateRequest]
                        payload: 0x8406140410015e25263370273281855972867a73ef5a82
16:18:31.264 CNTRLR   [Node 006] The node is alive.
16:18:31.265 CNTRLR « [Node 006] node info received
                      supported CCs:
                      · Z-Wave Plus Info
                      · Binary Switch
                      · Multilevel Switch
                      · Color Switch
                      · Configuration
                      · All Switch
                      · Meter
                      · Clock
                      · Association
                      · Association Group Information
                      · Manufacturer Specific
                      · Version
                      · Firmware Update Meta Data
                      · Powerlevel
                      controlled CCs:
                      · Device Reset Locally
                      · Hail
16:18:31.292 CNTRLR   [Node 006] Interview stage completed: NodeInfo
16:18:31.295 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a complete interview...
16:18:31.295 CNTRLR » [Node 006] querying manufacturer information...
16:18:31.315 SERIAL » 0x0109001306027204258f3d                                            (11 bytes)
16:18:31.316 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      143
                      └─[ManufacturerSpecificCCGet]
16:18:31.322 SERIAL « [ACK]                                                                   (0x06)
16:18:31.325 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:31.325 SERIAL » [ACK]                                                                   (0x06)
16:18:31.327 DRIVER « [RES] [SendData]
                        was sent: true
16:18:31.482 SERIAL « 0x010700138f00001074                                                 (9 bytes)
16:18:31.484 SERIAL » [ACK]                                                                   (0x06)
16:18:31.485 DRIVER « [REQ] [SendData]
                        callback id:     143
                        transmit status: OK
16:18:31.601 SERIAL « 0x010e0004000608720500860103006068                                  (16 bytes)
16:18:31.603 CNTRLR   [Node 006] [+] [Manufacturer Specific] manufacturerId: 134        [Endpoint 0]
16:18:31.605 CNTRLR   [Node 006] [+] [Manufacturer Specific] productType: 259           [Endpoint 0]
16:18:31.606 CNTRLR   [Node 006] [+] [Manufacturer Specific] productId: 96              [Endpoint 0]
16:18:31.607 SERIAL » [ACK]                                                                   (0x06)
16:18:31.611 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[ManufacturerSpecificCCReport]
                          manufacturer id: 0x86
                          product type:    0x0103
                          product id:      0x60
16:18:31.614 CNTRLR « [Node 006] received response for manufacturer information:
                        manufacturer: AEON Labs (0x86)
                        product type: 0x0103
                        product id:   0x60
16:18:31.614 CNTRLR   [Node 006] [+] [Manufacturer Specific] interviewComple [Endpoint 0] [internal]
                      te: true
16:18:31.615 CNTRLR   [Node 006] trying to load device config
16:18:31.615 CNTRLR   [Node 006] no device config loaded
16:18:31.637 CNTRLR   [Node 006] VersionCC: doing a complete interview...
16:18:31.637 CNTRLR » [Node 006] querying node versions...
16:18:31.721 SERIAL » 0x01090013060286112590c3                                            (11 bytes)
16:18:31.722 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      144
                      └─[VersionCCGet]
16:18:31.728 SERIAL « [ACK]                                                                   (0x06)
16:18:31.730 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:31.735 SERIAL » [ACK]                                                                   (0x06)
16:18:31.737 DRIVER « [RES] [SendData]
                        was sent: true
16:18:31.851 SERIAL « 0x010700139000000c77                                                 (9 bytes)
16:18:31.852 SERIAL » [ACK]                                                                   (0x06)
16:18:31.853 DRIVER « [REQ] [SendData]
                        callback id:     144
                        transmit status: OK
16:18:31.974 SERIAL « 0x010f00040006098612030405010360000f                                (17 bytes)
16:18:31.976 CNTRLR   [Node 006] [+] [Version] libraryType: 3                           [Endpoint 0]
16:18:31.978 CNTRLR   [Node 006] [+] [Version] protocolVersion: "4.5"                   [Endpoint 0]
16:18:31.980 CNTRLR   [Node 006] [+] [Version] firmwareVersions: 1.3                    [Endpoint 0]
16:18:31.981 SERIAL » [ACK]                                                                   (0x06)
16:18:31.984 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCReport]
                          library type:      Enhanced Slave
                          protocol version:  4.5
                          firmware versions: 1.3
16:18:31.987 CNTRLR « [Node 006] received response for node versions:
                        library type:      Enhanced Slave (0x03)
                        protocol version:  4.5
                        firmware versions: 1.3
16:18:31.988 CNTRLR » [Node 006] querying CC versions...
16:18:31.989 CNTRLR » [Node 006]   querying the CC version for Binary Switch...
16:18:31.999 SERIAL » 0x010a001306038613252591e7                                          (12 bytes)
16:18:32.001 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      145
                      └─[VersionCCCommandClassGet]
                          CC: Binary Switch
16:18:32.004 SERIAL « [ACK]                                                                   (0x06)
16:18:32.012 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:32.013 SERIAL » [ACK]                                                                   (0x06)
16:18:32.015 DRIVER « [RES] [SendData]
                        was sent: true
16:18:32.205 SERIAL « 0x01070013910000146e                                                 (9 bytes)
16:18:32.207 SERIAL » [ACK]                                                                   (0x06)
16:18:32.210 DRIVER « [REQ] [SendData]
                        callback id:     145
                        transmit status: OK
16:18:32.324 SERIAL « 0x010a00040006048614250145                                          (12 bytes)
16:18:32.325 SERIAL » [ACK]                                                                   (0x06)
16:18:32.327 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Binary Switch
                          version: 1
16:18:32.329 CNTRLR   [Node 006]   supports CC Binary Switch (0x25) in version 1
16:18:32.329 CNTRLR   [Node 006]   skipping query for All Switch (0x27) because max implemented vers
                      ion is 0
16:18:32.330 CNTRLR » [Node 006]   querying the CC version for Z-Wave Plus Info...
16:18:32.335 SERIAL » 0x010a0013060386135e25929f                                          (12 bytes)
16:18:32.336 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      146
                      └─[VersionCCCommandClassGet]
                          CC: Z-Wave Plus Info
16:18:32.338 SERIAL « [ACK]                                                                   (0x06)
16:18:32.345 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:32.346 SERIAL » [ACK]                                                                   (0x06)
16:18:32.348 DRIVER « [RES] [SendData]
                        was sent: true
16:18:32.555 SERIAL « 0x01070013920000166f                                                 (9 bytes)
16:18:32.556 SERIAL » [ACK]                                                                   (0x06)
16:18:32.558 DRIVER « [REQ] [SendData]
                        callback id:     146
                        transmit status: OK
16:18:32.675 SERIAL « 0x010a000400060486145e023d                                          (12 bytes)
16:18:32.676 SERIAL » [ACK]                                                                   (0x06)
16:18:32.676 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Z-Wave Plus Info
                          version: 2
16:18:32.678 CNTRLR   [Node 006]   supports CC Z-Wave Plus Info (0x5e) in version 2
16:18:32.678 CNTRLR » [Node 006]   querying the CC version for Multilevel Switch...
16:18:32.687 SERIAL » 0x010a001306038613262593e6                                          (12 bytes)
16:18:32.688 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      147
                      └─[VersionCCCommandClassGet]
                          CC: Multilevel Switch
16:18:32.691 SERIAL « [ACK]                                                                   (0x06)
16:18:32.696 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:32.697 SERIAL » [ACK]                                                                   (0x06)
16:18:32.698 DRIVER « [RES] [SendData]
                        was sent: true
16:18:32.904 SERIAL « 0x01070013930000166e                                                 (9 bytes)
16:18:32.905 SERIAL » [ACK]                                                                   (0x06)
16:18:32.906 DRIVER « [REQ] [SendData]
                        callback id:     147
                        transmit status: OK
16:18:33.025 SERIAL « 0x010a00040006048614260245                                          (12 bytes)
16:18:33.027 SERIAL » [ACK]                                                                   (0x06)
16:18:33.028 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Multilevel Switch
                          version: 2
16:18:33.033 CNTRLR   [Node 006]   supports CC Multilevel Switch (0x26) in version 2
16:18:33.033 CNTRLR » [Node 006]   querying the CC version for Color Switch...
16:18:33.042 SERIAL » 0x010a001306038613332594f4                                          (12 bytes)
16:18:33.043 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      148
                      └─[VersionCCCommandClassGet]
                          CC: Color Switch
16:18:33.046 SERIAL « [ACK]                                                                   (0x06)
16:18:33.051 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:33.053 SERIAL » [ACK]                                                                   (0x06)
16:18:33.055 DRIVER « [RES] [SendData]
                        was sent: true
16:18:34.088 SERIAL « 0x010700139400006817                                                 (9 bytes)
16:18:34.089 SERIAL » [ACK]                                                                   (0x06)
16:18:34.090 DRIVER « [REQ] [SendData]
                        callback id:     148
                        transmit status: OK
16:18:35.372 SERIAL « 0x010a00040006048614330153                                          (12 bytes)
16:18:35.374 SERIAL » [ACK]                                                                   (0x06)
16:18:35.376 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Color Switch
                          version: 1
16:18:35.381 CNTRLR   [Node 006]   supports CC Color Switch (0x33) in version 1
16:18:35.381 CNTRLR » [Node 006]   querying the CC version for Configuration...
16:18:35.387 SERIAL » 0x010a001306038613702595b6                                          (12 bytes)
16:18:35.388 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      149
                      └─[VersionCCCommandClassGet]
                          CC: Configuration
16:18:35.392 SERIAL « [ACK]                                                                   (0x06)
16:18:35.396 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:35.397 SERIAL » [ACK]                                                                   (0x06)
16:18:35.397 DRIVER « [RES] [SendData]
                        was sent: true
16:18:35.702 SERIAL « 0x010a00040006048614330153                                          (12 bytes)
16:18:35.703 SERIAL » [ACK]                                                                   (0x06)
16:18:35.705 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Color Switch
                          version: 1
16:18:37.960 SERIAL « 0x010a00040006048614700110                                          (12 bytes)
16:18:37.960 SERIAL » [ACK]                                                                   (0x06)
16:18:37.961 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Configuration
                          version: 1
16:18:40.762 SERIAL « 0x010a00040006048614700110                                          (12 bytes)
16:18:40.763 SERIAL » [ACK]                                                                   (0x06)
16:18:40.765 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Configuration
                          version: 1
16:18:40.995 SERIAL « 0x01070013950002314d                                                 (9 bytes)
16:18:40.997 SERIAL » [ACK]                                                                   (0x06)
16:18:40.999 DRIVER « [REQ] [SendData]
                        callback id:     149
                        transmit status: OK
16:18:41.414 SERIAL « 0x010a00040006048614700110                                          (12 bytes)
16:18:41.416 SERIAL » [ACK]                                                                   (0x06)
16:18:41.418 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Configuration
                          version: 1
16:18:41.423 CNTRLR   [Node 006]   supports CC Configuration (0x70) in version 1
16:18:41.423 CNTRLR » [Node 006]   querying the CC version for Meter...
16:18:41.431 SERIAL » 0x010a001306038613322596f7                                          (12 bytes)
16:18:41.433 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      150
                      └─[VersionCCCommandClassGet]
                          CC: Meter
16:18:41.439 SERIAL « [ACK]                                                                   (0x06)
16:18:41.441 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:41.444 SERIAL » [ACK]                                                                   (0x06)
16:18:41.446 DRIVER « [RES] [SendData]
                        was sent: true
16:18:41.567 SERIAL « 0x010a00040006048614700110                                          (12 bytes)
16:18:41.569 SERIAL » [ACK]                                                                   (0x06)
16:18:41.571 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Configuration
                          version: 1
16:18:41.755 SERIAL « 0x010a00040006048614700110                                          (12 bytes)
16:18:41.757 SERIAL » [ACK]                                                                   (0x06)
16:18:41.760 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Configuration
                          version: 1
16:18:42.954 SERIAL « 0x010a00040006048614700110                                          (12 bytes)
16:18:42.956 SERIAL » [ACK]                                                                   (0x06)
16:18:42.958 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Configuration
                          version: 1
16:18:46.026 SERIAL « 0x01070013960001cbb7                                                 (9 bytes)
16:18:46.027 SERIAL » [ACK]                                                                   (0x06)
16:18:46.032 DRIVER « [REQ] [SendData]
                        callback id:     150
                        transmit status: OK
16:18:46.171 SERIAL « 0x010a00040006048614320350                                          (12 bytes)
16:18:46.172 SERIAL » [ACK]                                                                   (0x06)
16:18:46.174 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Meter
                          version: 3
16:18:46.177 CNTRLR   [Node 006]   supports CC Meter (0x32) in version 3
16:18:46.178 CNTRLR   [Node 006]   skipping query for Clock (0x81) because max implemented version i
                      s 1
16:18:46.178 CNTRLR » [Node 006]   querying the CC version for Association...
16:18:46.185 SERIAL » 0x010a00130603861385259741                                          (12 bytes)
16:18:46.186 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      151
                      └─[VersionCCCommandClassGet]
                          CC: Association
16:18:46.191 SERIAL « [ACK]                                                                   (0x06)
16:18:46.194 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:46.195 SERIAL » [ACK]                                                                   (0x06)
16:18:46.196 DRIVER « [RES] [SendData]
                        was sent: true
16:18:46.477 SERIAL « 0x010a00040006048614320350                                          (12 bytes)
16:18:46.478 SERIAL » [ACK]                                                                   (0x06)
16:18:46.481 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Meter
                          version: 3
16:18:46.754 SERIAL « 0x010a00040006048614320350                                          (12 bytes)
16:18:46.756 SERIAL » [ACK]                                                                   (0x06)
16:18:46.758 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Meter
                          version: 3
16:18:46.856 SERIAL « 0x010a00040006048614320350                                          (12 bytes)
16:18:46.857 SERIAL » [ACK]                                                                   (0x06)
16:18:46.858 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Meter
                          version: 3
16:18:47.174 SERIAL « 0x01070013970000631f                                                 (9 bytes)
16:18:47.175 SERIAL » [ACK]                                                                   (0x06)
16:18:47.181 DRIVER « [REQ] [SendData]
                        callback id:     151
                        transmit status: OK
16:18:47.260 SERIAL « 0x010a00040006048614320350                                          (12 bytes)
16:18:47.260 SERIAL » [ACK]                                                                   (0x06)
16:18:47.261 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Meter
                          version: 3
16:18:47.423 SERIAL « 0x010a00040006048614320350                                          (12 bytes)
16:18:47.424 SERIAL » [ACK]                                                                   (0x06)
16:18:47.431 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Meter
                          version: 3
16:18:47.734 SERIAL « 0x010a000400060486148502e6                                          (12 bytes)
16:18:47.736 SERIAL » [ACK]                                                                   (0x06)
16:18:47.739 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Association
                          version: 2
16:18:47.745 CNTRLR   [Node 006]   supports CC Association (0x85) in version 2
16:18:47.745 CNTRLR » [Node 006]   querying the CC version for Association Group Information...
16:18:47.758 SERIAL » 0x010a00130603861359259892                                          (12 bytes)
16:18:47.759 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      152
                      └─[VersionCCCommandClassGet]
                          CC: Association Group Information
16:18:47.761 SERIAL « [ACK]                                                                   (0x06)
16:18:47.769 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:47.770 SERIAL » [ACK]                                                                   (0x06)
16:18:47.771 DRIVER « [RES] [SendData]
                        was sent: true
16:18:47.965 SERIAL « 0x010700139800001566                                                 (9 bytes)
16:18:47.966 SERIAL » [ACK]                                                                   (0x06)
16:18:47.968 DRIVER « [REQ] [SendData]
                        callback id:     152
                        transmit status: OK
16:18:48.084 SERIAL « 0x010a00040006048614590139                                          (12 bytes)
16:18:48.085 SERIAL » [ACK]                                                                   (0x06)
16:18:48.087 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Association Group Information
                          version: 1
16:18:48.090 CNTRLR   [Node 006]   supports CC Association Group Information (0x59) in version 1
16:18:48.090 CNTRLR » [Node 006]   querying the CC version for Manufacturer Specific...
16:18:48.102 SERIAL » 0x010a001306038613722599b8                                          (12 bytes)
16:18:48.103 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      153
                      └─[VersionCCCommandClassGet]
                          CC: Manufacturer Specific
16:18:48.105 SERIAL « [ACK]                                                                   (0x06)
16:18:48.111 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:48.111 SERIAL » [ACK]                                                                   (0x06)
16:18:48.112 DRIVER « [RES] [SendData]
                        was sent: true
16:18:48.674 SERIAL « 0x01070013990000394b                                                 (9 bytes)
16:18:48.675 SERIAL » [ACK]                                                                   (0x06)
16:18:48.677 DRIVER « [REQ] [SendData]
                        callback id:     153
                        transmit status: OK
16:18:48.795 SERIAL « 0x010a00040006048614720211                                          (12 bytes)
16:18:48.796 SERIAL » [ACK]                                                                   (0x06)
16:18:48.797 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Manufacturer Specific
                          version: 2
16:18:48.800 CNTRLR   [Node 006]   supports CC Manufacturer Specific (0x72) in version 2
16:18:48.800 CNTRLR » [Node 006]   querying the CC version for Version...
16:18:48.809 SERIAL » 0x010a00130603861386259a4f                                          (12 bytes)
16:18:48.810 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      154
                      └─[VersionCCCommandClassGet]
                          CC: Version
16:18:48.812 SERIAL « [ACK]                                                                   (0x06)
16:18:48.818 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:48.819 SERIAL » [ACK]                                                                   (0x06)
16:18:48.820 DRIVER « [RES] [SendData]
                        was sent: true
16:18:49.025 SERIAL « 0x010700139a00001564                                                 (9 bytes)
16:18:49.025 SERIAL » [ACK]                                                                   (0x06)
16:18:49.026 DRIVER « [REQ] [SendData]
                        callback id:     154
                        transmit status: OK
16:18:49.145 SERIAL « 0x010a000400060486148602e5                                          (12 bytes)
16:18:49.146 SERIAL » [ACK]                                                                   (0x06)
16:18:49.147 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Version
                          version: 2
16:18:49.152 CNTRLR   [Node 006]   supports CC Version (0x86) in version 2
16:18:49.152 CNTRLR » [Node 006]   querying the CC version for Firmware Update Meta Data...
16:18:49.158 SERIAL » 0x010a0013060386137a259bb2                                          (12 bytes)
16:18:49.159 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      155
                      └─[VersionCCCommandClassGet]
                          CC: Firmware Update Meta Data
16:18:49.161 SERIAL « [ACK]                                                                   (0x06)
16:18:49.167 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:49.168 SERIAL » [ACK]                                                                   (0x06)
16:18:49.168 DRIVER « [RES] [SendData]
                        was sent: true
16:18:49.376 SERIAL « 0x010700139b00001666                                                 (9 bytes)
16:18:49.377 SERIAL » [ACK]                                                                   (0x06)
16:18:49.384 DRIVER « [REQ] [SendData]
                        callback id:     155
                        transmit status: OK
16:18:49.494 SERIAL « 0x010a000400060486147a0219                                          (12 bytes)
16:18:49.495 SERIAL » [ACK]                                                                   (0x06)
16:18:49.496 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[VersionCCCommandClassReport]
                          CC:      Firmware Update Meta Data
                          version: 2
16:18:49.497 CNTRLR   [Node 006]   supports CC Firmware Update Meta Data (0x7a) in version 2
16:18:49.498 CNTRLR   [Node 006]   skipping query for Powerlevel (0x73) because max implemented vers
                      ion is 0
16:18:49.498 CNTRLR   [Node 006]   skipping query for Device Reset Locally (0x5a) because max implem
                      ented version is 1
16:18:49.498 CNTRLR   [Node 006]   skipping query for Hail (0x82) because max implemented version is
                       1
16:18:49.498 CNTRLR   [Node 006] [+] [Version] interviewComplete: true       [Endpoint 0] [internal]
16:18:49.499 CNTRLR   [Node 006] trying to load device config
16:18:49.520 CNTRLR   [Node 006] device config loaded
16:18:49.543 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
16:18:49.544 CNTRLR » [Node 006] querying Z-Wave+ information...
16:18:49.551 SERIAL » 0x0109001306025e01259c07                                            (11 bytes)
16:18:49.551 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      156
                      └─[ZWavePlusCCGet]
16:18:49.555 SERIAL « [ACK]                                                                   (0x06)
16:18:49.561 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:18:49.562 SERIAL » [ACK]                                                                   (0x06)
16:18:49.564 DRIVER « [RES] [SendData]
                        was sent: true
16:18:49.721 SERIAL « 0x010700139c00001067                                                 (9 bytes)
16:18:49.721 SERIAL » [ACK]                                                                   (0x06)
16:18:49.722 DRIVER « [REQ] [SendData]
                        callback id:     156
                        transmit status: OK
16:18:55.169 SERIAL « 0x011800040006123202a1640001db93000000000000000105004f              (26 bytes)
16:18:55.173 CNTRLR   [Node 006] [Meter] value[66561]: metadata updated                 [Endpoint 0]
16:18:55.176 CNTRLR   [Node 006] [Meter] previousValue[66561]: metadata updated         [Endpoint 0]
16:18:55.179 CNTRLR   [Node 006] [Meter] deltaTime[66561]: metadata updated             [Endpoint 0]
16:18:55.181 CNTRLR   [Node 006] [+] [Meter] value[66561]: 121.747                      [Endpoint 0]
16:18:55.182 CNTRLR   [Node 006] [+] [Meter] deltaTime[66561]: 0                        [Endpoint 0]
16:18:55.183 SERIAL » [ACK]                                                                   (0x06)
16:18:55.186 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:       Electric
                          scale:      V
                          rate type:  Consumed
                          value:      121.747
                          time delta: 0 seconds
16:18:55.367 SERIAL « 0x011800040006123202a16c00000000000000000000000105000e              (26 bytes)
16:18:55.371 CNTRLR   [Node 006] [Meter] value[66817]: metadata updated                 [Endpoint 0]
16:18:55.375 CNTRLR   [Node 006] [Meter] previousValue[66817]: metadata updated         [Endpoint 0]
16:18:55.377 CNTRLR   [Node 006] [Meter] deltaTime[66817]: metadata updated             [Endpoint 0]
16:18:55.378 CNTRLR   [Node 006] [+] [Meter] value[66817]: 0                            [Endpoint 0]
16:18:55.379 CNTRLR   [Node 006] [+] [Meter] deltaTime[66817]: 0                        [Endpoint 0]
16:18:55.380 SERIAL » [ACK]                                                                   (0x06)
16:18:55.382 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:       Electric
                          scale:      A
                          rate type:  Consumed
                          value:      0
                          time delta: 0 seconds
16:18:55.569 SERIAL « 0x01180004000612320221640000aec9003c0000aec900010500ba              (26 bytes)
16:18:55.572 CNTRLR   [Node 006] [Meter] value[65537]: metadata updated                 [Endpoint 0]
16:18:55.577 CNTRLR   [Node 006] [Meter] previousValue[65537]: metadata updated         [Endpoint 0]
16:18:55.580 CNTRLR   [Node 006] [Meter] deltaTime[65537]: metadata updated             [Endpoint 0]
16:18:55.581 CNTRLR   [Node 006] [+] [Meter] value[65537]: 44.745                       [Endpoint 0]
16:18:55.582 CNTRLR   [Node 006] [+] [Meter] previousValue[65537]: 44.745               [Endpoint 0]
16:18:55.584 CNTRLR   [Node 006] [+] [Meter] deltaTime[65537]: 60                       [Endpoint 0]
16:18:55.585 SERIAL » [ACK]                                                                   (0x06)
16:18:55.594 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:        Electric
                          scale:       kWh
                          rate type:   Consumed
                          value:       44.745
                          time delta:  60 seconds
                          prev. value: 44.745
16:18:55.768 SERIAL « 0x0118000400061232022174000000000000000000000001050096              (26 bytes)
16:18:55.769 CNTRLR   [Node 006] [Meter] value[66049]: metadata updated                 [Endpoint 0]
16:18:55.771 CNTRLR   [Node 006] [Meter] previousValue[66049]: metadata updated         [Endpoint 0]
16:18:55.772 CNTRLR   [Node 006] [Meter] deltaTime[66049]: metadata updated             [Endpoint 0]
16:18:55.773 CNTRLR   [Node 006] [+] [Meter] value[66049]: 0                            [Endpoint 0]
16:18:55.775 CNTRLR   [Node 006] [+] [Meter] deltaTime[66049]: 0                        [Endpoint 0]
16:18:55.776 SERIAL » [ACK]                                                                   (0x06)
16:18:55.778 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:       Electric
                          scale:      W
                          rate type:  Consumed
                          value:      0
                          time delta: 0 seconds
16:18:59.731 CNTRLR   [Node 006] Interview attempt 1/5 failed, retrying in 5000 ms...
16:19:04.732 CNTRLR   [Node 006] Beginning interview - last completed stage: NodeInfo
16:19:04.739 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a partial interview...
16:19:04.740 CNTRLR   [Node 006] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal]
                      te: true => true
16:19:04.741 CNTRLR   [Node 006] trying to load device config
16:19:04.743 CNTRLR   [Node 006] no device config loaded
16:19:04.773 CNTRLR   [Node 006] VersionCC: doing a partial interview...
16:19:04.776 CNTRLR   [Node 006] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal]
                      ue
16:19:04.777 CNTRLR   [Node 006] trying to load device config
16:19:04.793 CNTRLR   [Node 006] device config loaded
16:19:04.795 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
16:19:04.795 CNTRLR » [Node 006] querying Z-Wave+ information...
16:19:04.805 SERIAL » 0x0109001306025e01259d06                                            (11 bytes)
16:19:04.805 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      157
                      └─[ZWavePlusCCGet]
16:19:04.807 SERIAL « [ACK]                                                                   (0x06)
16:19:04.818 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:19:04.818 SERIAL » [ACK]                                                                   (0x06)
16:19:04.820 DRIVER « [RES] [SendData]
                        was sent: true
16:19:04.933 SERIAL « 0x010700139d00000d7b                                                 (9 bytes)
16:19:04.934 SERIAL » [ACK]                                                                   (0x06)
16:19:04.935 DRIVER « [REQ] [SendData]
                        callback id:     157
                        transmit status: OK
16:19:14.947 CNTRLR   [Node 006] Interview attempt 2/5 failed, retrying in 10000 ms...
16:19:24.533 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:19:24.534 SERIAL » [ACK]                                                                   (0x06)
16:19:24.537 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:19:24.548 SERIAL » 0x01110013020a9880b10b6e12079e971e059ea0                            (19 bytes)
16:19:24.549 DRIVER » [Node 002] [REQ] [SendData]
                      │ transmit options: 0x05
                      │ callback id:      158
                      └─[SecurityCCNonceReport]
                          nonce: 0xb10b6e12079e971e
16:19:24.552 SERIAL « [ACK]                                                                   (0x06)
16:19:24.558 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:19:24.559 SERIAL » [ACK]                                                                   (0x06)
16:19:24.559 DRIVER « [RES] [SendData]
                        was sent: true
16:19:24.773 SERIAL « 0x010700139e00001663                                                 (9 bytes)
16:19:24.775 SERIAL » [ACK]                                                                   (0x06)
16:19:24.777 DRIVER « [REQ] [SendData]
                        callback id:     158
                        transmit status: OK
16:19:24.933 SERIAL « 0x0120000400021a98816144f6b19c9881bd8d15a8f8c297d3b18a73f0c4135b678 (34 bytes)
                      a17
16:19:24.936 SERIAL » [ACK]                                                                   (0x06)
16:19:24.940 CNTRLR   [Node 002] [Multilevel Sensor] Air temperature: metadata updated  [Endpoint 0]
16:19:24.944 CNTRLR   [Node 002] [~] [Multilevel Sensor] Air temperature: 72.1 => 72.1  [Endpoint 0]
16:19:24.946 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCCommandEncapsulation]
                        │ sequenced: false
                        └─[MultilevelSensorCCReport]
                            type:  Air temperature
                            scale: Fahrenheit
                            value: 72.1
16:19:24.952 CNTRLR   [Node 006] Beginning interview - last completed stage: NodeInfo
16:19:24.954 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a partial interview...
16:19:24.955 CNTRLR   [Node 006] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal]
                      te: true => true
16:19:24.955 CNTRLR   [Node 006] trying to load device config
16:19:24.956 CNTRLR   [Node 006] no device config loaded
16:19:24.993 CNTRLR   [Node 006] VersionCC: doing a partial interview...
16:19:24.994 CNTRLR   [Node 006] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal]
                      ue
16:19:24.994 CNTRLR   [Node 006] trying to load device config
16:19:25.015 CNTRLR   [Node 006] device config loaded
16:19:25.016 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
16:19:25.016 CNTRLR » [Node 006] querying Z-Wave+ information...
16:19:25.022 SERIAL » 0x0109001306025e01259f04                                            (11 bytes)
16:19:25.023 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      159
                      └─[ZWavePlusCCGet]
16:19:25.028 SERIAL « [ACK]                                                                   (0x06)
16:19:25.032 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:19:25.033 SERIAL » [ACK]                                                                   (0x06)
16:19:25.034 DRIVER « [RES] [SendData]
                        was sent: true
16:19:25.410 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:19:25.412 SERIAL » [ACK]                                                                   (0x06)
16:19:25.420 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:19:27.317 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:19:27.319 SERIAL » [ACK]                                                                   (0x06)
16:19:27.326 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:19:30.192 SERIAL « 0x010700139f00020472                                                 (9 bytes)
16:19:30.192 SERIAL » [ACK]                                                                   (0x06)
16:19:30.193 DRIVER « [REQ] [SendData]
                        callback id:     159
                        transmit status: OK
16:19:31.101 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:19:31.103 SERIAL » [ACK]                                                                   (0x06)
16:19:31.105 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:19:31.584 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:19:31.585 SERIAL » [ACK]                                                                   (0x06)
16:19:31.586 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:19:34.181 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:19:34.183 SERIAL » [ACK]                                                                   (0x06)
16:19:34.185 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:19:37.301 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:19:37.303 SERIAL » [ACK]                                                                   (0x06)
16:19:37.305 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:19:40.209 SERIAL » 0x01110013020a9880a8cd81f690cb54d105a084                            (19 bytes)
16:19:40.210 DRIVER » [Node 002] [REQ] [SendData]
                      │ transmit options: 0x05
                      │ callback id:      160
                      └─[SecurityCCNonceReport]
                          nonce: 0xa8cd81f690cb54d1
16:19:40.210 CNTRLR   [Node 006] Interview attempt 3/5 failed, retrying in 15000 ms...
16:19:40.212 SERIAL « [ACK]                                                                   (0x06)
16:19:40.218 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:19:40.218 SERIAL » [ACK]                                                                   (0x06)
16:19:40.219 DRIVER « [RES] [SendData]
                        was sent: true
16:19:40.591 SERIAL « 0x01070013a00000256e                                                 (9 bytes)
16:19:40.592 SERIAL » [ACK]                                                                   (0x06)
16:19:40.594 DRIVER « [REQ] [SendData]
                        callback id:     160
                        transmit status: OK
16:19:40.741 SERIAL « 0x011f00040002199881441db1d74302c032fd54aaa04e8ba8301d3b16b21a31ac9 (33 bytes)
                      1
16:19:40.743 DRIVER   Message with invalid data received. Dropping it:
                      0x011f00040002199881441db1d74302c032fd54aaa04e8ba8301d3b16b21a31ac91
16:19:40.743 SERIAL » [ACK]                                                                   (0x06)
16:19:55.130 SERIAL « 0x011800040006123202a1640001dbae0000000000000001050072              (26 bytes)
16:19:55.132 CNTRLR   [Node 006] [~] [Meter] value[66561]: 121.747 => 121.774           [Endpoint 0]
16:19:55.134 CNTRLR   [Node 006] [~] [Meter] deltaTime[66561]: 0 => 0                   [Endpoint 0]
16:19:55.135 SERIAL » [ACK]                                                                   (0x06)
16:19:55.136 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:       Electric
                          scale:      V
                          rate type:  Consumed
                          value:      121.774
                          time delta: 0 seconds
16:19:55.210 CNTRLR   [Node 006] Beginning interview - last completed stage: NodeInfo
16:19:55.212 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a partial interview...
16:19:55.213 CNTRLR   [Node 006] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal]
                      te: true => true
16:19:55.213 CNTRLR   [Node 006] trying to load device config
16:19:55.214 CNTRLR   [Node 006] no device config loaded
16:19:55.236 CNTRLR   [Node 006] VersionCC: doing a partial interview...
16:19:55.237 CNTRLR   [Node 006] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal]
                      ue
16:19:55.237 CNTRLR   [Node 006] trying to load device config
16:19:55.250 CNTRLR   [Node 006] device config loaded
16:19:55.251 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
16:19:55.251 CNTRLR » [Node 006] querying Z-Wave+ information...
16:19:55.259 SERIAL » 0x0109001306025e0125a13a                                            (11 bytes)
16:19:55.261 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      161
                      └─[ZWavePlusCCGet]
16:19:55.264 SERIAL « [ACK]                                                                   (0x06)
16:19:55.269 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:19:55.270 SERIAL » [ACK]                                                                   (0x06)
16:19:55.271 DRIVER « [RES] [SendData]
                        was sent: true
16:19:55.350 SERIAL « 0x011800040006123202a16c00000000000000000000000105000e              (26 bytes)
16:19:55.351 CNTRLR   [Node 006] [~] [Meter] value[66817]: 0 => 0                       [Endpoint 0]
16:19:55.352 CNTRLR   [Node 006] [~] [Meter] deltaTime[66817]: 0 => 0                   [Endpoint 0]
16:19:55.353 SERIAL » [ACK]                                                                   (0x06)
16:19:55.357 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:       Electric
                          scale:      A
                          rate type:  Consumed
                          value:      0
                          time delta: 0 seconds
16:19:59.607 SERIAL « 0x01070013a10001b2f9                                                 (9 bytes)
16:19:59.608 SERIAL » [ACK]                                                                   (0x06)
16:19:59.610 DRIVER « [REQ] [SendData]
                        callback id:     161
                        transmit status: OK
16:19:59.694 SERIAL « 0x01180004000612320221640000aec900400000aec900010500c6              (26 bytes)
16:19:59.695 CNTRLR   [Node 006] [~] [Meter] value[65537]: 44.745 => 44.745             [Endpoint 0]
16:19:59.697 CNTRLR   [Node 006] [~] [Meter] previousValue[65537]: 44.745 => 44.745     [Endpoint 0]
16:19:59.698 CNTRLR   [Node 006] [~] [Meter] deltaTime[65537]: 60 => 64                 [Endpoint 0]
16:19:59.699 SERIAL » [ACK]                                                                   (0x06)
16:19:59.701 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:        Electric
                          scale:       kWh
                          rate type:   Consumed
                          value:       44.745
                          time delta:  64 seconds
                          prev. value: 44.745
16:19:59.793 SERIAL « 0x0118000400061232022174000000000000000000000001050096              (26 bytes)
16:19:59.794 CNTRLR   [Node 006] [~] [Meter] value[66049]: 0 => 0                       [Endpoint 0]
16:19:59.795 CNTRLR   [Node 006] [~] [Meter] deltaTime[66049]: 0 => 0                   [Endpoint 0]
16:19:59.796 SERIAL » [ACK]                                                                   (0x06)
16:19:59.797 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:       Electric
                          scale:      W
                          rate type:  Consumed
                          value:      0
                          time delta: 0 seconds
16:20:09.626 CNTRLR   [Node 006] Interview attempt 4/5 failed, retrying in 20000 ms...
16:20:24.532 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:20:24.534 SERIAL » [ACK]                                                                   (0x06)
16:20:24.536 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:20:24.553 SERIAL » 0x01110013020a9880fb89177ed3d70c4505a21c                            (19 bytes)
16:20:24.555 DRIVER » [Node 002] [REQ] [SendData]
                      │ transmit options: 0x05
                      │ callback id:      162
                      └─[SecurityCCNonceReport]
                          nonce: 0xfb89177ed3d70c45
16:20:24.557 SERIAL « [ACK]                                                                   (0x06)
16:20:24.565 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:20:24.566 SERIAL » [ACK]                                                                   (0x06)
16:20:24.569 DRIVER « [RES] [SendData]
                        was sent: true
16:20:24.773 SERIAL « 0x01070013a20000165f                                                 (9 bytes)
16:20:24.774 SERIAL » [ACK]                                                                   (0x06)
16:20:24.784 DRIVER « [REQ] [SendData]
                        callback id:     162
                        transmit status: OK
16:20:24.933 SERIAL « 0x0120000400021a9881b6d4aa5b6338a6af46b0ed4b00c63dfb27e29ccb3b9e87a (34 bytes)
                      358
16:20:24.935 DRIVER   Message with invalid data received. Dropping it:
                      0x0120000400021a9881b6d4aa5b6338a6af46b0ed4b00c63dfb27e29ccb3b9e87a358
16:20:24.935 SERIAL » [ACK]                                                                   (0x06)
16:20:26.487 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:20:26.489 SERIAL » [ACK]                                                                   (0x06)
16:20:26.490 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:20:26.504 SERIAL » 0x01110013020a9880e45ee46b0836671a05a33d                            (19 bytes)
16:20:26.504 DRIVER » [Node 002] [REQ] [SendData]
                      │ transmit options: 0x05
                      │ callback id:      163
                      └─[SecurityCCNonceReport]
                          nonce: 0xe45ee46b0836671a
16:20:26.507 SERIAL « [ACK]                                                                   (0x06)
16:20:26.512 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:20:26.513 SERIAL » [ACK]                                                                   (0x06)
16:20:26.513 DRIVER « [RES] [SendData]
                        was sent: true
16:20:26.723 SERIAL « 0x01070013a30000155d                                                 (9 bytes)
16:20:26.724 SERIAL » [ACK]                                                                   (0x06)
16:20:26.725 DRIVER « [REQ] [SendData]
                        callback id:     163
                        transmit status: OK
16:20:26.883 SERIAL « 0x011f000400021998813580d9fe75f27a79c949524d516de4145bfe8e84d21d2ce (33 bytes)
                      f
16:20:26.886 SERIAL » [ACK]                                                                   (0x06)
16:20:26.888 CNTRLR   [Node 002] [Multilevel Sensor] Humidity: metadata updated         [Endpoint 0]
16:20:26.891 CNTRLR   [Node 002] [~] [Multilevel Sensor] Humidity: 40 => 40             [Endpoint 0]
16:20:26.893 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCCommandEncapsulation]
                        │ sequenced: false
                        └─[MultilevelSensorCCReport]
                            type:  Humidity
                            scale: Percentage value
                            value: 40
16:20:27.772 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:20:27.774 SERIAL » [ACK]                                                                   (0x06)
16:20:27.776 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:20:27.793 SERIAL » 0x01110013020a9880fa0e233bf690351505a4e6                            (19 bytes)
16:20:27.794 DRIVER » [Node 002] [REQ] [SendData]
                      │ transmit options: 0x05
                      │ callback id:      164
                      └─[SecurityCCNonceReport]
                          nonce: 0xfa0e233bf6903515
16:20:27.797 SERIAL « [ACK]                                                                   (0x06)
16:20:27.804 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:20:27.804 SERIAL » [ACK]                                                                   (0x06)
16:20:27.805 DRIVER « [RES] [SendData]
                        was sent: true
16:20:28.014 SERIAL « 0x01070013a400001659                                                 (9 bytes)
16:20:28.014 SERIAL » [ACK]                                                                   (0x06)
16:20:28.015 DRIVER « [REQ] [SendData]
                        callback id:     164
                        transmit status: OK
16:20:28.168 SERIAL « 0x011d00040002179881e1f591adae361c95449a10d4fad1c4267016c4ccbcd2    (31 bytes)
16:20:28.171 SERIAL » [ACK]                                                                   (0x06)
16:20:28.174 CNTRLR   [Node 002] [~] [Battery] level: 100 => 100                        [Endpoint 0]
16:20:28.176 CNTRLR   [Node 002] [~] [Battery] isLow: false => false                    [Endpoint 0]
16:20:28.178 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCCommandEncapsulation]
                        │ sequenced: false
                        └─[BatteryCCReport]
                            level:  100
                            is low: false
16:20:28.331 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:20:28.332 SERIAL » [ACK]                                                                   (0x06)
16:20:28.333 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:20:28.349 SERIAL » 0x01110013020a988090267f6c86fd35dc05a57a                            (19 bytes)
16:20:28.350 DRIVER » [Node 002] [REQ] [SendData]
                      │ transmit options: 0x05
                      │ callback id:      165
                      └─[SecurityCCNonceReport]
                          nonce: 0x90267f6c86fd35dc
16:20:28.352 SERIAL « [ACK]                                                                   (0x06)
16:20:28.357 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:20:28.358 SERIAL » [ACK]                                                                   (0x06)
16:20:28.358 DRIVER « [RES] [SendData]
                        was sent: true
16:20:28.574 SERIAL « 0x01070013a500001658                                                 (9 bytes)
16:20:28.575 SERIAL » [ACK]                                                                   (0x06)
16:20:28.577 DRIVER « [REQ] [SendData]
                        callback id:     165
                        transmit status: OK
16:20:28.735 SERIAL « 0x0120000400021a988176cb14ffb6e0f2a904f99647ede48790b6172789a94274c (34 bytes)
                      be8
16:20:28.737 SERIAL » [ACK]                                                                   (0x06)
16:20:28.740 CNTRLR   [Node 002] [Multilevel Sensor] Illuminance: metadata updated      [Endpoint 0]
16:20:28.742 CNTRLR   [Node 002] [~] [Multilevel Sensor] Illuminance: 28 => 28          [Endpoint 0]
16:20:28.743 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCCommandEncapsulation]
                        │ sequenced: false
                        └─[MultilevelSensorCCReport]
                            type:  Illuminance
                            scale: Lux
                            value: 28
16:20:28.951 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:20:28.955 SERIAL » [ACK]                                                                   (0x06)
16:20:28.957 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:20:28.979 SERIAL » 0x01110013020a9880be4ce33d4fb8afa605a69c                            (19 bytes)
16:20:28.980 DRIVER » [Node 002] [REQ] [SendData]
                      │ transmit options: 0x05
                      │ callback id:      166
                      └─[SecurityCCNonceReport]
                          nonce: 0xbe4ce33d4fb8afa6
16:20:28.984 SERIAL « [ACK]                                                                   (0x06)
16:20:28.990 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:20:28.991 SERIAL » [ACK]                                                                   (0x06)
16:20:28.993 DRIVER « [RES] [SendData]
                        was sent: true
16:20:29.366 SERIAL « 0x01070013a60000266b                                                 (9 bytes)
16:20:29.368 SERIAL » [ACK]                                                                   (0x06)
16:20:29.369 DRIVER « [REQ] [SendData]
                        callback id:     166
                        transmit status: OK
16:20:29.521 SERIAL « 0x011f0004000219988184782c053bafa032d79d63b5c74bbeb54346366608ded07 (33 bytes)
                      d
16:20:29.523 SERIAL » [ACK]                                                                   (0x06)
16:20:29.526 CNTRLR   [Node 002] [Multilevel Sensor] Ultraviolet: metadata updated      [Endpoint 0]
16:20:29.528 CNTRLR   [Node 002] [~] [Multilevel Sensor] Ultraviolet: 0 => 0            [Endpoint 0]
16:20:29.531 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCCommandEncapsulation]
                        │ sequenced: false
                        └─[MultilevelSensorCCReport]
                            type:  Ultraviolet
                            scale: UV index
                            value: 0
16:20:29.627 CNTRLR   [Node 006] Beginning interview - last completed stage: NodeInfo
16:20:29.629 CNTRLR   [Node 006] ManufacturerSpecificCC: doing a partial interview...
16:20:29.629 CNTRLR   [Node 006] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal]
                      te: true => true
16:20:29.630 CNTRLR   [Node 006] trying to load device config
16:20:29.631 CNTRLR   [Node 006] no device config loaded
16:20:29.657 CNTRLR   [Node 006] VersionCC: doing a partial interview...
16:20:29.658 CNTRLR   [Node 006] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal]
                      ue
16:20:29.659 CNTRLR   [Node 006] trying to load device config
16:20:29.693 CNTRLR   [Node 006] device config loaded
16:20:29.694 CNTRLR   [Node 006] ZWavePlusCC: doing a complete interview...
16:20:29.695 CNTRLR » [Node 006] querying Z-Wave+ information...
16:20:29.702 SERIAL » 0x0109001306025e0125a73c                                            (11 bytes)
16:20:29.703 DRIVER » [Node 006] [REQ] [SendData]
                      │ transmit options: 0x25
                      │ callback id:      167
                      └─[ZWavePlusCCGet]
16:20:29.705 SERIAL « [ACK]                                                                   (0x06)
16:20:29.827 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:20:29.827 SERIAL » [ACK]                                                                   (0x06)
16:20:29.828 DRIVER « [RES] [SendData]
                        was sent: true
16:20:31.382 SERIAL « 0x0108000400020298402b                                              (10 bytes)
16:20:31.383 SERIAL » [ACK]                                                                   (0x06)
16:20:31.384 DRIVER « [Node 002] [REQ] [ApplicationCommand]
                      └─[SecurityCCNonceGet]
16:20:33.481 SERIAL « 0x01070013a700017934                                                 (9 bytes)
16:20:33.483 SERIAL » [ACK]                                                                   (0x06)
16:20:33.486 DRIVER « [REQ] [SendData]
                        callback id:     167
                        transmit status: OK
16:20:43.509 SERIAL » 0x01110013020a98801ac6c97a160721e105a8fe                            (19 bytes)
16:20:43.510 DRIVER » [Node 002] [REQ] [SendData]
                      │ transmit options: 0x05
                      │ callback id:      168
                      └─[SecurityCCNonceReport]
                          nonce: 0x1ac6c97a160721e1
16:20:43.511 CNTRLR   [Node 006] Failed all interview attempts, giving up.
16:20:43.512 SERIAL « [ACK]                                                                   (0x06)
16:20:43.518 SERIAL « 0x0104011301e8                                                       (6 bytes)
16:20:43.518 SERIAL » [ACK]                                                                   (0x06)
16:20:43.519 DRIVER « [RES] [SendData]
                        was sent: true
16:20:43.650 SERIAL « 0x01070013a800000d4e                                                 (9 bytes)
16:20:43.651 SERIAL » [ACK]                                                                   (0x06)
16:20:43.652 DRIVER « [REQ] [SendData]
                        callback id:     168
                        transmit status: OK
16:20:55.095 SERIAL « 0x011800040006123202a1640001dbfb0000000000000001050027              (26 bytes)
16:20:55.101 CNTRLR   [Node 006] [~] [Meter] value[66561]: 121.774 => 121.851           [Endpoint 0]
16:20:55.103 CNTRLR   [Node 006] [~] [Meter] deltaTime[66561]: 0 => 0                   [Endpoint 0]
16:20:55.104 SERIAL » [ACK]                                                                   (0x06)
16:20:55.106 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:       Electric
                          scale:      V
                          rate type:  Consumed
                          value:      121.851
                          time delta: 0 seconds
16:20:55.195 SERIAL « 0x011800040006123202a16c00000000000000000000000105000e              (26 bytes)
16:20:55.196 CNTRLR   [Node 006] [~] [Meter] value[66817]: 0 => 0                       [Endpoint 0]
16:20:55.198 CNTRLR   [Node 006] [~] [Meter] deltaTime[66817]: 0 => 0                   [Endpoint 0]
16:20:55.198 SERIAL » [ACK]                                                                   (0x06)
16:20:55.200 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:       Electric
                          scale:      A
                          rate type:  Consumed
                          value:      0
                          time delta: 0 seconds
16:20:55.293 SERIAL « 0x01180004000612320221640000aec900380000aec900010500be              (26 bytes)
16:20:55.295 CNTRLR   [Node 006] [~] [Meter] value[65537]: 44.745 => 44.745             [Endpoint 0]
16:20:55.297 CNTRLR   [Node 006] [~] [Meter] previousValue[65537]: 44.745 => 44.745     [Endpoint 0]
16:20:55.298 CNTRLR   [Node 006] [~] [Meter] deltaTime[65537]: 64 => 56                 [Endpoint 0]
16:20:55.299 SERIAL » [ACK]                                                                   (0x06)
16:20:55.302 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:        Electric
                          scale:       kWh
                          rate type:   Consumed
                          value:       44.745
                          time delta:  56 seconds
                          prev. value: 44.745
16:20:55.395 SERIAL « 0x0118000400061232022174000000000000000000000001050096              (26 bytes)
16:20:55.397 CNTRLR   [Node 006] [~] [Meter] value[66049]: 0 => 0                       [Endpoint 0]
16:20:55.401 CNTRLR   [Node 006] [~] [Meter] deltaTime[66049]: 0 => 0                   [Endpoint 0]
16:20:55.403 SERIAL » [ACK]                                                                   (0x06)
16:20:55.406 DRIVER « [Node 006] [REQ] [ApplicationCommand]
                      └─[MeterCCReport]
                          type:       Electric
                          scale:      W
                          rate type:  Consumed
                          value:      0
                          time delta: 0 seconds



@AlCalzone
Copy link
Member

AlCalzone commented Jan 13, 2021

Node 6 claims it supports Z-Wave+ CC, but does not respond when we query that CC's info. This is something that keeps popping up. I'm gonna discuss the generic issue with a contact who has in-depth knowledge - maybe we can solve it once and for all.

@AlCalzone AlCalzone added the bug Something isn't working label Jan 13, 2021
@robertsLando robertsLando removed their assignment Jan 14, 2021
@tommyjlong
Copy link
Contributor Author

As a reference/FYI, when I do the same thing in zwave2mqtt using OZW, OZW also does not get a response on zwaveplus info, but seems to keep on going; getting to what I think is its final state of "Complete":

.....
2021-01-14 14:26:10.215 Detail, Node006, AdvanceQueries queryPending=0 queryRetries=0 queryStage=NodePlusInfo live=1
2021-01-14 14:26:10.215 Detail, Node006, QueryStage_NodePlusInfo
2021-01-14 14:26:10.216 Detail, Node006, Queuing (Query) ZWavePlusInfoCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x5e, 0x01, 0x25, 0xde, 0x45
2021-01-14 14:26:10.216 Detail, Node006, Queuing (Query) Query Stage Complete (NodePlusInfo)
2021-01-14 14:26:10.217 Detail, 
2021-01-14 14:26:10.217 Info, Node006, Sending (Query) message (Callback ID=0xde, Expected Reply=0x04) - ZWavePlusInfoCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x5e, 0x01, 0x25, 0xde, 0x45
2021-01-14 14:26:10.217 Info, Node006, Encrypted Flag is 0
2021-01-14 14:26:10.244 Detail, Node006,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2021-01-14 14:26:10.245 Detail, Node006,   ZW_SEND_DATA delivered to Z-Wave stack
2021-01-14 14:26:10.426 Detail, Node006,   Received: 0x01, 0x07, 0x00, 0x13, 0xde, 0x00, 0x00, 0x14, 0x21
2021-01-14 14:26:10.428 Detail, Node006,   ZW_SEND_DATA Request with callback ID 0xde received (expected 0xde)
2021-01-14 14:26:10.430 Info, Node006, Request RTT 212 Average Request RTT 170
2021-01-14 14:26:10.432 Detail, Node006,   Expected callbackId was received
2021-01-14 14:26:20.218 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "ZWavePlusInfoCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x5e, 0x01, 0x25, 0xde, 0x45"
2021-01-14 14:26:20.218 Detail, Node006, Removing current message
2021-01-14 14:26:20.218 Detail, Node006, Notification: Notification - TimeOut
2021-01-14 14:26:20.219 Detail, Node006, Query Stage Complete (NodePlusInfo)
.....
2021-01-14 14:27:07.653 Detail, Node006, Query Stage Complete (Dynamic)
2021-01-14 14:27:07.653 Detail, Node006, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2021-01-14 14:27:07.653 Detail, Node006, QueryStage_Configuration
2021-01-14 14:27:07.653 Detail, Node006, QueryStage_Complete
2021-01-14 14:27:07.653 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=1
2021-01-14 14:27:07.653 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=1
2021-01-14 14:27:07.654 Info, Saving Cache
..
2021-01-14 14:27:07.661 Info, Node006, Cache Save for Node 6 as its QueryStage_CacheLoad
..
2021-01-14 14:27:07.684 Detail, Node006, Notification: NodeQueriesComplete

@tommyjlong
Copy link
Contributor Author

I would like to try out this fix. I have a question on installation with zwavejsmqtt using npm (install, run build). I just installed zwavejs2mqtt tagged version 1.0.1 and it appears that it installs the zwavejs tagged v6.1.0 driver which does not contain this fix. Is there a way I can use npm to get zwavejs2mqtt to install the zwavejs driver from main (with the fix) instead of the tagged v6.1.0 branch? Sorry I am not familiar with nodejs very much.

@AlCalzone
Copy link
Member

If you go into the zwavejs2mqtt repo and open the docs there you should find a description.
I'm on mobile right now, can't look it up

@tommyjlong
Copy link
Contributor Author

Thanks. I've looked all through the docs. Closest I found was docs on using a custom docker build but couldn't find anything on custom npm install. If you come across something and can provide a pointer, it would be much appreciated.

@AlCalzone
Copy link
Member

@tommyjlong
Copy link
Contributor Author

It took a while to get the install procedure worked out, and I am happy to say, that node-zwavejs v6.1.1 solved this problem and my Smartswitch 6 completed the interview process!! Thanks :)

@Madelaide
Copy link

Madelaide commented Mar 4, 2021

Is this fix in the latest (zw js to mqtt 0.6.0 addon and ha 21.3.0) release? It does not seem to be working for me.

I have two SS6 which do not interview; the ones that were already on the zstick did interview and work fine.

If I try add them via HA nothing at all happens, if i add them on the stick directly, they add fine, but HA doesn't interview them (other devices added this way DO work fine).

in the excerpt below, it is for node 135.

021-03-03 19:23:15.539 INFO ZWAVE: Node 11: value updated: 50-0-deltaTime-66817 0 => 0
2021-03-03 19:23:15.907 INFO ZWAVE: Node 135 is dead
2021-03-03 19:23:20.037 ERROR ZWAVE: Interview of node 135 has failed: The node is dead
2021-03-03 19:23:26.304 INFO ZWAVE: Node 41: value updated: 128-0-level 100 => 100

@AlCalzone
Copy link
Member

Please open a new issue and fill in the requested info. That log snippet is not nearly enough to say anything.

@Madelaide
Copy link

Hi
Yes okay, i thought it was still this ticket as it is the same issue, but that is fine; I won't have access to the system for a few days but will raise it when I return and I'll turn on debug logs vs info (or is TRACE required?)

PS: i finally found the zw version in the integration, if it was fixed in 6.1.1 has it regressed?

Network Connected
ws://a0d7b954-zwavejs2mqtt:3000
Driver Version: 6.6.0
Server Version: 1.1.0

@AlCalzone
Copy link
Member

has it regressed?

🤷🏻‍♂️ I really need to see the detailed log. dead could just mean you have a bad connection.

@Madelaide
Copy link

I understand, I'll try and provide it as soon as I can. The aeotec smart switch 6 are the worst RF devices in my network, see very few neighbours . They see 1-4 where an immediately adjacent fibaro, or even older aeotec smart switch 2 will see 20-30 neighbours.

@Madelaide
Copy link

zwavejs_357.log

Okay, I managed to remote in and run an interview - node 135 is the new node, today at 10 am you can see it fail - interestingly if you search for Node 135, yesterday at 150734 node 135 did a partial interview but seemed to fail - nothing in the UI, and also 136.

Node 11 is another switch, but already in the node list, at the risk of losing it I reinterview, and it completed okay. hopefully this log points out the issue(s)

@Madelaide
Copy link

PS: If you need this in a different ticket I am out of time today but can try move/recreate it over the weekend

@AlCalzone
Copy link
Member

As I suspected: The affected nodes do not acknowledge the commands we send them. That usually indicates a connectivity problem. I can see NoAck a few times for nodes 100, 112, 135, 136 in your log.

@Madelaide
Copy link

For reference, node 11 is 15m away through two double-brick walls and a stud wall, node 135/6 are 4m and 3m, respectively, LOS. I can try moving them within 1m next week as I have gone away for the long-weekend local holiday (mini break).

@AlCalzone
Copy link
Member

AlCalzone commented Mar 5, 2021

Try doing a network heal. I'm lately seeing many issues that can be explained by bad routes (meaning the devices don't know how to reach the controller).

@Madelaide
Copy link

Thankyou. Will try after a stick backup. Last heal I did killed the network and had to revert to a 2 month old archive.

@AlCalzone
Copy link
Member

How's that even possible? You can't change the routes on nodes with a backup.

@blhoward2
Copy link
Collaborator

blhoward2 commented Mar 6, 2021

If they mean a backup of their docker or something maybe poor connectivity and they restored an old cache, thereby requiring less interviewing? I agree a backup of the stick would do little.

@tommyjlong
Copy link
Contributor Author

I can add, that my Smart Switch 6 from a networking/RF point of view was both bad and good. I initially wanted to use in on the 1st floor of my house, but the range was very bad (less than 7m through 1 wall). I moved it to my 2nd floor much further away through more walls and it worked very well. Fortunate for me it was where my washing machine was located, so I use it strictly as an energy meter. I have not had any issues since this problem was fixed, but I am still on 1.0.1/6.1.1. I will try and upgrade in the next day or two just to confirm it still works.

@Madelaide
Copy link

Madelaide commented Mar 7, 2021

How's that even possible? You can't change the routes on nodes with a backup.

The stick became unresponsive in ha after an attempted heal. It was effectively dead in ha -even after snapshot rollback.

So I zensys restored the stick. I didn’t care about the routes. It worked and could talk to it with ha again. So I always backup the switch before trying to heal.

Ps. The neighbours are stored on the stick -they are visible in the zensys matrix view

@Madelaide
Copy link

Madelaide commented Mar 7, 2021

If they mean a backup of their docker or something maybe poor connectivity and they restored an old cache, thereby requiring less interviewing? I agree a backup of the stick would do little.

921 MHz over the house is very good but added two repeaters anyway.

I did purchases a USB loop back tester made in Australia and it found errors on the USB extension I was using. Annoying as it was expensive. But using a test passing cable now.

@Madelaide
Copy link

PS: I moved the controller within 10 cm of the node in question (135) and it interviewed okay.

@AlCalzone
Copy link
Member

AlCalzone commented Mar 11, 2021

That confirms my initial suspicion that the node does not have correct routes. The only way to get that corrected is to heal it while the controller is at its usual location.

Another workaround (assuming that it can even be included where it is) would be:

  • stop zwave-js
  • factory reset the node
  • start zwave-js
  • replace the node with itself (replace failed node)

@Madelaide
Copy link

I would love to heal them, but the command has no effect on the nodes. The neighbour list remains unchanged (and I've tried single healing 4 other different types of devices with neighbours). Maybe I need to raise an issue for that...

@AlCalzone
Copy link
Member

AlCalzone commented Mar 11, 2021

Then maybe the devices really don't see any other neighbors. You (or I) should be able to see in a log what happens exactly. But let's track that in a separate issue.

@cdemi
Copy link

cdemi commented Apr 22, 2021

I see this issue as closed so I am not sure if I am doing something wrong, but I am experiencing the same issue with Aeotec Smart Switch 6. Exactly after inclusion in the interview stage, the node is stuck in "NodeInfo":

image

@AlCalzone
Copy link
Member

Got a log?

@cdemi
Copy link

cdemi commented Apr 22, 2021

Sorry I'm pretty new to HomeAssistant/Z-Wave JS since I am moving from SmartThings. I will figure out how to get the log file and attach it soon

@AlCalzone
Copy link
Member

If you open a new issue, you should find some hints in the issue template. It looks like you're using zwavejs2mqtt, so https://zwave-js.github.io/zwavejs2mqtt/#/troubleshooting/bug_report?id=export-logs could help you aswell.
Please make sure to post the correct log - I need the zwave-js logfile.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working interview overhaul 🔬 Depends on a reworked interview process
Projects
None yet
Development

Successfully merging a pull request may close this issue.

6 participants