Zwave not working with 0.107.0

[faspina (Frank Spina)]

Home assistant core in docker. Update to 0.107.0 zwave not working , says it ready but no devices being picked up

[nickrout]

How to help us help you - or How to ask a good question

Before we begin… This forum is not a helpdesk The people here don’t work for Home Assistant, that’s an open source project. We are volunteering our free time to help others. Not all topics may get an answer, never mind one that helps you solve your problem. [image] This also isn’t a general home automation forum, this is a forum for Home Assistant and things related to it. Any question about Home Assistant, and about using things with Home Assistant, is welcome here. We can’t help you with e…

[jivesinger]

I updated to 0.107.0 today.
Running HA core on a rPi3 with a Gen 5 Zstick.
No issues here.

Anything in you’re logs ?
What devices are not working ?

[faspina (Frank Spina)]

2 ecolink door sensors, 1 linear door sensor, 1 eco link tilt sensor, 1 ge light switch, zoos power strip. I have 6 light switch, 3 thermostats that did were fine so I am Not sure what happens.

These have been installed for years and have gone thorough many upgrades. First time I have seen zwave devices just disappear.

I just had to go through and add them all back.I am stable now.

[MartijnB]

Same problem here.
Lost connection with the Gen 5 stick on HassIO 0.107.0/1/2

Even with the path of the device in the configuration file.
Now back to 0.106.3

[nickrout]

None of you have actually posted any logs or any of the stuff required by the rules. How the hell do you expect help?

[ebnerjoh (Johannes Ebner)]

I am running HA on a Unraid installation (Docker).

After upgrading to 0.107.4 today in the morning most of my Z-Wave Devices are also not working anymore.

If I check the log I do not see any specific error:

2020-03-21 09:09:26 INFO (Dummy-4) [openzwave] Driver ready using library Static Controller version Z-Wave 3.95

2020-03-21 09:09:26 INFO (Dummy-4) [openzwave] home_id 0xc6552925, controller node id is 1

2020-03-21 09:09:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 5 not ready after 30 seconds, continuing anyway

2020-03-21 09:09:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 7 not ready after 30 seconds, continuing anyway

2020-03-21 09:09:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 8 not ready after 30 seconds, continuing anyway

2020-03-21 09:09:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 8 Switch (node_id: 8) not ready after 30 seconds, continuing anyway

2020-03-21 09:09:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 12 not ready after 30 seconds, continuing anyway

2020-03-21 09:09:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 21 not ready after 30 seconds, continuing anyway

[ebnerjoh (Johannes Ebner)]

One note to add - not the whole z-wave network is offline. Only my Fibaro Gen5 Sensors

[Lars_Sjostrom (Lars Sjöström)]

I have spent two days trying to get z-wave back online and working with 0.107.x and I have had no success, I will list my stuff.

[ebnerjoh (Johannes Ebner)]

I have now done a factory reset of the Aeotec Stick and one Wall Plug. Then I have learned the Wall Plug again. It is not working.

This is the OZW-Log:

2020-03-21 11:31:02.860 Always, OpenZwave Version 1.4.3467 Starting Up
2020-03-21 11:31:09.852 Info, Setting Up Provided Network Key for Secure Communications
2020-03-21 11:31:09.853 Info, mgr,     Added driver for controller /dev/ttyACM0
2020-03-21 11:31:09.853 Info,   Opening controller /dev/ttyACM0
2020-03-21 11:31:09.853 Info, Trying to open serial port /dev/ttyACM0 (attempt 1)
2020-03-21 11:31:09.853 Info, Serial port /dev/ttyACM0 opened (attempt 1)
2020-03-21 11:31:09.854 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-03-21 11:31:09.854 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-03-21 11:31:09.854 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-03-21 11:31:09.854 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-03-21 11:31:09.854 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-03-21 11:31:09.854 Detail,
2020-03-21 11:31:09.854 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-03-21 11:31:09.856 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x33, 0x2e, 0x39, 0x35, 0x00, 0x01, 0x99
2020-03-21 11:31:09.856 Detail,
2020-03-21 11:31:09.856 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2020-03-21 11:31:09.856 Info, contrlr,     Static Controller library, version Z-Wave 3.95
2020-03-21 11:31:09.856 Detail, Node045,   Expected reply was received
2020-03-21 11:31:09.857 Detail, Node045,   Message transaction complete
2020-03-21 11:31:09.857 Detail,
2020-03-21 11:31:09.857 Detail, contrlr, Removing current message
2020-03-21 11:31:09.857 Detail,
2020-03-21 11:31:09.857 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-03-21 11:31:09.859 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xe8, 0x69, 0x7d, 0x6e, 0x01, 0x45
2020-03-21 11:31:09.859 Detail,
2020-03-21 11:31:09.859 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xe8697d6e.  Our node ID = 1
2020-03-21 11:31:09.859 Detail, Node105,   Expected reply was received
2020-03-21 11:31:09.859 Detail, Node105,   Message transaction complete
2020-03-21 11:31:09.859 Detail,
2020-03-21 11:31:09.859 Detail, contrlr, Removing current message
2020-03-21 11:31:09.859 Detail,
2020-03-21 11:31:09.859 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-03-21 11:31:09.860 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x28, 0xd7
2020-03-21 11:31:09.860 Detail,
2020-03-21 11:31:09.860 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2020-03-21 11:31:09.860 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
2020-03-21 11:31:09.860 Info, contrlr,     The PC controller is a primary controller.
2020-03-21 11:31:09.860 Detail, Node215,   Expected reply was received
2020-03-21 11:31:09.860 Detail, Node215,   Message transaction complete
2020-03-21 11:31:09.861 Detail,
2020-03-21 11:31:09.861 Detail, contrlr, Removing current message
2020-03-21 11:31:09.861 Detail,
2020-03-21 11:31:09.861 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-03-21 11:31:09.864 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x01, 0x00, 0x00, 0x86, 0x00, 0x01, 0x00, 0x5a, 0xfe, 0x81, 0xff, 0x88, 0x4f, 0x1f, 0x00, 0x00, 0xfb, 0x9f, 0x7d, 0xa0, 0x67, 0x00, 0x00, 0x80, 0x00, 0x80, 0x86, 0x00, 0x00, 0x00, 0xe8, 0x73, 0x00, 0x00, 0x0e, 0x00, 0x00, 0x60, 0x00, 0x00, 0xfb
2020-03-21 11:31:09.864 Detail,
2020-03-21 11:31:09.864 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2020-03-21 11:31:09.864 Info, contrlr,     Serial API Version:   1.0
2020-03-21 11:31:09.864 Info, contrlr,     Manufacturer ID:      0x0086
2020-03-21 11:31:09.864 Info, contrlr,     Product Type:         0x0001
2020-03-21 11:31:09.864 Info, contrlr,     Product ID:           0x005a
2020-03-21 11:31:09.864 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2020-03-21 11:31:09.864 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2020-03-21 11:31:09.864 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2020-03-21 11:31:09.864 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2020-03-21 11:31:09.864 Detail,   Expected reply was received
2020-03-21 11:31:09.864 Detail,   Message transaction complete
2020-03-21 11:31:09.864 Detail,
2020-03-21 11:31:09.864 Detail, contrlr, Removing current message
2020-03-21 11:31:09.864 Detail,
2020-03-21 11:31:09.864 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-03-21 11:31:09.866 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2020-03-21 11:31:09.866 Detail,
2020-03-21 11:31:09.866 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
2020-03-21 11:31:09.866 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2020-03-21 11:31:09.866 Detail, Node172,   Expected reply was received
2020-03-21 11:31:09.866 Detail, Node172,   Message transaction complete
2020-03-21 11:31:09.866 Detail,
2020-03-21 11:31:09.866 Detail, contrlr, Removing current message
2020-03-21 11:31:09.866 Detail,
2020-03-21 11:31:09.866 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2020-03-21 11:31:09.921 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x46, 0x17, 0x5e, 0xeb, 0xbe, 0xa2, 0xc3, 0x73, 0x0b, 0xe1, 0x00, 0x37, 0x62, 0x72, 0xf8, 0x58, 0x82, 0xe6, 0x1c, 0x70, 0x97, 0xe0, 0xc3, 0xf2, 0xfb, 0xb8, 0xae, 0xe0, 0xaf, 0xe6, 0x3a, 0xf3, 0x00
2020-03-21 11:31:09.921 Detail,
2020-03-21 11:31:09.921 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2020-03-21 11:31:09.921 Detail, Node032,   Expected reply was received
2020-03-21 11:31:09.922 Detail, Node032,   Message transaction complete
2020-03-21 11:31:09.922 Detail,
2020-03-21 11:31:09.922 Detail, contrlr, Removing current message
2020-03-21 11:31:09.922 Detail,
2020-03-21 11:31:09.922 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2020-03-21 11:31:10.023 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 0x01, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x05, 0x00, 0xc5
2020-03-21 11:31:10.023 Detail,
2020-03-21 11:31:10.023 Info, mgr,     Driver with Home ID of 0xe8697d6e is now ready.
2020-03-21 11:31:10.023 Info,
2020-03-21 11:31:10.024 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2020-03-21 11:31:10.024 Info, contrlr,     Node 001 - Known
2020-03-21 11:31:10.024 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2020-03-21 11:31:10.024 Detail, Node001, QueryStage_CacheLoad
2020-03-21 11:31:10.024 Info, Node001, Node Identity Codes: 0086:0001:005a
2020-03-21 11:31:10.024 Detail, Node001, QueryStage_Associations
2020-03-21 11:31:10.024 Detail, Node001, QueryStage_Neighbors
2020-03-21 11:31:10.024 Detail, contrlr, Requesting routing info (neighbor list) for Node 1
2020-03-21 11:31:10.024 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2020-03-21 11:31:10.024 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2020-03-21 11:31:10.024 Detail,   Expected reply was received
2020-03-21 11:31:10.024 Detail,   Message transaction complete
2020-03-21 11:31:10.024 Detail,
2020-03-21 11:31:10.024 Detail, contrlr, Removing current message
2020-03-21 11:31:10.024 Detail, Node001, Notification: DriverReady
2020-03-21 11:31:10.025 Detail, Node001, Notification: NodeAdded
2020-03-21 11:31:10.026 Detail, Node001, Notification: NodeProtocolInfo
2020-03-21 11:31:10.026 Detail, Node001, Notification: EssentialNodeQueriesComplete
2020-03-21 11:31:10.027 Detail, Node001, Notification: ValueAdded
2020-03-21 11:31:10.027 Detail, Node001, Notification: NodeNaming
2020-03-21 11:31:10.027 Detail,
2020-03-21 11:31:10.028 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2020-03-21 11:31:10.035 Detail, contrlr,   Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
2020-03-21 11:31:10.035 Detail,
2020-03-21 11:31:10.035 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2020-03-21 11:31:10.035 Detail, Node015,   Expected reply was received
2020-03-21 11:31:10.035 Detail, Node015,   Message transaction complete
2020-03-21 11:31:10.035 Detail,
2020-03-21 11:31:10.035 Detail, contrlr, Removing current message
2020-03-21 11:31:10.035 Detail,
2020-03-21 11:31:10.035 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2020-03-21 11:31:10.036 Detail, contrlr, Removing current message
2020-03-21 11:31:10.036 Detail,
2020-03-21 11:31:10.036 Info, Node001, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2020-03-21 11:31:10.041 Detail, Node001,   Received: 0x01, 0x20, 0x01, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5e
2020-03-21 11:31:10.042 Detail,
2020-03-21 11:31:10.042 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2020-03-21 11:31:10.042 Info, Node001,     Neighbors of this node are:
2020-03-21 11:31:10.042 Info, Node001,  (none reported)
2020-03-21 11:31:10.042 Detail,   Expected reply was received
2020-03-21 11:31:10.042 Detail,   Message transaction complete
2020-03-21 11:31:10.042 Detail,
2020-03-21 11:31:10.042 Detail, Node001, Removing current message
2020-03-21 11:31:10.042 Detail, Node001, Query Stage Complete (Neighbors)
2020-03-21 11:31:10.042 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2020-03-21 11:31:10.042 Detail, Node001, QueryStage_Session
2020-03-21 11:31:10.042 Detail, Node001, QueryStage_Dynamic
2020-03-21 11:31:10.042 Detail, Node001, QueryStage_Configuration
2020-03-21 11:31:10.042 Detail, Node001, QueryStage_Complete
2020-03-21 11:31:10.042 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2020-03-21 11:31:10.042 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
2020-03-21 11:31:10.042 Info,          Node query processing complete.
2020-03-21 11:31:10.042 Detail, Node001, Notification: NodeQueriesComplete
2020-03-21 11:31:10.043 Detail, contrlr, Notification: AllNodesQueried
2020-03-21 11:35:28.250 Detail, Queuing (Controller) Add Device
2020-03-21 11:35:28.250 Info, Add Device
2020-03-21 11:35:28.251 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0a, 0x7b
2020-03-21 11:35:28.251 Detail, Notification: ControllerCommand - Starting
2020-03-21 11:35:28.252 Detail,
2020-03-21 11:35:28.252 Info, contrlr, Sending (Command) message (Callback ID=0x0a, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0a, 0x7b
2020-03-21 11:35:28.255 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x0a, 0x01, 0x00, 0x00, 0xb9
2020-03-21 11:35:28.256 Detail,
2020-03-21 11:35:28.256 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:35:28.256 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2020-03-21 11:35:28.257 Detail, Node001,   Expected callbackId was received
2020-03-21 11:35:28.257 Detail, Node001,   Expected reply was received
2020-03-21 11:35:28.257 Detail, Node001,   Message transaction complete
2020-03-21 11:35:28.258 Detail,
2020-03-21 11:35:28.258 Detail, contrlr, Removing current message
2020-03-21 11:35:28.258 Detail, Notification: ControllerCommand - Waiting
2020-03-21 11:35:28.259 Info, WriteNextMsg Controller nothing to do
2020-03-21 11:35:29.700 Detail,   Received: 0x01, 0x07, 0x00, 0x4a, 0x0a, 0x02, 0x00, 0x00, 0xba
2020-03-21 11:35:29.700 Detail,
2020-03-21 11:35:29.701 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:35:29.701 Info, ADD_NODE_STATUS_NODE_FOUND
2020-03-21 11:35:29.701 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:35:29.805 Detail,   Received: 0x01, 0x1b, 0x00, 0x4a, 0x0a, 0x03, 0x02, 0x14, 0x04, 0x10, 0x01, 0x5e, 0x22, 0x85, 0x59, 0x70, 0x56, 0x5a, 0x7a, 0x72, 0x32, 0x8e, 0x71, 0x73, 0x98, 0x31, 0x25, 0x86, 0xc4
2020-03-21 11:35:29.805 Detail,
2020-03-21 11:35:29.805 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:35:29.805 Info, ADD_NODE_STATUS_ADDING_SLAVE
2020-03-21 11:35:29.805 Info, Adding node ID 2 - Secure
2020-03-21 11:35:29.805 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:35:30.089 Detail,   Received: 0x01, 0x07, 0x00, 0x4a, 0x0a, 0x05, 0x02, 0x00, 0xbf
2020-03-21 11:35:30.089 Detail,
2020-03-21 11:35:30.090 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:35:30.090 Info, ADD_NODE_STATUS_PROTOCOL_DONE
2020-03-21 11:35:30.091 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2020-03-21 11:35:30.091 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:35:30.092 Detail,
2020-03-21 11:35:30.092 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2020-03-21 11:35:30.144 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x0a, 0x06, 0x02, 0x00, 0xbc
2020-03-21 11:35:30.145 Detail,
2020-03-21 11:35:30.145 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:35:30.146 Info, contrlr, ADD_NODE_STATUS_DONE
2020-03-21 11:35:30.151 Info, Node002,   Basic device class    (0x04) - Routing Slave
2020-03-21 11:35:30.151 Info, Node002,   Generic device Class  (0x10) - Binary Switch
2020-03-21 11:35:30.152 Info, Node002,   Specific device class (0x01) - Binary Power Switch
2020-03-21 11:35:30.152 Info, Node002,     COMMAND_CLASS_BASIC will be mapped to COMMAND_CLASS_SWITCH_BINARY
2020-03-21 11:35:30.153 Info, Node002,   Mandatory Command Classes for Node 2:
2020-03-21 11:35:30.153 Info, Node002,     COMMAND_CLASS_BASIC
2020-03-21 11:35:30.153 Info, Node002,     COMMAND_CLASS_SWITCH_BINARY
2020-03-21 11:35:30.154 Info, Node002,     COMMAND_CLASS_SWITCH_ALL
2020-03-21 11:35:30.154 Info, Node002,   Mandatory Command Classes controlled by Node 2:
2020-03-21 11:35:30.154 Info, Node002,     None
2020-03-21 11:35:30.154 Detail, Node002, Queuing (Security) SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x0b, 0x55
2020-03-21 11:35:30.155 Info, Node002,   Optional command classes for node 2:
2020-03-21 11:35:30.155 Info, Node002,     COMMAND_CLASS_ZWAVEPLUS_INFO
2020-03-21 11:35:30.155 Info, Node002,     COMMAND_CLASS_APPLICATION_STATUS
2020-03-21 11:35:30.155 Info, Node002,     COMMAND_CLASS_ASSOCIATION
2020-03-21 11:35:30.156 Info, Node002,   CommandClass 0x59 - NOT REQUIRED
2020-03-21 11:35:30.156 Info, Node002,     COMMAND_CLASS_CONFIGURATION
2020-03-21 11:35:30.156 Info, Node002,     COMMAND_CLASS_CRC_16_ENCAP
2020-03-21 11:35:30.156 Info, Node002,     COMMAND_CLASS_DEVICE_RESET_LOCALLY
2020-03-21 11:35:30.156 Info, Node002,   CommandClass 0x7a - NOT REQUIRED
2020-03-21 11:35:30.157 Info, Node002,     COMMAND_CLASS_MANUFACTURER_SPECIFIC
2020-03-21 11:35:30.157 Info, Node002,     COMMAND_CLASS_METER
2020-03-21 11:35:30.157 Info, Node002,     COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION
2020-03-21 11:35:30.157 Info, Node002,     COMMAND_CLASS_ALARM
2020-03-21 11:35:30.157 Info, Node002,     COMMAND_CLASS_POWERLEVEL
2020-03-21 11:35:30.158 Info, Node002,     COMMAND_CLASS_SECURITY (Existing)
2020-03-21 11:35:30.158 Info, Node002,     COMMAND_CLASS_SENSOR_MULTILEVEL
2020-03-21 11:35:30.158 Info, Node002,     COMMAND_CLASS_SWITCH_BINARY (Existing)
2020-03-21 11:35:30.158 Info, Node002,     COMMAND_CLASS_VERSION
2020-03-21 11:35:30.158 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1
2020-03-21 11:35:30.159 Detail, Node002, QueryStage_ProtocolInfo
2020-03-21 11:35:30.159 Detail, Node002, Queuing (Query) Get Node Protocol Info (Node=2): 0x01, 0x04, 0x00, 0x41, 0x02, 0xb8
2020-03-21 11:35:30.159 Detail, Node002, Queuing (Query) Query Stage Complete (ProtocolInfo)
2020-03-21 11:35:30.160 Info, Node002, Initializing Node. New Node: true (true)
2020-03-21 11:35:30.160 Detail, Node006,   Expected reply was received
2020-03-21 11:35:30.160 Detail, Node006,   Message transaction complete
2020-03-21 11:35:30.161 Detail,
2020-03-21 11:35:30.161 Detail, contrlr, Removing current message
2020-03-21 11:35:30.162 Detail, Node002, Notification: NodeAdded
2020-03-21 11:35:30.164 Detail, Node002, Notification: NodeProtocolInfo
2020-03-21 11:35:30.165 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.167 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.168 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.169 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.169 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.170 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.172 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.175 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.178 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.180 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.181 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.182 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.183 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.184 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.185 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.186 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.186 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.187 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.188 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.190 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.191 Detail, Node002, Notification: ValueAdded
2020-03-21 11:35:30.192 Detail, Notification: ControllerCommand - Completed
2020-03-21 11:35:30.192 Detail,
2020-03-21 11:35:30.192 Info, Node002, Sending (Security) message (Callback ID=0x0b, Expected Reply=0x04) - SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x0b, 0x55
2020-03-21 11:35:30.204 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-03-21 11:35:30.205 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2020-03-21 11:35:30.221 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x0b, 0x00, 0x00, 0x02, 0xe2
2020-03-21 11:35:30.221 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b)
2020-03-21 11:35:30.222 Info, Node002, Request RTT 28 Average Request RTT 28
2020-03-21 11:35:30.222 Detail,   Expected callbackId was received
2020-03-21 11:35:30.233 Detail, Node002,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x02, 0x03, 0x98, 0x05, 0x00, 0x6e
2020-03-21 11:35:30.234 Detail,
2020-03-21 11:35:30.234 Info, Node002, Response RTT 41 Average Response RTT 41
2020-03-21 11:35:30.235 Info, Node002, Received SecurityCmd_SchemeReport from node 2: 0
2020-03-21 11:35:30.235 Info, Node002,     Security scheme agreed.
2020-03-21 11:35:30.235 Detail, Node002, Queuing (Security) SecurityCmd_NetworkKeySet (Node=2): 0x01, 0x19, 0x00, 0x13, 0x02, 0x12, 0x98, 0x06, 0x7a, 0x3e, 0x4c, 0x62, 0x38, 0xd4, 0x78, 0xb9, 0x80, 0x84, 0xa9, 0xbf, 0x1f, 0xfb, 0xb8, 0x2d, 0x25, 0x0c, 0x76
2020-03-21 11:35:30.236 Detail, Node002,   Expected reply and command class was received
2020-03-21 11:35:30.236 Detail, Node002,   Message transaction complete
2020-03-21 11:35:30.236 Detail,
2020-03-21 11:35:30.237 Detail, Node002, Removing current message
2020-03-21 11:35:30.237 Detail,
2020-03-21 11:35:30.237 Info, Node002, Processing (Security) Nonce Request message (Callback ID=0x0c, Expected Reply=0x04)
2020-03-21 11:35:30.238 Info, Node002, Sending (Security) message (Callback ID=0x0c, Expected Reply=0x04) - Nonce_Get(SecurityCmd_NetworkKeySet) - 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x98, 0x40, 0x05, 0x02:
2020-03-21 11:35:30.245 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-03-21 11:35:30.246 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2020-03-21 11:35:30.267 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x02, 0x00, 0x00, 0x03, 0xea
2020-03-21 11:35:30.268 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x02 received (expected 0x02)
2020-03-21 11:35:30.268 Info, Node002, Request RTT 29 Average Request RTT 28
2020-03-21 11:35:30.280 Detail, Node002,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x02, 0x0a, 0x98, 0x80, 0x38, 0xd2, 0x6d, 0x6a, 0x5a, 0xda, 0xa2, 0xb8, 0x8c
2020-03-21 11:35:30.280 Info, Node002, Received SecurityCmd_NonceReport from node 2
2020-03-21 11:35:30.280 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.281 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.281 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.282 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.282 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.282 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.282 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.283 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.283 Info, Node002, Sending (Security) message (Callback ID=0x0d, Expected Reply=0x04) - SecurityCmd_NetworkKeySet (Node=2): 0x01, 0x19, 0x00, 0x13, 0x02, 0x12, 0x98, 0x06, 0x7a, 0x3e, 0x4c, 0x62, 0x38, 0xd4, 0x78, 0xb9, 0x80, 0x84, 0xa9, 0xbf, 0x1f, 0xfb, 0xb8, 0x2d, 0x25, 0x0d, 0x77
2020-03-21 11:35:30.295 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-03-21 11:35:30.296 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2020-03-21 11:35:30.314 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x0d, 0x00, 0x00, 0x03, 0xe5
2020-03-21 11:35:30.315 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x0d)
2020-03-21 11:35:30.315 Info, Node002, Request RTT 76 Average Request RTT 52
2020-03-21 11:35:30.316 Detail,   Expected callbackId was received
2020-03-21 11:35:30.339 Detail, Node002,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x02, 0x02, 0x98, 0x40, 0x2b
2020-03-21 11:35:30.339 Info, Node002, Received SecurityCmd_NonceGet from node 2
2020-03-21 11:35:30.340 Info, NONCES: 0x50, 0xab, 0x6c, 0x88, 0xb4, 0x70, 0xa8, 0xfd
2020-03-21 11:35:30.340 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2020-03-21 11:35:30.341 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2020-03-21 11:35:30.341 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2020-03-21 11:35:30.342 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2020-03-21 11:35:30.342 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2020-03-21 11:35:30.342 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2020-03-21 11:35:30.342 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2020-03-21 11:35:30.342 Info, Node002, Sending (Security) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x02, 0x0a, 0x98, 0x80, 0x50, 0xab, 0x6c, 0x88, 0xb4, 0x70, 0xa8, 0xfd, 0x05, 0x01, 0x67:
2020-03-21 11:35:30.350 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-03-21 11:35:30.351 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2020-03-21 11:35:30.367 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x02, 0xe8
2020-03-21 11:35:30.367 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2020-03-21 11:35:30.368 Info, Node002, Request RTT 129 Average Request RTT 90
2020-03-21 11:35:30.386 Detail, Node002,   Received: 0x01, 0x1c, 0x00, 0x04, 0x00, 0x02, 0x16, 0x98, 0x81, 0xa9, 0x49, 0xb0, 0x33, 0xca, 0x89, 0xba, 0xe2, 0x35, 0x61, 0xf7, 0x50, 0xad, 0x45, 0xc8, 0xb7, 0x2b, 0xf2, 0xb5, 0xe8, 0x72
2020-03-21 11:35:30.386 Info, Raw: 0x98, 0x81, 0xa9, 0x49, 0xb0, 0x33, 0xca, 0x89, 0xba, 0xe2, 0x35, 0x61, 0xf7, 0x50, 0xad, 0x45, 0xc8, 0xb7, 0x2b, 0xf2, 0xb5, 0xe8, 0x72
2020-03-21 11:35:30.386 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.387 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.387 Detail, Node002, Decrypted Packet: 0x10, 0x64, 0x6b
2020-03-21 11:35:30.387 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.388 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.388 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.389 Info, Node001, Setting Up Inclusion Network Key for Secure Communications
2020-03-21 11:35:30.389 Warning, Node002, MAC Authentication of Packet Failed. Dropping
2020-03-21 11:35:30.389 Detail, Node002, Removing current message
2020-03-21 11:35:30.390 Detail,
2020-03-21 11:35:30.390 Info, Node002, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=2): 0x01, 0x04, 0x00, 0x41, 0x02, 0xb8
2020-03-21 11:35:30.392 Detail, Node002,   Received: 0x01, 0x09, 0x01, 0x41, 0xd3, 0x9c, 0x01, 0x04, 0x10, 0x01, 0xed
2020-03-21 11:35:30.393 Detail,
2020-03-21 11:35:30.393 Info, Node002, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO
2020-03-21 11:35:30.394 Info, Node002,   Protocol Info for Node 2:
2020-03-21 11:35:30.394 Info, Node002,     Listening     = true
2020-03-21 11:35:30.394 Info, Node002,     Beaming       = true
2020-03-21 11:35:30.395 Info, Node002,     Routing       = true
2020-03-21 11:35:30.395 Info, Node002,     Max Baud Rate = 40000
2020-03-21 11:35:30.395 Info, Node002,     Version       = 4
2020-03-21 11:35:30.396 Info, Node002,     Security      = false

[ebnerjoh (Johannes Ebner)]

Second Part of the logfile:

2020-03-21 11:35:30.396 Detail, Node156,   Expected reply was received
2020-03-21 11:35:30.396 Detail, Node156,   Message transaction complete
2020-03-21 11:35:30.396 Detail,
2020-03-21 11:35:30.396 Detail, Node002, Removing current message
2020-03-21 11:35:30.397 Detail, Node002, Query Stage Complete (ProtocolInfo)
2020-03-21 11:35:30.397 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1
2020-03-21 11:35:30.398 Detail, Node002, QueryStage_Probe
2020-03-21 11:35:30.398 Info, Node002, NoOperation::Set - Routing=true
2020-03-21 11:35:30.398 Detail, Node002, Queuing (NoOp) NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x0e, 0xce
2020-03-21 11:35:30.399 Detail, Node002, Queuing (Query) Query Stage Complete (Probe)
2020-03-21 11:35:30.399 Detail,
2020-03-21 11:35:30.399 Info, Node002, Sending (NoOp) message (Callback ID=0x0e, Expected Reply=0x13) - NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x0e, 0xce
2020-03-21 11:35:30.407 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-03-21 11:35:30.408 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2020-03-21 11:35:30.423 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x0e, 0x00, 0x00, 0x03, 0xe6
2020-03-21 11:35:30.424 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0e received (expected 0x0e)
2020-03-21 11:35:30.424 Info, Node002, Request RTT 24 Average Request RTT 57
2020-03-21 11:35:30.424 Detail,   Expected callbackId was received
2020-03-21 11:35:30.425 Detail,   Expected reply was received
2020-03-21 11:35:30.425 Detail,   Message transaction complete
2020-03-21 11:35:30.426 Detail,
2020-03-21 11:35:30.426 Detail, Node002, Removing current message
2020-03-21 11:35:30.427 Detail, Node002, Notification: Notification - NoOperation
2020-03-21 11:35:30.428 Detail, Node002, Query Stage Complete (Probe)
2020-03-21 11:35:30.428 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=WakeUp live=1
2020-03-21 11:35:30.428 Detail, Node002, QueryStage_WakeUp
2020-03-21 11:35:30.429 Detail, Node002, QueryStage_ManufacturerSpecific1
2020-03-21 11:35:30.429 Detail, Node002, Checking for ManufacturerSpecific CC and Requesting values if present on this node
2020-03-21 11:35:30.429 Detail, Node002, Queuing (Query) ManufacturerSpecificCmd_Get (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x72, 0x04, 0x25, 0x0f, 0xb9
2020-03-21 11:35:30.429 Detail, Node002, Queuing (Query) Query Stage Complete (ManufacturerSpecific1)
2020-03-21 11:35:30.430 Detail,
2020-03-21 11:35:30.430 Info, Node002, Sending (Query) message (Callback ID=0x0f, Expected Reply=0x04) - ManufacturerSpecificCmd_Get (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x72, 0x04, 0x25, 0x0f, 0xb9
2020-03-21 11:35:30.438 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-03-21 11:35:30.439 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2020-03-21 11:35:30.454 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x0f, 0x00, 0x00, 0x03, 0xe7
2020-03-21 11:35:30.455 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0f received (expected 0x0f)
2020-03-21 11:35:30.455 Info, Node002, Request RTT 24 Average Request RTT 40
2020-03-21 11:35:30.455 Detail,   Expected callbackId was received
2020-03-21 11:35:34.970 Detail, Queuing (Controller) Add Device
2020-03-21 11:35:37.205 Detail, Node002,   Received: 0x01, 0x13, 0x00, 0x49, 0x84, 0x02, 0x0d, 0x04, 0x10, 0x01, 0x5e, 0x22, 0x59, 0x56, 0x7a, 0x32, 0x71, 0x73, 0x98, 0x31, 0xab
2020-03-21 11:35:37.205 Detail,
2020-03-21 11:35:37.206 Info, Node002, UPDATE_STATE_NODE_INFO_RECEIVED from node 2
2020-03-21 11:35:37.206 Detail, Node002, AdvanceQueries queryPending=1 queryRetries=0 queryStage=ManufacturerSpecific1 live=1
2020-03-21 11:35:40.431 Error, Node002, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-03-21 11:35:40.431 Detail, Node002, Removing current message
2020-03-21 11:35:40.431 Detail, Node002, Notification: Notification - TimeOut
2020-03-21 11:35:40.432 Info, Add Device
2020-03-21 11:35:40.432 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x10, 0x61
2020-03-21 11:35:40.432 Detail, Notification: ControllerCommand - Starting
2020-03-21 11:35:40.433 Detail,
2020-03-21 11:35:40.433 Info, contrlr, Sending (Command) message (Callback ID=0x10, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x10, 0x61
2020-03-21 11:35:40.435 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x10, 0x01, 0x00, 0x00, 0xa3
2020-03-21 11:35:40.435 Detail,
2020-03-21 11:35:40.435 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:35:40.435 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2020-03-21 11:35:40.435 Detail, Node001,   Expected callbackId was received
2020-03-21 11:35:40.435 Detail, Node001,   Expected reply was received
2020-03-21 11:35:40.435 Detail, Node001,   Message transaction complete
2020-03-21 11:35:40.436 Detail,
2020-03-21 11:35:40.436 Detail, contrlr, Removing current message
2020-03-21 11:35:40.436 Detail, Notification: ControllerCommand - Waiting
2020-03-21 11:35:40.436 Info, WriteNextMsg Controller nothing to do
2020-03-21 11:36:03.135 Detail,   Received: 0x01, 0x14, 0x00, 0x4a, 0x10, 0x02, 0x02, 0x0d, 0x04, 0x10, 0x01, 0x5e, 0x22, 0x59, 0x56, 0x7a, 0x32, 0x71, 0x73, 0x98, 0x31, 0x39
2020-03-21 11:36:03.136 Detail,
2020-03-21 11:36:03.136 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:03.136 Info, ADD_NODE_STATUS_NODE_FOUND
2020-03-21 11:36:03.137 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:36:03.139 Detail,   Received: 0x01, 0x14, 0x00, 0x4a, 0x10, 0x03, 0x02, 0x0d, 0x04, 0x10, 0x01, 0x5e, 0x22, 0x59, 0x56, 0x7a, 0x32, 0x71, 0x73, 0x98, 0x31, 0x38
2020-03-21 11:36:03.139 Detail,
2020-03-21 11:36:03.139 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:03.139 Info, ADD_NODE_STATUS_ADDING_SLAVE
2020-03-21 11:36:03.140 Info, Adding node ID 2 - Secure
2020-03-21 11:36:03.140 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:36:03.414 Detail,   Received: 0x01, 0x07, 0x00, 0x4a, 0x10, 0x05, 0x02, 0x00, 0xa5
2020-03-21 11:36:03.414 Detail,
2020-03-21 11:36:03.414 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:03.414 Info, ADD_NODE_STATUS_PROTOCOL_DONE
2020-03-21 11:36:03.415 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2020-03-21 11:36:03.415 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:36:03.415 Detail,
2020-03-21 11:36:03.415 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2020-03-21 11:36:03.467 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x10, 0x06, 0x02, 0x00, 0xa6
2020-03-21 11:36:03.468 Detail,
2020-03-21 11:36:03.468 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:03.469 Info, contrlr, ADD_NODE_STATUS_DONE
2020-03-21 11:36:03.469 Info, Node002,   Basic device class    (0x04) - Routing Slave
2020-03-21 11:36:03.470 Info, Node002,   Generic device Class  (0x10) - Binary Switch
2020-03-21 11:36:03.470 Info, Node002,   Specific device class (0x01) - Binary Power Switch
2020-03-21 11:36:03.471 Info, Node002,     COMMAND_CLASS_BASIC will be mapped to COMMAND_CLASS_SWITCH_BINARY
2020-03-21 11:36:03.471 Info, Node002,   Mandatory Command Classes for Node 2:
2020-03-21 11:36:03.471 Info, Node002,     COMMAND_CLASS_BASIC
2020-03-21 11:36:03.472 Info, Node002,     COMMAND_CLASS_SWITCH_BINARY
2020-03-21 11:36:03.472 Info, Node002,     COMMAND_CLASS_SWITCH_ALL
2020-03-21 11:36:03.472 Info, Node002,   Mandatory Command Classes controlled by Node 2:
2020-03-21 11:36:03.473 Info, Node002,     None
2020-03-21 11:36:03.473 Detail, Node002, Queuing (Security) SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x11, 0x4f
2020-03-21 11:36:03.473 Info, Node002,   Optional command classes for node 2:
2020-03-21 11:36:03.474 Info, Node002,     COMMAND_CLASS_ZWAVEPLUS_INFO
2020-03-21 11:36:03.474 Info, Node002,     COMMAND_CLASS_APPLICATION_STATUS
2020-03-21 11:36:03.474 Info, Node002,   CommandClass 0x59 - NOT REQUIRED
2020-03-21 11:36:03.475 Info, Node002,     COMMAND_CLASS_CRC_16_ENCAP
2020-03-21 11:36:03.475 Info, Node002,   CommandClass 0x7a - NOT REQUIRED
2020-03-21 11:36:03.476 Info, Node002,     COMMAND_CLASS_METER
2020-03-21 11:36:03.476 Info, Node002,     COMMAND_CLASS_ALARM
2020-03-21 11:36:03.477 Info, Node002,     COMMAND_CLASS_POWERLEVEL
2020-03-21 11:36:03.477 Info, Node002,     COMMAND_CLASS_SECURITY (Existing)
2020-03-21 11:36:03.478 Info, Node002,     COMMAND_CLASS_SENSOR_MULTILEVEL
2020-03-21 11:36:03.478 Info, Node002, Initializing Node. New Node: true (true)
2020-03-21 11:36:03.478 Detail, Node006,   Expected reply was received
2020-03-21 11:36:03.479 Detail, Node006,   Message transaction complete
2020-03-21 11:36:03.479 Detail,
2020-03-21 11:36:03.479 Detail, contrlr, Removing current message
2020-03-21 11:36:03.480 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.483 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.484 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.486 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.487 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.489 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.490 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.492 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.494 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.496 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.498 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.500 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.502 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.503 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.505 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.506 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.508 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.509 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.511 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.513 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.514 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:03.515 Detail, Node002, Notification: NodeRemoved
2020-03-21 11:36:03.519 Detail, Node002, Notification: NodeAdded
2020-03-21 11:36:03.520 Detail, Node002, Notification: NodeProtocolInfo
2020-03-21 11:36:03.522 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.524 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.525 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.526 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.528 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.529 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.531 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.534 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.538 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.539 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.540 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.542 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.542 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.544 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.545 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.546 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.548 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.549 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:03.550 Detail, Notification: ControllerCommand - Completed
2020-03-21 11:36:03.551 Detail,
2020-03-21 11:36:03.551 Info, Node002, Sending (Security) message (Callback ID=0x11, Expected Reply=0x04) - SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x11, 0x4f
2020-03-21 11:36:03.562 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-03-21 11:36:03.563 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2020-03-21 11:36:03.578 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x11, 0x00, 0x00, 0x02, 0xf8
2020-03-21 11:36:03.579 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x11 received (expected 0x11)
2020-03-21 11:36:03.579 Info, Node002, Request RTT 28 Average Request RTT 28
2020-03-21 11:36:03.580 Detail,   Expected callbackId was received
2020-03-21 11:36:06.518 Detail, Queuing (Controller) Add Device
2020-03-21 11:36:13.553 Error, Node002, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-03-21 11:36:13.553 Detail, Node002, Removing current message
2020-03-21 11:36:13.554 Detail, Node002, Notification: Notification - TimeOut
2020-03-21 11:36:13.555 Detail, Notification: ControllerCommand - Error - Failed
2020-03-21 11:36:13.556 Info, Add Device
2020-03-21 11:36:13.557 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x12, 0x63
2020-03-21 11:36:13.557 Detail, Notification: ControllerCommand - Starting
2020-03-21 11:36:13.558 Detail,
2020-03-21 11:36:13.558 Info, contrlr, Sending (Command) message (Callback ID=0x12, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x12, 0x63
2020-03-21 11:36:13.561 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x12, 0x01, 0x00, 0x00, 0xa1
2020-03-21 11:36:13.562 Detail,
2020-03-21 11:36:13.562 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:13.563 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2020-03-21 11:36:13.563 Detail, Node001,   Expected callbackId was received
2020-03-21 11:36:13.564 Detail, Node001,   Expected reply was received
2020-03-21 11:36:13.564 Detail, Node001,   Message transaction complete
2020-03-21 11:36:13.564 Detail,
2020-03-21 11:36:13.565 Detail, contrlr, Removing current message
2020-03-21 11:36:13.565 Detail, Notification: ControllerCommand - Waiting
2020-03-21 11:36:13.566 Info, WriteNextMsg Controller nothing to do
2020-03-21 11:36:42.435 Detail,   Received: 0x01, 0x14, 0x00, 0x4a, 0x12, 0x02, 0x02, 0x0d, 0x04, 0x10, 0x01, 0x5e, 0x22, 0x59, 0x56, 0x7a, 0x32, 0x71, 0x73, 0x98, 0x31, 0x3b
2020-03-21 11:36:42.436 Detail,
2020-03-21 11:36:42.436 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:42.436 Info, ADD_NODE_STATUS_NODE_FOUND
2020-03-21 11:36:42.437 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:36:42.438 Detail,   Received: 0x01, 0x14, 0x00, 0x4a, 0x12, 0x03, 0x02, 0x0d, 0x04, 0x10, 0x01, 0x5e, 0x22, 0x59, 0x56, 0x7a, 0x32, 0x71, 0x73, 0x98, 0x31, 0x3a
2020-03-21 11:36:42.439 Detail,
2020-03-21 11:36:42.439 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:42.439 Info, ADD_NODE_STATUS_ADDING_SLAVE
2020-03-21 11:36:42.440 Info, Adding node ID 2 - Secure
2020-03-21 11:36:42.440 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:36:42.711 Detail,   Received: 0x01, 0x07, 0x00, 0x4a, 0x12, 0x05, 0x02, 0x00, 0xa7
2020-03-21 11:36:42.711 Detail,
2020-03-21 11:36:42.712 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:42.712 Info, ADD_NODE_STATUS_PROTOCOL_DONE
2020-03-21 11:36:42.712 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2020-03-21 11:36:42.713 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:36:42.713 Detail,
2020-03-21 11:36:42.714 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2020-03-21 11:36:42.766 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x12, 0x06, 0x02, 0x00, 0xa4
2020-03-21 11:36:42.766 Detail,
2020-03-21 11:36:42.767 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:42.767 Info, contrlr, ADD_NODE_STATUS_DONE
2020-03-21 11:36:42.768 Info, Node002,   Basic device class    (0x04) - Routing Slave
2020-03-21 11:36:42.768 Info, Node002,   Generic device Class  (0x10) - Binary Switch
2020-03-21 11:36:42.769 Info, Node002,   Specific device class (0x01) - Binary Power Switch
2020-03-21 11:36:42.769 Info, Node002,     COMMAND_CLASS_BASIC will be mapped to COMMAND_CLASS_SWITCH_BINARY
2020-03-21 11:36:42.770 Info, Node002,   Mandatory Command Classes for Node 2:
2020-03-21 11:36:42.770 Info, Node002,     COMMAND_CLASS_BASIC
2020-03-21 11:36:42.770 Info, Node002,     COMMAND_CLASS_SWITCH_BINARY
2020-03-21 11:36:42.770 Info, Node002,     COMMAND_CLASS_SWITCH_ALL
2020-03-21 11:36:42.770 Info, Node002,   Mandatory Command Classes controlled by Node 2:
2020-03-21 11:36:42.771 Info, Node002,     None
2020-03-21 11:36:42.771 Detail, Node002, Queuing (Security) SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x13, 0x4d
2020-03-21 11:36:42.771 Info, Node002,   Optional command classes for node 2:
2020-03-21 11:36:42.772 Info, Node002,     COMMAND_CLASS_ZWAVEPLUS_INFO
2020-03-21 11:36:42.772 Info, Node002,     COMMAND_CLASS_APPLICATION_STATUS
2020-03-21 11:36:42.773 Info, Node002,   CommandClass 0x59 - NOT REQUIRED
2020-03-21 11:36:42.773 Info, Node002,     COMMAND_CLASS_CRC_16_ENCAP
2020-03-21 11:36:42.773 Info, Node002,   CommandClass 0x7a - NOT REQUIRED
2020-03-21 11:36:42.773 Info, Node002,     COMMAND_CLASS_METER
2020-03-21 11:36:42.774 Info, Node002,     COMMAND_CLASS_ALARM
2020-03-21 11:36:42.774 Info, Node002,     COMMAND_CLASS_POWERLEVEL
2020-03-21 11:36:42.775 Info, Node002,     COMMAND_CLASS_SECURITY (Existing)
2020-03-21 11:36:42.775 Info, Node002,     COMMAND_CLASS_SENSOR_MULTILEVEL
2020-03-21 11:36:42.775 Info, Node002, Initializing Node. New Node: true (true)
2020-03-21 11:36:42.775 Detail, Node006,   Expected reply was received
2020-03-21 11:36:42.776 Detail, Node006,   Message transaction complete
2020-03-21 11:36:42.776 Detail,
2020-03-21 11:36:42.776 Detail, contrlr, Removing current message
2020-03-21 11:36:42.776 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.778 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.780 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.781 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.783 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.785 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.787 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.788 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.790 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.792 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.794 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.796 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.797 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.798 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.800 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.801 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.803 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.804 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:36:42.806 Detail, Node002, Notification: NodeRemoved
2020-03-21 11:36:42.809 Detail, Node002, Notification: NodeAdded
2020-03-21 11:36:42.811 Detail, Node002, Notification: NodeProtocolInfo
2020-03-21 11:36:42.812 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.814 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.816 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.816 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.818 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.818 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.821 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.824 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.827 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.828 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.829 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.830 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.831 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.831 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.832 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.833 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.834 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.835 Detail, Node002, Notification: ValueAdded
2020-03-21 11:36:42.835 Detail, Notification: ControllerCommand - Completed
2020-03-21 11:36:42.836 Detail,
2020-03-21 11:36:42.836 Info, Node002, Sending (Security) message (Callback ID=0x13, Expected Reply=0x04) - SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x13, 0x4d
2020-03-21 11:36:42.847 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-03-21 11:36:42.847 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2020-03-21 11:36:42.864 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x13, 0x00, 0x00, 0x03, 0xfb
2020-03-21 11:36:42.864 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x13 received (expected 0x13)
2020-03-21 11:36:42.864 Info, Node002, Request RTT 27 Average Request RTT 27
2020-03-21 11:36:42.864 Detail,   Expected callbackId was received
2020-03-21 11:36:43.590 Detail, Queuing (Controller) Add Device
2020-03-21 11:36:52.837 Error, Node002, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-03-21 11:36:52.838 Detail, Node002, Removing current message
2020-03-21 11:36:52.839 Detail, Node002, Notification: Notification - TimeOut
2020-03-21 11:36:52.840 Detail, Notification: ControllerCommand - Error - Failed
2020-03-21 11:36:52.841 Info, Add Device
2020-03-21 11:36:52.842 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x14, 0x65
2020-03-21 11:36:52.842 Detail, Notification: ControllerCommand - Starting
2020-03-21 11:36:52.843 Detail,
2020-03-21 11:36:52.843 Info, contrlr, Sending (Command) message (Callback ID=0x14, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x14, 0x65
2020-03-21 11:36:52.846 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x14, 0x01, 0x00, 0x00, 0xa7
2020-03-21 11:36:52.847 Detail,
2020-03-21 11:36:52.847 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:36:52.848 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2020-03-21 11:36:52.848 Detail, Node001,   Expected callbackId was received
2020-03-21 11:36:52.849 Detail, Node001,   Expected reply was received
2020-03-21 11:36:52.849 Detail, Node001,   Message transaction complete
2020-03-21 11:36:52.849 Detail,
2020-03-21 11:36:52.849 Detail, contrlr, Removing current message
2020-03-21 11:36:52.850 Detail, Notification: ControllerCommand - Waiting
2020-03-21 11:36:52.850 Info, WriteNextMsg Controller nothing to do
2020-03-21 11:37:09.670 Detail,   Received: 0x01, 0x14, 0x00, 0x4a, 0x14, 0x02, 0x02, 0x0d, 0x04, 0x10, 0x01, 0x5e, 0x22, 0x59, 0x56, 0x7a, 0x32, 0x71, 0x73, 0x98, 0x31, 0x3d
2020-03-21 11:37:09.670 Detail,
2020-03-21 11:37:09.671 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:37:09.671 Info, ADD_NODE_STATUS_NODE_FOUND
2020-03-21 11:37:09.671 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:37:09.673 Detail,   Received: 0x01, 0x14, 0x00, 0x4a, 0x14, 0x03, 0x02, 0x0d, 0x04, 0x10, 0x01, 0x5e, 0x22, 0x59, 0x56, 0x7a, 0x32, 0x71, 0x73, 0x98, 0x31, 0x3c
2020-03-21 11:37:09.674 Detail,
2020-03-21 11:37:09.674 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:37:09.674 Info, ADD_NODE_STATUS_ADDING_SLAVE
2020-03-21 11:37:09.674 Info, Adding node ID 2 - Secure
2020-03-21 11:37:09.675 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:37:09.946 Detail,   Received: 0x01, 0x07, 0x00, 0x4a, 0x14, 0x05, 0x02, 0x00, 0xa1
2020-03-21 11:37:09.946 Detail,
2020-03-21 11:37:09.946 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:37:09.947 Info, ADD_NODE_STATUS_PROTOCOL_DONE
2020-03-21 11:37:09.947 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2020-03-21 11:37:09.948 Detail, Notification: ControllerCommand - InProgress
2020-03-21 11:37:09.948 Detail,
2020-03-21 11:37:09.949 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2020-03-21 11:37:10.001 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x14, 0x06, 0x02, 0x00, 0xa2
2020-03-21 11:37:10.002 Detail,
2020-03-21 11:37:10.002 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:37:10.002 Info, contrlr, ADD_NODE_STATUS_DONE
2020-03-21 11:37:10.003 Info, Node002,   Basic device class    (0x04) - Routing Slave
2020-03-21 11:37:10.003 Info, Node002,   Generic device Class  (0x10) - Binary Switch
2020-03-21 11:37:10.005 Info, Node002,   Specific device class (0x01) - Binary Power Switch
2020-03-21 11:37:10.005 Info, Node002,     COMMAND_CLASS_BASIC will be mapped to COMMAND_CLASS_SWITCH_BINARY
2020-03-21 11:37:10.005 Info, Node002,   Mandatory Command Classes for Node 2:
2020-03-21 11:37:10.006 Info, Node002,     COMMAND_CLASS_BASIC
2020-03-21 11:37:10.006 Info, Node002,     COMMAND_CLASS_SWITCH_BINARY
2020-03-21 11:37:10.006 Info, Node002,     COMMAND_CLASS_SWITCH_ALL
2020-03-21 11:37:10.006 Info, Node002,   Mandatory Command Classes controlled by Node 2:
2020-03-21 11:37:10.007 Info, Node002,     None
2020-03-21 11:37:10.007 Detail, Node002, Queuing (Security) SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x15, 0x4b
2020-03-21 11:37:10.007 Info, Node002,   Optional command classes for node 2:
2020-03-21 11:37:10.007 Info, Node002,     COMMAND_CLASS_ZWAVEPLUS_INFO
2020-03-21 11:37:10.007 Info, Node002,     COMMAND_CLASS_APPLICATION_STATUS
2020-03-21 11:37:10.007 Info, Node002,   CommandClass 0x59 - NOT REQUIRED
2020-03-21 11:37:10.007 Info, Node002,     COMMAND_CLASS_CRC_16_ENCAP
2020-03-21 11:37:10.007 Info, Node002,   CommandClass 0x7a - NOT REQUIRED
2020-03-21 11:37:10.007 Info, Node002,     COMMAND_CLASS_METER
2020-03-21 11:37:10.007 Info, Node002,     COMMAND_CLASS_ALARM
2020-03-21 11:37:10.007 Info, Node002,     COMMAND_CLASS_POWERLEVEL
2020-03-21 11:37:10.007 Info, Node002,     COMMAND_CLASS_SECURITY (Existing)
2020-03-21 11:37:10.007 Info, Node002,     COMMAND_CLASS_SENSOR_MULTILEVEL
2020-03-21 11:37:10.007 Info, Node002, Initializing Node. New Node: true (true)
2020-03-21 11:37:10.007 Detail, Node006,   Expected reply was received
2020-03-21 11:37:10.007 Detail, Node006,   Message transaction complete
2020-03-21 11:37:10.007 Detail,
2020-03-21 11:37:10.007 Detail, contrlr, Removing current message
2020-03-21 11:37:10.007 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.008 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.008 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.009 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.009 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.009 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.010 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.010 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.011 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.011 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.011 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.011 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.012 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.012 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.012 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.013 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.013 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.013 Detail, Node002, Notification: ValueRemoved
2020-03-21 11:37:10.013 Detail, Node002, Notification: NodeRemoved
2020-03-21 11:37:10.014 Detail, Node002, Notification: NodeAdded
2020-03-21 11:37:10.015 Detail, Node002, Notification: NodeProtocolInfo
2020-03-21 11:37:10.016 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.016 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.017 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.017 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.017 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.018 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.018 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.019 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.020 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.021 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.021 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.021 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.021 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.022 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.022 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.022 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.023 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.023 Detail, Node002, Notification: ValueAdded
2020-03-21 11:37:10.023 Detail, Notification: ControllerCommand - Completed
2020-03-21 11:37:10.024 Detail,
2020-03-21 11:37:10.024 Info, Node002, Sending (Security) message (Callback ID=0x15, Expected Reply=0x04) - SecurityCmd_SchemeGet (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x98, 0x04, 0x00, 0x25, 0x15, 0x4b

[ebnerjoh (Johannes Ebner)]

2020-03-21 11:37:10.034 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-03-21 11:37:10.035 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2020-03-21 11:37:10.051 Detail, Node002,   Received: 0x01, 0x07, 0x00, 0x13, 0x15, 0x00, 0x00, 0x02, 0xfc
2020-03-21 11:37:10.051 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x15 received (expected 0x15)
2020-03-21 11:37:10.052 Info, Node002, Request RTT 27 Average Request RTT 27
2020-03-21 11:37:10.052 Detail,   Expected callbackId was received
2020-03-21 11:37:11.440 Detail, Queuing (Controller) Add Device
2020-03-21 11:37:17.240 Detail, Node002,   Received: 0x01, 0x13, 0x00, 0x49, 0x84, 0x02, 0x0d, 0x04, 0x10, 0x01, 0x5e, 0x22, 0x59, 0x56, 0x7a, 0x32, 0x71, 0x73, 0x98, 0x31, 0xab
2020-03-21 11:37:17.240 Detail,
2020-03-21 11:37:17.240 Info, Node002, UPDATE_STATE_NODE_INFO_RECEIVED from node 2
2020-03-21 11:37:17.240 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1
2020-03-21 11:37:17.241 Detail, Node002, QueryStage_ProtocolInfo
2020-03-21 11:37:17.241 Detail, Node002, Queuing (Query) Get Node Protocol Info (Node=2): 0x01, 0x04, 0x00, 0x41, 0x02, 0xb8
2020-03-21 11:37:17.241 Detail, Node002, Queuing (Query) Query Stage Complete (ProtocolInfo)
2020-03-21 11:37:20.024 Error, Node002, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-03-21 11:37:20.024 Detail, Node002, Removing current message
2020-03-21 11:37:20.024 Detail, Node002, Notification: Notification - TimeOut
2020-03-21 11:37:20.025 Detail, Notification: ControllerCommand - Error - Failed
2020-03-21 11:37:20.025 Info, Add Device
2020-03-21 11:37:20.025 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x16, 0x67
2020-03-21 11:37:20.025 Detail, Notification: ControllerCommand - Starting
2020-03-21 11:37:20.025 Detail,
2020-03-21 11:37:20.025 Info, contrlr, Sending (Command) message (Callback ID=0x16, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x16, 0x67
2020-03-21 11:37:20.028 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x16, 0x01, 0x00, 0x00, 0xa5
2020-03-21 11:37:20.028 Detail,
2020-03-21 11:37:20.028 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2020-03-21 11:37:20.028 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2020-03-21 11:37:20.028 Detail, Node001,   Expected callbackId was received
2020-03-21 11:37:20.028 Detail, Node001,   Expected reply was received
2020-03-21 11:37:20.028 Detail, Node001,   Message transaction complete
2020-03-21 11:37:20.028 Detail,
2020-03-21 11:37:20.028 Detail, contrlr, Removing current message
2020-03-21 11:37:20.028 Detail, Notification: ControllerCommand - Waiting
2020-03-21 11:37:20.028 Info, WriteNextMsg Controller nothing to do
2020-03-21 11:37:21.688 Detail, Queuing (Controller) Add Device
2020-03-21 11:37:21.688 Info, WriteNextMsg Controller nothing to do

[Lars_Sjostrom (Lars Sjöström)]

I have AEON Labs ZW090 Z-Stick Gen5 EU USB Controller.
3 x AEON Labs ZW117 Range Extender 6
1 x FIBARO System FGBS222 Smart Implant
6 x FGWPE/F Wall Plug Gen5
I have reset the stick and then I managed to add one AEON Labs ZW117 Range Extender 6
but then the other one showed up unknown node.
I could not add the wall plugs, I managed to do this once but after restarting it showed up as unknown node. Also trying to add the plug again did not help It crashed home assistant docker so that I had to restart it. The network takes forever to boot up.
Last night I hade reset everything and tried again I managed to add 1 x AEON Labs ZW117 Range Extender 6 the other two refused to show they were marked as unknown nodes.
I gave up this morning because I am married… and my wife is not happy about this…
I installed the old version and 0.106.6 I just had to go into integrations and remove and add the z-wave and it was working. All three Range extender showed up instantly.
I tried to add a wall plug and the smart implant and it was added just fine again.
Thanks to fear of the corona, I have been home and had the time to try to reset and start over multiple times with no or little success with the new version…. Spent two entire days on this.

Running Home Assistant in docker on a Synology NAS. (Not hass.io)

Side note: Also the Verisure Door lock seems to be kind of buggy in the new version it is not found on every boot…

[ebnerjoh (Johannes Ebner)]

Hi, what exactly have you done after the downgrade?

[Lars_Sjostrom (Lars Sjöström)]

I just removed z-wave from the integration in GUI and added z-wave again and it was working.

[ebnerjoh (Johannes Ebner)]

Ok, found it.

I have done the same. Downgraded to 0.106.6 and removed and added z-wave.

No difference for me…
I can only see my Wall Plug and Relay, the Door Sensor and Motion Sensors are not apearing anymore.

[Lars_Sjostrom (Lars Sjöström)]

I used the old backup files also on downgrade so the new ha have not messed up any database stuff

[ebnerjoh (Johannes Ebner)]

I would need the zwcfg…xml and pyozw.sqlite?

Or did you made a complete restore of home assistant?

[ebnerjoh (Johannes Ebner)]

Thanks, that helped,

Lukily I had a bakcup from this night. So I went back to 0.107.1 and now everything is working again.

[faspina (Frank Spina)]

I got a back up from sunday before 107. Use the 106.6 image and zwave is back and working although all my hacs plugin are hosed and hacs is not installing. But I think I can work with this.