Z-wave network down since a server restart, no clue how to recover ("solved")

mclaeys · September 9, 2019, 7:35pm

Hi all,

Since an hour or 2 I have a mayor issue with my Z-wave network, as it’s not starting anymore after a Home Assistant restart.
I can’t seem to find a solution for it. it looks like my network key isn’t working anymore, but I can’t figure out why.

Long story short, I migrated last week from an old Home Assistant server to a new one. Because I didn’t rename my Z-wave entities on the old one and hadn’t added them secure, I wanted to start over completely so I removed them all from the old one. Moved my Aeon stick gen 5 to the new one, generated a network key and started adding them again secure.

I added the following in my config:

#Z-wave
zwave:
  usb_path: /dev/ttyACM1
  network_key: !secret z_wave_key

In my secret file I added the key (different values but same format):

z_wave_key: "0x38, 0x30, 0xe1, 0x5c, 0xe4, 0x42, 0xb0, 0xbe, 0x3b, 0xa8, 0x17, 0xb7, 0xb3, 0xba, 0xab, 0x30"

I added all my devices as add secure and it worked flawless. One “unknown node” in the list, a node that somehow failed during the pairing process or so, but eventually it paired succesfully the second time (so it’s twice in the node list, once as unknown and once as the actual node).

Since I was making some changes to my config related to Google assistant I restarted Home Assistant. Z-wave didn’t come up anymore since then. I have been using it yesterday, so I know the pairing worked in the first place and the devices did work.

I was almost done renaming all entities to something I can at least know what it is.
I restarted HA again
I restarted the complete server
I opened the Options.xml with the Z-wave options, added the same key in there too in the network key field.
I removed zwave from the configuration.yaml to see if the integration would take over (in this case it pointed to the wrong USB device for some reason)
I removed the key from secret file and added it directly in the configuration.yaml

none of it worked though

In OZW_log.txt I find the following info:

2019-09-09 20:49:04.512 Always, OpenZwave Version 1.4.3440 Starting Up
2019-09-09 20:49:11.962 Info, Setting Up Provided Network Key for Secure Communications
2019-09-09 20:49:11.962 Info, mgr,     Added driver for controller /dev/ttyACM1
2019-09-09 20:49:11.967 Info,   Opening controller /dev/ttyACM1
2019-09-09 20:49:11.967 Info, Trying to open serial port /dev/ttyACM1 (attempt 1)
2019-09-09 20:49:13.925 Info, Serial port /dev/ttyACM1 opened (attempt 1)
2019-09-09 20:49:13.925 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-09-09 20:49:13.926 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-09-09 20:49:13.926 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-09-09 20:49:13.926 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-09-09 20:49:13.926 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-09-09 20:49:13.926 Detail, 
2019-09-09 20:49:13.926 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-09-09 20:49:13.928 Warning, WARNING: Out of frame flow! (0x98).  Sending NAK.
2019-09-09 20:49:13.928 Warning, WARNING: Out of frame flow! (0x40).  Sending NAK.
2019-09-09 20:49:13.931 Warning, WARNING: Out of frame flow! (0x65).  Sending NAK.
2019-09-09 20:49:13.931 Detail, Unsolicited message received while waiting for ACK.
2019-09-09 20:49:13.951 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-09 20:49:13.951 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-09 20:49:13.951 Warning, Node076, Couldn't Generate Nonce Key for Node 76
2019-09-09 20:49:13.951 Detail, Unsolicited message received while waiting for ACK.
2019-09-09 20:49:13.951 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-09 20:49:13.951 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-09 20:49:13.951 Warning, Node076, Couldn't Generate Nonce Key for Node 76
2019-09-09 20:49:13.951 Detail, Unsolicited message received while waiting for ACK.
2019-09-09 20:49:13.951 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-09 20:49:13.951 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-09 20:49:13.951 Warning, Node076, Couldn't Generate Nonce Key for Node 76
2019-09-09 20:49:13.951 Detail, Unsolicited message received while waiting for ACK.
2019-09-09 20:49:13.951 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-09 20:49:13.951 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-09 20:49:13.951 Warning, Node077, Couldn't Generate Nonce Key for Node 77
2019-09-09 20:49:13.951 Detail, Unsolicited message received while waiting for ACK.
2019-09-09 20:49:13.951 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-09 20:49:13.951 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-09 20:49:13.951 Warning, Node077, Couldn't Generate Nonce Key for Node 77
2019-09-09 20:49:13.951 Detail, Unsolicited message received while waiting for ACK.
2019-09-09 20:49:13.951 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-09 20:49:13.951 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-09 20:49:13.951 Warning, Node077, Couldn't Generate Nonce Key for Node 77
2019-09-09 20:49:13.951 Detail, Unsolicited message received while waiting for ACK.
2019-09-09 20:49:13.951 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-09 20:49:13.951 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-09 20:49:13.951 Warning, Node077, Couldn't Generate Nonce Key for Node 77
2019-09-09 20:49:13.951 Detail, Unsolicited message received while waiting for ACK.
2019-09-09 20:49:13.954 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98

Anyone who had the same issue? Removing them and adding them again was hell, I don’t really want to do that again (and since the error indicates a key issue and I still have it).

The devices are Fibaro dimmers (24), Fibaro blinds controllers (7), Fibaro RGBW modules (4) and Aeotec power meters (2). Don’t mind the numbers of the nodes in the txt, it started numbering from where it stopped on the previous server, don’t know why.

Just wanted to add, later the messages change, still not functioning though.

2019-09-09 21:32:38.720 Info, Node077, ApplicationCommandHandler - Unhandled Command Class 0x56
2019-09-09 21:32:42.728 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-09 21:32:42.728 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-09 21:32:42.728 Info, NONCES: 0xed, 0x08, 0xa7, 0xa7, 0xd9, 0x58, 0x55, 0xd9
2019-09-09 21:32:42.728 Info, NONCES: 0xb2, 0xc8, 0x55, 0xee, 0x74, 0x5d, 0xdc, 0x0e
2019-09-09 21:32:42.728 Info, NONCES: 0xd6, 0xf0, 0x4f, 0x5f, 0x76, 0xd9, 0x61, 0x8f
2019-09-09 21:32:42.728 Info, NONCES: 0x33, 0xcd, 0xcc, 0x84, 0x3c, 0xcf, 0x2c, 0xd7
2019-09-09 21:32:42.728 Info, NONCES: 0x84, 0xfe, 0x40, 0x63, 0x7f, 0xda, 0xfc, 0x7a
2019-09-09 21:32:42.728 Info, NONCES: 0x9b, 0x09, 0xa1, 0x80, 0x85, 0xea, 0xa1, 0x08
2019-09-09 21:32:42.728 Info, NONCES: 0x6c, 0xca, 0x6f, 0x96, 0xfc, 0xba, 0xc7, 0x44
2019-09-09 21:32:42.728 Info, NONCES: 0x82, 0x79, 0xe8, 0x19, 0xd3, 0x03, 0x50, 0x58
2019-09-09 21:32:42.728 Info, Node076, Sending (Query) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4c, 0x0a, 0x98, 0x80, 0x6c, 0xca, 0x6f, 0x96, 0xfc, 0xba, 0xc7, 0x44, 0x05, 0x01, 0x3d:
2019-09-09 21:32:42.742 Detail, Node044,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-09 21:32:42.742 Detail, Node044,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-09 21:32:42.999 Detail, Node044,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x1a, 0xf0
2019-09-09 21:32:42.999 Detail, Node044,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2019-09-09 21:32:42.999 Info, Node044, Request RTT 2605262 Average Request RTT 2602156
2019-09-09 21:32:43.185 Detail, Node076,   Received: 0x01, 0x2c, 0x00, 0x04, 0x00, 0x4c, 0x26, 0x98, 0x81, 0x64, 0x17, 0xa4, 0xdf, 0xe3, 0xa3, 0x7b, 0x2d, 0x6c, 0x04, 0xe0, 0xe2, 0x07, 0x5f, 0x5d, 0xf8, 0x98, 0xaf, 0xd8, 0x8e, 0xf2, 0x37, 0xae, 0x8c, 0xa6, 0x5e, 0x3e, 0x6c, 0x92, 0x2a, 0x7d, 0x4f, 0xbc, 0xeb, 0x68, 0xe5, 0x51
2019-09-09 21:32:43.185 Info, Raw: 0x98, 0x81, 0x64, 0x17, 0xa4, 0xdf, 0xe3, 0xa3, 0x7b, 0x2d, 0x6c, 0x04, 0xe0, 0xe2, 0x07, 0x5f, 0x5d, 0xf8, 0x98, 0xaf, 0xd8, 0x8e, 0xf2, 0x37, 0xae, 0x8c, 0xa6, 0x5e, 0x3e, 0x6c, 0x92, 0x2a, 0x7d, 0x4f, 0xbc, 0xeb, 0x68, 0xe5, 0x51
2019-09-09 21:32:43.185 Detail, Node076, Decrypted Packet: 0x00, 0x56, 0x01, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x00, 0x00, 0x00, 0x05, 0x00, 0x00, 0x00, 0x00, 0x07, 0xa5
2019-09-09 21:32:43.185 Detail, 
2019-09-09 21:32:43.185 Info, Node076, ApplicationCommandHandler - Unhandled Command Class 0x56
2019-09-09 21:32:43.639 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-09 21:32:43.639 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-09 21:32:43.639 Info, NONCES: 0x18, 0x2d, 0x08, 0x10, 0x95, 0xb4, 0x65, 0x17
2019-09-09 21:32:43.639 Info, NONCES: 0x95, 0x2a, 0x56, 0xcf, 0xd3, 0xc9, 0x17, 0x9a
2019-09-09 21:32:43.639 Info, NONCES: 0xe3, 0xd0, 0x2d, 0x46, 0xc4, 0x26, 0xea, 0x29
2019-09-09 21:32:43.639 Info, NONCES: 0x83, 0x56, 0x47, 0x6a, 0xc1, 0x9a, 0x36, 0x7f
2019-09-09 21:32:43.639 Info, NONCES: 0x2f, 0x0b, 0x91, 0xcf, 0xc0, 0x47, 0x5a, 0x2b
2019-09-09 21:32:43.639 Info, NONCES: 0x6f, 0x7c, 0x02, 0x5b, 0xc0, 0xac, 0xcc, 0x15
2019-09-09 21:32:43.639 Info, NONCES: 0x6d, 0xe2, 0xfb, 0xb6, 0xd9, 0xcf, 0x9c, 0xdc
2019-09-09 21:32:43.639 Info, NONCES: 0xbe, 0xab, 0xbf, 0x28, 0xb2, 0xa2, 0x16, 0x9c
2019-09-09 21:32:43.639 Info, Node077, Sending (Query) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4d, 0x0a, 0x98, 0x80, 0x6f, 0x7c, 0x02, 0x5b, 0xc0, 0xac, 0xcc, 0x15, 0x05, 0x01, 0x59:
2019-09-09 21:32:43.651 Detail, Node044,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-09 21:32:43.651 Detail, Node044,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-09 21:32:43.678 Detail, Node044,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x03, 0xe9
2019-09-09 21:32:43.678 Detail, Node044,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2019-09-09 21:32:43.678 Info, Node044, Request RTT 2605941 Average Request RTT 2604048
2019-09-09 21:32:43.719 Detail, Node077,   Received: 0x01, 0x29, 0x00, 0x04, 0x00, 0x4d, 0x23, 0x98, 0x81, 0xa2, 0x11, 0x9a, 0x1e, 0x7b, 0xe4, 0x57, 0x10, 0x01, 0x19, 0x51, 0xa1, 0x1a, 0x5f, 0xf0, 0xb5, 0xb8, 0xa6, 0x98, 0xd0, 0x30, 0xa0, 0x2d, 0x97, 0x6f, 0x64, 0x7e, 0x08, 0x81, 0xbe, 0xc8, 0x64, 0x6b, 0x5b
2019-09-09 21:32:43.719 Info, Raw: 0x98, 0x81, 0xa2, 0x11, 0x9a, 0x1e, 0x7b, 0xe4, 0x57, 0x10, 0x01, 0x19, 0x51, 0xa1, 0x1a, 0x5f, 0xf0, 0xb5, 0xb8, 0xa6, 0x98, 0xd0, 0x30, 0xa0, 0x2d, 0x97, 0x6f, 0x64, 0x7e, 0x08, 0x81, 0xbe, 0xc8, 0x64, 0x6b, 0x5b
2019-09-09 21:32:43.719 Detail, Node077, Decrypted Packet: 0x00, 0x56, 0x01, 0x32, 0x02, 0x21, 0x74, 0x00, 0x05, 0x2a, 0xbc, 0x00, 0x00, 0x00, 0x4f, 0x81
2019-09-09 21:32:43.719 Detail, 
2019-09-09 21:32:43.719 Info, Node077, ApplicationCommandHandler - Unhandled Command Class 0x56
2019-09-09 21:32:47.730 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-09 21:32:47.730 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-09 21:32:47.730 Info, NONCES: 0xed, 0x08, 0xa7, 0xa7, 0xd9, 0x58, 0x55, 0xd9
2019-09-09 21:32:47.730 Info, NONCES: 0xb2, 0xc8, 0x55, 0xee, 0x74, 0x5d, 0xdc, 0x0e
2019-09-09 21:32:47.730 Info, NONCES: 0xd6, 0xf0, 0x4f, 0x5f, 0x76, 0xd9, 0x61, 0x8f
2019-09-09 21:32:47.730 Info, NONCES: 0x33, 0xcd, 0xcc, 0x84, 0x3c, 0xcf, 0x2c, 0xd7
2019-09-09 21:32:47.730 Info, NONCES: 0x84, 0xfe, 0x40, 0x63, 0x7f, 0xda, 0xfc, 0x7a
2019-09-09 21:32:47.730 Info, NONCES: 0x9b, 0x09, 0xa1, 0x80, 0x85, 0xea, 0xa1, 0x08
2019-09-09 21:32:47.730 Info, NONCES: 0x6c, 0xca, 0x6f, 0x96, 0xfc, 0xba, 0xc7, 0x44
2019-09-09 21:32:47.730 Info, NONCES: 0x99, 0x5f, 0x9c, 0xbe, 0xc7, 0x6c, 0xe5, 0x5c
2019-09-09 21:32:47.730 Info, Node076, Sending (Query) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4c, 0x0a, 0x98, 0x80, 0x99, 0x5f, 0x9c, 0xbe, 0xc7, 0x6c, 0xe5, 0x5c, 0x05, 0x01, 0x51:
2019-09-09 21:32:47.740 Detail, Node044,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-09 21:32:47.740 Detail, Node044,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-09 21:32:47.748 Detail, 
2019-09-09 21:32:47.748 Info, NONCES: 0x78, 0x21, 0xcd, 0x66, 0x2f, 0xaf, 0xb6, 0xd3
2019-09-09 21:32:47.748 Info, NONCES: 0xb2, 0xc8, 0x55, 0xee, 0x74, 0x5d, 0xdc, 0x0e
2019-09-09 21:32:47.748 Info, NONCES: 0xd6, 0xf0, 0x4f, 0x5f, 0x76, 0xd9, 0x61, 0x8f
2019-09-09 21:32:47.748 Info, NONCES: 0x33, 0xcd, 0xcc, 0x84, 0x3c, 0xcf, 0x2c, 0xd7
2019-09-09 21:32:47.748 Info, NONCES: 0x84, 0xfe, 0x40, 0x63, 0x7f, 0xda, 0xfc, 0x7a
2019-09-09 21:32:47.748 Info, NONCES: 0x9b, 0x09, 0xa1, 0x80, 0x85, 0xea, 0xa1, 0x08
2019-09-09 21:32:47.748 Info, NONCES: 0x6c, 0xca, 0x6f, 0x96, 0xfc, 0xba, 0xc7, 0x44
2019-09-09 21:32:47.748 Info, NONCES: 0x99, 0x5f, 0x9c, 0xbe, 0xc7, 0x6c, 0xe5, 0x5c
2019-09-09 21:32:47.748 Info, Node076, Sending (Query) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4c, 0x0a, 0x98, 0x80, 0x78, 0x21, 0xcd, 0x66, 0x2f, 0xaf, 0xb6, 0xd3, 0x05, 0x01, 0xb0:
2019-09-09 21:32:47.748 Detail, Node044, Notification: Notification - TimeOut
2019-09-09 21:32:47.756 Detail, Node044,   Received: 0x01, 0x04, 0x01, 0x13, 0x00, 0xe9
2019-09-09 21:32:47.756 Error, Node044, ERROR: ZW_SEND_DATA could not be delivered to Z-Wave stack
2019-09-09 21:32:48.000 Detail, Node044,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x18, 0xf2
2019-09-09 21:32:48.000 Detail, Node044,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2019-09-09 21:32:48.000 Info, Node044, Request RTT 2610264 Average Request RTT 2607156
2019-09-09 21:32:48.193 Detail, Node076,   Received: 0x01, 0x2c, 0x00, 0x04, 0x00, 0x4c, 0x26, 0x98, 0x81, 0x57, 0x8a, 0xdd, 0x92, 0xc6, 0xd5, 0xe9, 0xf2, 0xcd, 0xa9, 0xd2, 0x87, 0x0d, 0x10, 0x99, 0xf4, 0xba, 0x36, 0x5d, 0x50, 0xd2, 0x15, 0x2a, 0x57, 0xc0, 0xe1, 0x03, 0x99, 0xa5, 0x45, 0x76, 0xcc, 0x31, 0x9d, 0x3f, 0x9c, 0xaa
2019-09-09 21:32:48.193 Info, Raw: 0x98, 0x81, 0x57, 0x8a, 0xdd, 0x92, 0xc6, 0xd5, 0xe9, 0xf2, 0xcd, 0xa9, 0xd2, 0x87, 0x0d, 0x10, 0x99, 0xf4, 0xba, 0x36, 0x5d, 0x50, 0xd2, 0x15, 0x2a, 0x57, 0xc0, 0xe1, 0x03, 0x99, 0xa5, 0x45, 0x76, 0xcc, 0x31, 0x9d, 0x3f, 0x9c, 0xaa
2019-09-09 21:32:48.193 Detail, Node076, Decrypted Packet: 0x00, 0x56, 0x01, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x00, 0x00, 0x00, 0x05, 0x00, 0x00, 0x00, 0x00, 0x07, 0xa5
2019-09-09 21:32:48.193 Detail, 
2019-09-09 21:32:48.193 Info, Node076, ApplicationCommandHandler - Unhandled Command Class 0x56
2019-09-09 21:32:48.641 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-09 21:32:48.641 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-09 21:32:48.641 Info, NONCES: 0x18, 0x2d, 0x08, 0x10, 0x95, 0xb4, 0x65, 0x17
2019-09-09 21:32:48.641 Info, NONCES: 0x95, 0x2a, 0x56, 0xcf, 0xd3, 0xc9, 0x17, 0x9a
2019-09-09 21:32:48.641 Info, NONCES: 0xe3, 0xd0, 0x2d, 0x46, 0xc4, 0x26, 0xea, 0x29
2019-09-09 21:32:48.641 Info, NONCES: 0x83, 0x56, 0x47, 0x6a, 0xc1, 0x9a, 0x36, 0x7f
2019-09-09 21:32:48.641 Info, NONCES: 0x2f, 0x0b, 0x91, 0xcf, 0xc0, 0x47, 0x5a, 0x2b
2019-09-09 21:32:48.641 Info, NONCES: 0x6f, 0x7c, 0x02, 0x5b, 0xc0, 0xac, 0xcc, 0x15
2019-09-09 21:32:48.641 Info, NONCES: 0x33, 0x56, 0xa7, 0x7f, 0x2c, 0x46, 0x6e, 0x91
2019-09-09 21:32:48.641 Info, NONCES: 0xbe, 0xab, 0xbf, 0x28, 0xb2, 0xa2, 0x16, 0x9c
2019-09-09 21:32:48.641 Info, Node077, Sending (Query) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4d, 0x0a, 0x98, 0x80, 0x33, 0x56, 0xa7, 0x7f, 0x2c, 0x46, 0x6e, 0x91, 0x05, 0x01, 0x8e:
2019-09-09 21:32:48.655 Detail, Node044,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-09 21:32:48.655 Detail, Node044,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-09 21:32:48.677 Detail, Node044,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x03, 0xe9
2019-09-09 21:32:48.677 Detail, Node044,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2019-09-09 21:32:48.677 Info, Node044, Request RTT 2610940 Average Request RTT 2609048
2019-09-09 21:32:48.721 Detail, Node077,   Received: 0x01, 0x29, 0x00, 0x04, 0x00, 0x4d, 0x23, 0x98, 0x81, 0x5a, 0x1c, 0xa3, 0xc2, 0xd7, 0xa4, 0x3d, 0x5a, 0x79, 0x20, 0x42, 0xf4, 0xed, 0xa6, 0x7a, 0xb8, 0xf7, 0xaa, 0x83, 0x81, 0x52, 0xe5, 0x91, 0x41, 0x33, 0x0b, 0xe5, 0x72, 0xf1, 0xd8, 0xd4, 0xbb, 0x29, 0x08
2019-09-09 21:32:48.721 Info, Raw: 0x98, 0x81, 0x5a, 0x1c, 0xa3, 0xc2, 0xd7, 0xa4, 0x3d, 0x5a, 0x79, 0x20, 0x42, 0xf4, 0xed, 0xa6, 0x7a, 0xb8, 0xf7, 0xaa, 0x83, 0x81, 0x52, 0xe5, 0x91, 0x41, 0x33, 0x0b, 0xe5, 0x72, 0xf1, 0xd8, 0xd4, 0xbb, 0x29, 0x08
2019-09-09 21:32:48.721 Detail, Node077, Decrypted Packet: 0x00, 0x56, 0x01, 0x32, 0x02, 0x21, 0x74, 0x00, 0x05, 0x2e, 0xc5, 0x00, 0x00, 0x00, 0x77, 0x85
2019-09-09 21:32:48.721 Detail, 
2019-09-09 21:32:48.721 Info, Node077, ApplicationCommandHandler - Unhandled Command Class 0x56
2019-09-09 21:32:52.725 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-09 21:32:52.725 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-09 21:32:52.725 Info, NONCES: 0x78, 0x21, 0xcd, 0x66, 0x2f, 0xaf, 0xb6, 0xd3
2019-09-09 21:32:52.725 Info, NONCES: 0x21, 0xe2, 0x2c, 0x9c, 0x18, 0xca, 0x3e, 0xe5
2019-09-09 21:32:52.725 Info, NONCES: 0xd6, 0xf0, 0x4f, 0x5f, 0x76, 0xd9, 0x61, 0x8f
2019-09-09 21:32:52.725 Info, NONCES: 0x33, 0xcd, 0xcc, 0x84, 0x3c, 0xcf, 0x2c, 0xd7
2019-09-09 21:32:52.725 Info, NONCES: 0x84, 0xfe, 0x40, 0x63, 0x7f, 0xda, 0xfc, 0x7a
2019-09-09 21:32:52.725 Info, NONCES: 0x9b, 0x09, 0xa1, 0x80, 0x85, 0xea, 0xa1, 0x08
2019-09-09 21:32:52.725 Info, NONCES: 0x6c, 0xca, 0x6f, 0x96, 0xfc, 0xba, 0xc7, 0x44
2019-09-09 21:32:52.725 Info, NONCES: 0x99, 0x5f, 0x9c, 0xbe, 0xc7, 0x6c, 0xe5, 0x5c
2019-09-09 21:32:52.725 Info, Node076, Sending (Query) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4c, 0x0a, 0x98, 0x80, 0x21, 0xe2, 0x2c, 0x9c, 0x18, 0xca, 0x3e, 0xe5, 0x05, 0x01, 0xdd:
2019-09-09 21:32:52.737 Detail, Node044,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-09 21:32:52.737 Detail, Node044,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-09 21:32:52.999 Detail, Node044,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x1b, 0xf1
2019-09-09 21:32:52.999 Detail, Node044,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2019-09-09 21:32:52.999 Info, Node044, Request RTT 2615261 Average Request RTT 2612154
2019-09-09 21:32:53.181 Detail, Node076,   Received: 0x01, 0x2c, 0x00, 0x04, 0x00, 0x4c, 0x26, 0x98, 0x81, 0xca, 0x66, 0x48, 0xa8, 0x48, 0xda, 0x2d, 0xbd, 0x75, 0xdf, 0xab, 0x43, 0x6d, 0x76, 0x53, 0xe0, 0x85, 0xf9, 0x1c, 0xae, 0xee, 0x06, 0xbd, 0x13, 0x19, 0xb9, 0xd8, 0x21, 0x54, 0xc0, 0x4b, 0xea, 0x89, 0x38, 0x39, 0x46, 0x2a
2019-09-09 21:32:53.181 Info, Raw: 0x98, 0x81, 0xca, 0x66, 0x48, 0xa8, 0x48, 0xda, 0x2d, 0xbd, 0x75, 0xdf, 0xab, 0x43, 0x6d, 0x76, 0x53, 0xe0, 0x85, 0xf9, 0x1c, 0xae, 0xee, 0x06, 0xbd, 0x13, 0x19, 0xb9, 0xd8, 0x21, 0x54, 0xc0, 0x4b, 0xea, 0x89, 0x38, 0x39, 0x46, 0x2a
2019-09-09 21:32:53.181 Detail, Node076, Decrypted Packet: 0x00, 0x56, 0x01, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x00, 0x00, 0x00, 0x05, 0x00, 0x00, 0x00, 0x00, 0x07, 0xa5
2019-09-09 21:32:53.181 Detail,

SNoof85 · September 10, 2019, 9:14pm

What do you have on the zwave panel ? Can you see the nodes ?

tmjpugh · September 10, 2019, 9:42pm

Is zwave stick at /dev/ttyACM1? Sure it not move.

Mutt · September 10, 2019, 10:14pm

Look through your log file after a reboot, sometimes there is a warning of a config error (you always do a config check though don’t you ?) and that’s what stopped my Z-Wave network loading once. : - (((((

Edit : Ooooh why is it at ACM1 ? has it worked there before ?

freshcoast · September 10, 2019, 11:31pm

Double check that you don’t have ModemManager installed.

mclaeys · September 11, 2019, 7:40am

I removed modemmanager during installation, as it was in the instructions.

I always check my config before restarting, no errors were found in the config

I tried a different approach yesterday. I removed the zwave element in my configuration.yaml, I removed the integration (imported via configuration.yaml) and added the Zwave component again in Home Assistant (via integrations this time). It asks the path of the stick, it asks the security key and I pasted it from my configuration over.

The zwave network won’t start though it initializes the zwave stick correctly now for some reason, but it doesn’t initialize any of my modules.

2019-09-10 20:44:47.460 Always, OpenZwave Version 1.4.3440 Starting Up
2019-09-10 20:45:06.333 Info, Setting Up Provided Network Key for Secure Communications
2019-09-10 20:45:06.333 Info, mgr,     Added driver for controller /dev/serial/by-id/usb-0658_0200-if00
2019-09-10 20:45:06.338 Info,   Opening controller /dev/serial/by-id/usb-0658_0200-if00
2019-09-10 20:45:06.338 Info, Trying to open serial port /dev/serial/by-id/usb-0658_0200-if00 (attempt 1)
2019-09-10 20:45:08.090 Info, Serial port /dev/serial/by-id/usb-0658_0200-if00 opened (attempt 1)
2019-09-10 20:45:08.091 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-09-10 20:45:08.091 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-09-10 20:45:08.091 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-09-10 20:45:08.091 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-09-10 20:45:08.091 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-09-10 20:45:08.091 Detail, 
2019-09-10 20:45:08.091 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-09-10 20:45:08.093 Warning, WARNING: Out of frame flow! (0x04).  Sending NAK.
2019-09-10 20:45:08.093 Warning, WARNING: Out of frame flow! (0x00).  Sending NAK.
2019-09-10 20:45:08.095 Warning, WARNING: Out of frame flow! (0x4d).  Sending NAK.
2019-09-10 20:45:08.095 Warning, WARNING: Out of frame flow! (0x02).  Sending NAK.
2019-09-10 20:45:08.099 Warning, WARNING: Out of frame flow! (0x98).  Sending NAK.
2019-09-10 20:45:08.099 Warning, WARNING: Out of frame flow! (0x40).  Sending NAK.
2019-09-10 20:45:08.099 Warning, WARNING: Out of frame flow! (0x64).  Sending NAK.
2019-09-10 20:45:08.101 Detail, Unsolicited message received while waiting for ACK.
2019-09-10 20:45:08.119 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-10 20:45:08.119 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-10 20:45:08.119 Warning, Node077, Couldn't Generate Nonce Key for Node 77
2019-09-10 20:45:08.119 Detail, Unsolicited message received while waiting for ACK.
2019-09-10 20:45:08.119 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-10 20:45:08.119 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-10 20:45:08.119 Warning, Node077, Couldn't Generate Nonce Key for Node 77
2019-09-10 20:45:08.119 Detail, Unsolicited message received while waiting for ACK.
2019-09-10 20:45:08.119 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-10 20:45:08.119 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-10 20:45:08.119 Warning, Node077, Couldn't Generate Nonce Key for Node 77
2019-09-10 20:45:08.119 Detail, Unsolicited message received while waiting for ACK.
2019-09-10 20:45:08.120 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-10 20:45:08.120 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-10 20:45:08.120 Warning, Node076, Couldn't Generate Nonce Key for Node 76
2019-09-10 20:45:08.120 Detail, Unsolicited message received while waiting for ACK.
2019-09-10 20:45:08.120 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-10 20:45:08.120 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-10 20:45:08.120 Warning, Node076, Couldn't Generate Nonce Key for Node 76
2019-09-10 20:45:08.120 Detail, Unsolicited message received while waiting for ACK.
2019-09-10 20:45:08.120 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-10 20:45:08.120 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-10 20:45:08.120 Warning, Node076, Couldn't Generate Nonce Key for Node 76
2019-09-10 20:45:08.120 Detail, Unsolicited message received while waiting for ACK.
2019-09-10 20:45:08.120 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-10 20:45:08.120 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-10 20:45:08.120 Warning, Node076, Couldn't Generate Nonce Key for Node 76
2019-09-10 20:45:08.120 Detail, Unsolicited message received while waiting for ACK.
2019-09-10 20:45:08.170 Warning, WARNING: 50ms passed without finding the length byte...aborting frame read
2019-09-10 20:45:08.673 Detail, Unsolicited message received while waiting for ACK.
2019-09-10 20:45:08.685 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-10 20:45:08.685 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-10 20:45:08.685 Warning, Node076, Couldn't Generate Nonce Key for Node 76
2019-09-10 20:45:09.685 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-09-10 20:45:09.685 Detail, contrlr, Removing current message
2019-09-10 20:45:09.686 Detail, contrlr, Notification: Notification - TimeOut
2019-09-10 20:45:09.686 Detail, 
2019-09-10 20:45:09.686 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-09-10 20:45:09.698 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xe6, 0xdc, 0x57, 0xea, 0x01, 0x50
2019-09-10 20:45:09.698 Detail, 
2019-09-10 20:45:09.698 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xe6dc57ea.  Our node ID = 1
2019-09-10 20:45:09.698 Detail, Node220,   Expected reply was received
2019-09-10 20:45:09.698 Detail, Node220,   Message transaction complete
2019-09-10 20:45:09.698 Detail, 
2019-09-10 20:45:09.698 Detail, contrlr, Removing current message
2019-09-10 20:45:09.698 Detail, 
2019-09-10 20:45:09.698 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-09-10 20:45:09.708 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x08, 0xf7
2019-09-10 20:45:09.708 Detail, 
2019-09-10 20:45:09.708 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2019-09-10 20:45:09.708 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
2019-09-10 20:45:09.708 Info, contrlr,     The PC controller is a primary controller.
2019-09-10 20:45:09.708 Detail, Node247,   Expected reply was received
2019-09-10 20:45:09.708 Detail, Node247,   Message transaction complete
2019-09-10 20:45:09.708 Detail, 
2019-09-10 20:45:09.708 Detail, contrlr, Removing current message
2019-09-10 20:45:09.708 Detail, 
2019-09-10 20:45:09.708 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-09-10 20:45:09.720 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
2019-09-10 20:45:09.720 Detail, 
2019-09-10 20:45:09.720 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2019-09-10 20:45:09.720 Info, contrlr,     Serial API Version:   1.0
2019-09-10 20:45:09.720 Info, contrlr,     Manufacturer ID:      0x0086
2019-09-10 20:45:09.720 Info, contrlr,     Product Type:         0x0001
2019-09-10 20:45:09.720 Info, contrlr,     Product ID:           0x005a
2019-09-10 20:45:09.720 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-09-10 20:45:09.720 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-09-10 20:45:09.720 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2019-09-10 20:45:09.720 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2019-09-10 20:45:09.720 Detail,   Expected reply was received
2019-09-10 20:45:09.720 Detail,   Message transaction complete
2019-09-10 20:45:09.720 Detail, 
2019-09-10 20:45:09.720 Detail, contrlr, Removing current message
2019-09-10 20:45:09.720 Detail, 
2019-09-10 20:45:09.720 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-09-10 20:45:09.728 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2019-09-10 20:45:09.728 Detail, 
2019-09-10 20:45:09.728 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
2019-09-10 20:45:09.728 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2019-09-10 20:45:09.728 Detail, Node172,   Expected reply was received
2019-09-10 20:45:09.728 Detail, Node172,   Message transaction complete
2019-09-10 20:45:09.728 Detail, 
2019-09-10 20:45:09.728 Detail, contrlr, Removing current message
2019-09-10 20:45:09.728 Detail, 
2019-09-10 20:45:09.728 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-09-10 20:45:09.796 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x51, 0x99, 0x05, 0x82, 0x67, 0xc8, 0xd3, 0x28, 0x5b, 0x7d, 0x8c, 0x78, 0x57, 0x01, 0xce, 0x4f, 0x92, 0xaa, 0x9d, 0x93, 0x4e, 0x85, 0xe0, 0x97, 0xf5, 0xc9, 0x4d, 0x3e, 0xc4, 0xb3, 0x0f, 0xe4, 0xa1
2019-09-10 20:45:09.796 Detail, 
2019-09-10 20:45:09.796 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2019-09-10 20:45:09.796 Detail, Node032,   Expected reply was received
2019-09-10 20:45:09.796 Detail, Node032,   Message transaction complete
2019-09-10 20:45:09.796 Detail, 
2019-09-10 20:45:09.796 Detail, contrlr, Removing current message
2019-09-10 20:45:09.796 Detail,

a bit further it clearly sees all the neighbors

2019-09-10 20:45:09.914 Detail, Node076, QueryStage_ProtocolInfo
2019-09-10 20:45:09.914 Detail, Node076, Queuing (Query) Get Node Protocol Info (Node=76): 0x01, 0x04, 0x00, 0x41, 0x4c, 0xf6
2019-09-10 20:45:09.914 Detail, Node076, Queuing (Query) Query Stage Complete (ProtocolInfo)
2019-09-10 20:45:09.914 Info, contrlr,     Node 077 - Known
2019-09-10 20:45:09.914 Detail, Node077, AdvanceQueries queryPending=0 queryRetries=0 queryStage=ProtocolInfo live=1
2019-09-10 20:45:09.914 Detail, Node077, QueryStage_ProtocolInfo
2019-09-10 20:45:09.914 Detail, Node077, Queuing (Query) Get Node Protocol Info (Node=77): 0x01, 0x04, 0x00, 0x41, 0x4d, 0xf7
2019-09-10 20:45:09.914 Detail, Node077, Queuing (Query) Query Stage Complete (ProtocolInfo)
2019-09-10 20:45:09.914 Detail,   Expected reply was received
2019-09-10 20:45:09.914 Detail,   Message transaction complete
2019-09-10 20:45:09.914 Detail, 
2019-09-10 20:45:09.914 Detail, contrlr, Removing current message
2019-09-10 20:45:09.914 Detail, Node001, Notification: DriverReady
2019-09-10 20:45:09.917 Detail, Node001, Notification: NodeAdded
2019-09-10 20:45:09.917 Detail, Node001, Notification: NodeProtocolInfo
2019-09-10 20:45:09.917 Detail, Node001, Notification: EssentialNodeQueriesComplete
2019-09-10 20:45:09.917 Detail, Node001, Notification: ValueAdded
2019-09-10 20:45:09.917 Detail, Node001, Notification: NodeNaming
2019-09-10 20:45:09.918 Detail, Node041, Notification: NodeAdded
2019-09-10 20:45:09.921 Detail, Node041, Notification: NodeProtocolInfo
2019-09-10 20:45:09.921 Detail, Node041, Notification: ValueAdded
2019-09-10 20:45:09.922 Detail, Node041, Notification: ValueAdded
2019-09-10 20:45:09.922 Detail, Node041, Notification: ValueAdded
2019-09-10 20:45:09.923 Detail, Node041, Notification: ValueAdded
2019-09-10 20:45:09.923 Detail, Node041, Notification: ValueAdded
2019-09-10 20:45:09.923 Detail, Node041, Notification: ValueAdded
2019-09-10 20:45:09.923 Detail, Node041, Notification: ValueAdded
2019-09-10 20:45:09.923 Detail, Node041, Notification: ValueAdded
2019-09-10 20:45:09.923 Detail, Node041, Notification: ValueAdded
2019-09-10 20:45:09.924 Detail, Node042, Notification: NodeAdded
2019-09-10 20:45:09.924 Detail, Node042, Notification: NodeProtocolInfo
2019-09-10 20:45:09.924 Detail, Node042, Notification: ValueAdded
2019-09-10 20:45:09.925 Detail, Node042, Notification: ValueAdded
2019-09-10 20:45:09.925 Detail, Node042, Notification: ValueAdded
2019-09-10 20:45:09.925 Detail, Node042, Notification: ValueAdded
2019-09-10 20:45:09.926 Detail, Node042, Notification: ValueAdded
2019-09-10 20:45:09.926 Detail, Node042, Notification: ValueAdded
2019-09-10 20:45:09.926 Detail, Node042, Notification: ValueAdded
2019-09-10 20:45:09.926 Detail, Node042, Notification: ValueAdded
2019-09-10 20:45:09.926 Detail, Node042, Notification: ValueAdded
2019-09-10 20:45:09.926 Detail, Node043, Notification: NodeAdded
2019-09-10 20:45:09.926 Detail, Node043, Notification: NodeProtocolInfo
2019-09-10 20:45:09.927 Detail, Node043, Notification: ValueAdded
2019-09-10 20:45:09.927 Detail, Node043, Notification: ValueAdded
2019-09-10 20:45:09.928 Detail, Node043, Notification: ValueAdded
2019-09-10 20:45:09.928 Detail, Node043, Notification: ValueAdded
2019-09-10 20:45:09.928 Detail, Node043, Notification: ValueAdded
2019-09-10 20:45:09.928 Detail, Node043, Notification: ValueAdded
2019-09-10 20:45:09.929 Detail, Node043, Notification: ValueAdded
2019-09-10 20:45:09.929 Detail, Node043, Notification: ValueAdded
2019-09-10 20:45:09.929 Detail, Node043, Notification: ValueAdded
2019-09-10 20:45:09.929 Detail, Node044, Notification: NodeAdded
2019-09-10 20:45:09.929 Detail, Node044, Notification: NodeProtocolInfo
2019-09-10 20:45:09.929 Detail, Node045, Notification: NodeAdded
2019-09-10 20:45:09.930 Detail, Node045, Notification: NodeProtocolInfo
2019-09-10 20:45:09.930 Detail, Node046, Notification: NodeAdded
2019-09-10 20:45:09.930 Detail, Node046, Notification: NodeProtocolInfo
2019-09-10 20:45:09.930 Detail, Node047, Notification: NodeAdded
2019-09-10 20:45:09.930 Detail, Node047, Notification: NodeProtocolInfo
2019-09-10 20:45:09.931 Detail, Node047, Notification: ValueAdded
2019-09-10 20:45:09.931 Detail, Node047, Notification: ValueAdded
2019-09-10 20:45:09.932 Detail, Node047, Notification: ValueAdded
2019-09-10 20:45:09.932 Detail, Node047, Notification: ValueAdded
2019-09-10 20:45:09.932 Detail, Node047, Notification: ValueAdded
2019-09-10 20:45:09.932 Detail, Node047, Notification: ValueAdded
2019-09-10 20:45:09.933 Detail, Node047, Notification: ValueAdded
2019-09-10 20:45:09.933 Detail, Node047, Notification: ValueAdded
2019-09-10 20:45:09.933 Detail, Node047, Notification: ValueAdded
2019-09-10 20:45:09.933 Detail, Node048, Notification: NodeAdded
2019-09-10 20:45:09.933 Detail, Node049, Notification: NodeAdded
2019-09-10 20:45:09.934 Detail, Node050, Notification: NodeAdded
2019-09-10 20:45:09.934 Detail, Node051, Notification: NodeAdded
2019-09-10 20:45:09.934 Detail, Node052, Notification: NodeAdded
2019-09-10 20:45:09.934 Detail, Node053, Notification: NodeAdded
2019-09-10 20:45:09.935 Detail, Node054, Notification: NodeAdded
2019-09-10 20:45:09.935 Detail, Node055, Notification: NodeAdded
2019-09-10 20:45:09.935 Detail, Node056, Notification: NodeAdded
2019-09-10 20:45:09.936 Detail, Node057, Notification: NodeAdded
2019-09-10 20:45:09.936 Detail, Node058, Notification: NodeAdded
2019-09-10 20:45:09.937 Detail, Node059, Notification: NodeAdded
2019-09-10 20:45:09.937 Detail, Node060, Notification: NodeAdded
2019-09-10 20:45:09.937 Detail, Node061, Notification: NodeAdded
2019-09-10 20:45:09.938 Detail, Node062, Notification: NodeAdded
2019-09-10 20:45:09.938 Detail, Node063, Notification: NodeAdded
2019-09-10 20:45:09.938 Detail, Node064, Notification: NodeAdded
2019-09-10 20:45:09.939 Detail, Node065, Notification: NodeAdded
2019-09-10 20:45:09.939 Detail, Node066, Notification: NodeAdded
2019-09-10 20:45:09.939 Detail, Node067, Notification: NodeAdded
2019-09-10 20:45:09.940 Detail, Node068, Notification: NodeAdded
2019-09-10 20:45:09.940 Detail, Node069, Notification: NodeAdded
2019-09-10 20:45:09.941 Detail, Node070, Notification: NodeAdded
2019-09-10 20:45:09.941 Detail, Node071, Notification: NodeAdded
2019-09-10 20:45:09.941 Detail, Node072, Notification: NodeAdded
2019-09-10 20:45:09.942 Detail, Node073, Notification: NodeAdded
2019-09-10 20:45:09.942 Detail, Node074, Notification: NodeAdded
2019-09-10 20:45:09.943 Detail, Node075, Notification: NodeAdded
2019-09-10 20:45:09.943 Detail, Node076, Notification: NodeAdded
2019-09-10 20:45:09.943 Detail, Node077, Notification: NodeAdded
2019-09-10 20:45:09.944 Detail, 
2019-09-10 20:45:09.944 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2019-09-10 20:45:09.946 Detail, contrlr,   Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
2019-09-10 20:45:09.946 Detail, 
2019-09-10 20:45:09.946 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2019-09-10 20:45:09.946 Detail, Node015,   Expected reply was received
2019-09-10 20:45:09.946 Detail, Node015,   Message transaction complete
2019-09-10 20:45:09.946 Detail, 
2019-09-10 20:45:09.946 Detail, contrlr, Removing current message
2019-09-10 20:45:09.946 Detail, 
2019-09-10 20:45:09.946 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
2019-09-10 20:45:09.950 Detail, contrlr, Removing current message
2019-09-10 20:45:09.950 Detail, 
2019-09-10 20:45:09.950 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
2019-09-10 20:45:09.962 Detail, Node001,   Received: 0x01, 0x20, 0x01, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0xff, 0xff, 0xff, 0xff, 0x15, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x4b
2019-09-10 20:45:09.962 Detail, 
2019-09-10 20:45:09.962 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2019-09-10 20:45:09.962 Info, Node001,     Neighbors of this node are:
2019-09-10 20:45:09.962 Info, Node001,     Node 41
2019-09-10 20:45:09.962 Info, Node001,     Node 42
2019-09-10 20:45:09.962 Info, Node001,     Node 43
2019-09-10 20:45:09.962 Info, Node001,     Node 44
2019-09-10 20:45:09.962 Info, Node001,     Node 45
2019-09-10 20:45:09.962 Info, Node001,     Node 46
2019-09-10 20:45:09.962 Info, Node001,     Node 47
2019-09-10 20:45:09.962 Info, Node001,     Node 48
2019-09-10 20:45:09.962 Info, Node001,     Node 49
2019-09-10 20:45:09.962 Info, Node001,     Node 50
2019-09-10 20:45:09.962 Info, Node001,     Node 51
2019-09-10 20:45:09.962 Info, Node001,     Node 52
2019-09-10 20:45:09.962 Info, Node001,     Node 53
2019-09-10 20:45:09.962 Info, Node001,     Node 54
2019-09-10 20:45:09.962 Info, Node001,     Node 55
2019-09-10 20:45:09.962 Info, Node001,     Node 56
2019-09-10 20:45:09.962 Info, Node001,     Node 57
2019-09-10 20:45:09.962 Info, Node001,     Node 58
2019-09-10 20:45:09.962 Info, Node001,     Node 59
2019-09-10 20:45:09.962 Info, Node001,     Node 60
2019-09-10 20:45:09.962 Info, Node001,     Node 61
2019-09-10 20:45:09.962 Info, Node001,     Node 62
2019-09-10 20:45:09.962 Info, Node001,     Node 63
2019-09-10 20:45:09.962 Info, Node001,     Node 64
2019-09-10 20:45:09.962 Info, Node001,     Node 65
2019-09-10 20:45:09.962 Info, Node001,     Node 66
2019-09-10 20:45:09.962 Info, Node001,     Node 67
2019-09-10 20:45:09.962 Info, Node001,     Node 68
2019-09-10 20:45:09.962 Info, Node001,     Node 69
2019-09-10 20:45:09.962 Info, Node001,     Node 70
2019-09-10 20:45:09.962 Info, Node001,     Node 71
2019-09-10 20:45:09.962 Info, Node001,     Node 72
2019-09-10 20:45:09.962 Info, Node001,     Node 73
2019-09-10 20:45:09.962 Info, Node001,     Node 75
2019-09-10 20:45:09.962 Info, Node001,     Node 77
2019-09-10 20:45:09.962 Detail,   Expected reply was received
2019-09-10 20:45:09.962 Detail,   Message transaction complete
2019-09-10 20:45:09.962 Detail, 
2019-09-10 20:45:09.962 Detail, Node001, Removing current message
2019-09-10 20:45:09.962 Detail, 
2019-09-10 20:45:09.962 Info, Node041, Sending (NoOp) message (Callback ID=0x0a, Expected Reply=0x13) - NoOperation_Set (Node=41): 0x01, 0x09, 0x00, 0x13, 0x29, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xe1
2019-09-10 20:45:09.974 Detail, Node041,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-10 20:45:09.974 Detail, Node041,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-10 20:45:10.016 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-10 20:45:10.016 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-10 20:45:10.016 Info, NONCES: 0x01, 0x58, 0xc0, 0xcb, 0xc7, 0x30, 0x20, 0x9a
2019-09-10 20:45:10.016 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2019-09-10 20:45:10.016 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2019-09-10 20:45:10.016 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2019-09-10 20:45:10.016 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2019-09-10 20:45:10.016 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2019-09-10 20:45:10.016 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2019-09-10 20:45:10.016 Info, NONCES: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2019-09-10 20:45:10.016 Info, Node077, Sending (NoOp) message (Callback ID=0x01, Expected Reply=0x13) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4d, 0x0a, 0x98, 0x80, 0x01, 0x58, 0xc0, 0xcb, 0xc7, 0x30, 0x20, 0x9a, 0x05, 0x01, 0xb9:
2019-09-10 20:45:10.032 Detail, Node041,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-10 20:45:10.032 Detail, Node041,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-10 20:45:10.082 Detail, Node041,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x05, 0xef
2019-09-10 20:45:10.083 Detail, Node041,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2019-09-10 20:45:10.083 Info, Node041, Request RTT 121 Average Request RTT 121
2019-09-10 20:45:10.083 Detail, Node041, Notification: Notification - NoOperation

And then it’s this all the time. It was initializing all night and the zwave network is still in “starting”-mode.

2019-09-11 09:30:04.467 Detail, 
2019-09-11 09:30:04.467 Info, Node076, Received CRC16-command from node 76
2019-09-11 09:30:04.467 Info, Node076, Received Meter report from node 76: Power=353.874W
2019-09-11 09:30:04.467 Detail, Node076, Refreshed Value: old value=356.875, new value=353.874, type=decimal
2019-09-11 09:30:04.467 Detail, Node076, Changes to this value are not verified
2019-09-11 09:30:04.467 Detail, Node076, Notification: ValueChanged
2019-09-11 09:30:05.759 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-11 09:30:05.759 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-11 09:30:05.759 Info, NONCES: 0xfd, 0xc2, 0xb2, 0xc9, 0xcc, 0xaf, 0xfd, 0x91
2019-09-11 09:30:05.759 Info, NONCES: 0xa6, 0x66, 0x80, 0x1c, 0xc5, 0x09, 0xa6, 0x65
2019-09-11 09:30:05.759 Info, NONCES: 0xe2, 0xaa, 0x49, 0x87, 0x43, 0x51, 0x53, 0x40
2019-09-11 09:30:05.759 Info, NONCES: 0xb0, 0xb5, 0xa2, 0x73, 0x3c, 0x17, 0xad, 0xb6
2019-09-11 09:30:05.759 Info, NONCES: 0x04, 0xbe, 0x7d, 0x45, 0x26, 0x6d, 0x25, 0xf4
2019-09-11 09:30:05.759 Info, NONCES: 0xff, 0xc3, 0x92, 0x40, 0x7d, 0x70, 0x01, 0x4a
2019-09-11 09:30:05.759 Info, NONCES: 0x7b, 0xb1, 0x52, 0x6d, 0x52, 0x47, 0x78, 0x9a
2019-09-11 09:30:05.759 Info, NONCES: 0xa0, 0x20, 0x64, 0x07, 0xbf, 0x2c, 0x31, 0xa1
2019-09-11 09:30:05.759 Info, Node077, Sending (Send) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4d, 0x0a, 0x98, 0x80, 0x04, 0xbe, 0x7d, 0x45, 0x26, 0x6d, 0x25, 0xf4, 0x05, 0x01, 0xbe:
2019-09-11 09:30:05.772 Detail, Node055,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-11 09:30:05.772 Detail, Node055,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-11 09:30:05.799 Detail, Node055,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x03, 0xe9
2019-09-11 09:30:05.799 Detail, Node055,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2019-09-11 09:30:05.800 Info, Node055, Request RTT 2921646 Average Request RTT 2920226
2019-09-11 09:30:05.842 Detail, Node077,   Received: 0x01, 0x29, 0x00, 0x04, 0x00, 0x4d, 0x23, 0x98, 0x81, 0x36, 0x84, 0xe1, 0x1d, 0x3e, 0xdc, 0x00, 0x98, 0x75, 0x92, 0x9b, 0x4e, 0xf0, 0x0c, 0x29, 0x00, 0x29, 0x49, 0x30, 0x0c, 0xa2, 0x60, 0x2f, 0x07, 0x04, 0xf9, 0x8e, 0xfc, 0xd4, 0xc1, 0x4e, 0x18, 0xba, 0xb6
2019-09-11 09:30:05.842 Info, Raw: 0x98, 0x81, 0x36, 0x84, 0xe1, 0x1d, 0x3e, 0xdc, 0x00, 0x98, 0x75, 0x92, 0x9b, 0x4e, 0xf0, 0x0c, 0x29, 0x00, 0x29, 0x49, 0x30, 0x0c, 0xa2, 0x60, 0x2f, 0x07, 0x04, 0xf9, 0x8e, 0xfc, 0xd4, 0xc1, 0x4e, 0x18, 0xba, 0xb6
2019-09-11 09:30:05.842 Detail, Node077, Decrypted Packet: 0x00, 0x56, 0x01, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0xd7, 0x54, 0x00, 0x00, 0x00, 0x13, 0x85
2019-09-11 09:30:05.842 Detail, 
2019-09-11 09:30:05.842 Info, Node077, Received CRC16-command from node 77
2019-09-11 09:30:05.842 Info, Node077, Received Meter report from node 77: Power=55.124W
2019-09-11 09:30:05.842 Detail, Node077, Refreshed Value: old value=3.446, new value=55.124, type=decimal
2019-09-11 09:30:05.842 Detail, Node077, Changes to this value are not verified
2019-09-11 09:30:05.842 Detail, Node077, Notification: ValueChanged
2019-09-11 09:30:09.173 Detail, Node076,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4c, 0x02, 0x98, 0x40, 0x65
2019-09-11 09:30:09.173 Info, Node076, Received SecurityCmd_NonceGet from node 76
2019-09-11 09:30:09.173 Info, NONCES: 0x85, 0xc0, 0x68, 0xd1, 0x67, 0x74, 0x92, 0x37
2019-09-11 09:30:09.173 Info, NONCES: 0xd4, 0x72, 0x1a, 0x26, 0x08, 0x76, 0x89, 0x8f
2019-09-11 09:30:09.173 Info, NONCES: 0x86, 0x57, 0x98, 0x28, 0x72, 0xa4, 0xdb, 0x5a
2019-09-11 09:30:09.173 Info, NONCES: 0xb3, 0xb0, 0x44, 0x55, 0x41, 0x55, 0x3e, 0x9e
2019-09-11 09:30:09.173 Info, NONCES: 0x65, 0xf5, 0xf1, 0x67, 0x77, 0x84, 0x6b, 0xa7
2019-09-11 09:30:09.173 Info, NONCES: 0xe7, 0x8c, 0xfc, 0x49, 0xea, 0x06, 0xe9, 0xd1
2019-09-11 09:30:09.173 Info, NONCES: 0xdc, 0x42, 0x35, 0xa6, 0xfb, 0xed, 0x1c, 0x3c
2019-09-11 09:30:09.173 Info, NONCES: 0xab, 0xb9, 0x06, 0x02, 0xc4, 0xa9, 0xe0, 0x82
2019-09-11 09:30:09.173 Info, Node076, Sending (Send) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4c, 0x0a, 0x98, 0x80, 0x85, 0xc0, 0x68, 0xd1, 0x67, 0x74, 0x92, 0x37, 0x05, 0x01, 0xed:
2019-09-11 09:30:09.183 Detail, Node055,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-11 09:30:09.183 Detail, Node055,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-11 09:30:09.290 Detail, Node055,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x0b, 0xe1
2019-09-11 09:30:09.290 Detail, Node055,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2019-09-11 09:30:09.290 Info, Node055, Request RTT 2925137 Average Request RTT 2922681
2019-09-11 09:30:09.402 Detail, Node076,   Received: 0x01, 0x2c, 0x00, 0x04, 0x00, 0x4c, 0x26, 0x98, 0x81, 0x81, 0x47, 0xb4, 0x31, 0xd6, 0xee, 0xe5, 0xa6, 0x2d, 0x45, 0x54, 0xab, 0x33, 0x3b, 0x7d, 0xc0, 0x90, 0x5a, 0xb6, 0xf8, 0x71, 0x20, 0x33, 0xec, 0x39, 0xcd, 0xb0, 0x85, 0x7f, 0x7a, 0xb5, 0xc8, 0xf5, 0x08, 0xe7, 0x81, 0x96
2019-09-11 09:30:09.402 Info, Raw: 0x98, 0x81, 0x81, 0x47, 0xb4, 0x31, 0xd6, 0xee, 0xe5, 0xa6, 0x2d, 0x45, 0x54, 0xab, 0x33, 0x3b, 0x7d, 0xc0, 0x90, 0x5a, 0xb6, 0xf8, 0x71, 0x20, 0x33, 0xec, 0x39, 0xcd, 0xb0, 0x85, 0x7f, 0x7a, 0xb5, 0xc8, 0xf5, 0x08, 0xe7, 0x81, 0x96
2019-09-11 09:30:09.402 Detail, Node076, Decrypted Packet: 0x00, 0x56, 0x01, 0x32, 0x02, 0x21, 0x74, 0x00, 0x05, 0x61, 0xb6, 0x00, 0x05, 0x00, 0x05, 0x66, 0x52, 0xd9, 0x98
2019-09-11 09:30:09.402 Detail, 
2019-09-11 09:30:09.402 Info, Node076, Received CRC16-command from node 76
2019-09-11 09:30:09.402 Info, Node076, Received Meter report from node 76: Power=352.694W
2019-09-11 09:30:09.402 Detail, Node076, Refreshed Value: old value=353.874, new value=352.694, type=decimal
2019-09-11 09:30:09.402 Detail, Node076, Changes to this value are not verified
2019-09-11 09:30:09.403 Detail, Node076, Notification: ValueChanged
2019-09-11 09:30:10.757 Detail, Node077,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x4d, 0x02, 0x98, 0x40, 0x64
2019-09-11 09:30:10.757 Info, Node077, Received SecurityCmd_NonceGet from node 77
2019-09-11 09:30:10.757 Info, NONCES: 0xfd, 0xc2, 0xb2, 0xc9, 0xcc, 0xaf, 0xfd, 0x91
2019-09-11 09:30:10.757 Info, NONCES: 0xa6, 0x66, 0x80, 0x1c, 0xc5, 0x09, 0xa6, 0x65
2019-09-11 09:30:10.757 Info, NONCES: 0xe2, 0xaa, 0x49, 0x87, 0x43, 0x51, 0x53, 0x40
2019-09-11 09:30:10.758 Info, NONCES: 0xb0, 0xb5, 0xa2, 0x73, 0x3c, 0x17, 0xad, 0xb6
2019-09-11 09:30:10.758 Info, NONCES: 0x04, 0xbe, 0x7d, 0x45, 0x26, 0x6d, 0x25, 0xf4
2019-09-11 09:30:10.758 Info, NONCES: 0xb6, 0x2d, 0xb2, 0xa1, 0x3a, 0x77, 0xd2, 0xc5
2019-09-11 09:30:10.758 Info, NONCES: 0x7b, 0xb1, 0x52, 0x6d, 0x52, 0x47, 0x78, 0x9a
2019-09-11 09:30:10.758 Info, NONCES: 0xa0, 0x20, 0x64, 0x07, 0xbf, 0x2c, 0x31, 0xa1
2019-09-11 09:30:10.758 Info, Node077, Sending (Send) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x4d, 0x0a, 0x98, 0x80, 0xb6, 0x2d, 0xb2, 0xa1, 0x3a, 0x77, 0xd2, 0xc5, 0x05, 0x01, 0x74:
2019-09-11 09:30:10.770 Detail, Node055,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-11 09:30:10.770 Detail, Node055,   ZW_SEND_DATA delivered to Z-Wave stack
2019-09-11 09:30:10.800 Detail, Node055,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x03, 0xe9
2019-09-11 09:30:10.800 Detail, Node055,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2019-09-11 09:30:10.800 Info, Node055, Request RTT 2926647 Average Request RTT 2924664
2019-09-11 09:30:10.845 Detail, Node077,   Received: 0x01, 0x29, 0x00, 0x04, 0x00, 0x4d, 0x23, 0x98, 0x81, 0xfa, 0x37, 0x7e, 0xcc, 0xd5, 0x47, 0x3e, 0x81, 0x76, 0x42, 0x3e, 0x94, 0x68, 0x33, 0xf9, 0xea, 0x30, 0x7d, 0x35, 0xbc, 0x00, 0xd2, 0xb0, 0xa0, 0xb6, 0x84, 0x69, 0x3e, 0x74, 0x09, 0x0c, 0x2f, 0x23, 0x3f
2019-09-11 09:30:10.845 Info, Raw: 0x98, 0x81, 0xfa, 0x37, 0x7e, 0xcc, 0xd5, 0x47, 0x3e, 0x81, 0x76, 0x42, 0x3e, 0x94, 0x68, 0x33, 0xf9, 0xea, 0x30, 0x7d, 0x35, 0xbc, 0x00, 0xd2, 0xb0, 0xa0, 0xb6, 0x84, 0x69, 0x3e, 0x74, 0x09, 0x0c, 0x2f, 0x23, 0x3f
2019-09-11 09:30:10.845 Detail, Node077, Decrypted Packet: 0x00, 0x56, 0x01, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x9a, 0x18, 0x00, 0x00, 0x00, 0x02, 0x39
2019-09-11 09:30:10.845 Detail,

The system log only shows delays.

Mutt · September 11, 2019, 8:12am

What does the actual log say? Sometimes it’s different

mclaeys · September 11, 2019, 8:26am

Nah, sadly same message

2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 41 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 41 Switch (node_id: 41) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 41 Switch (node_id: 41) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 41 Level (node_id: 41) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 42 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 42 Switch (node_id: 42) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 42 Switch (node_id: 42) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 42 Level (node_id: 42) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 43 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 43 Switch (node_id: 43) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 43 Switch (node_id: 43) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 43 Level (node_id: 43) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 44 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 45 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 46 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 47 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 47 Switch (node_id: 47) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 47 Switch (node_id: 47) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 47 Level (node_id: 47) not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 48 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 49 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 50 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 51 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 52 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 53 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 54 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 55 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 56 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 57 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 58 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 59 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 60 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 61 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 62 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 63 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 64 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 65 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 66 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 67 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 68 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 69 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 70 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 71 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 72 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 73 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 74 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 75 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 76 not ready after 30 seconds, continuing anyway
2019-09-10 20:45:39 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 77 not ready after 30 seconds, continuing anyway
2019-09-10 20:46:40 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 48 Level (node_id: 48) not ready after 30 seconds, continuing anyway
2019-09-10 20:50:06 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave not ready after 300 seconds, continuing anyway
2019-09-10 20:57:05 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 20:57:30 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:01:40 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave not ready after 300 seconds, continuing anyway
2019-09-10 21:21:15 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:26:10 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:26:36 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:29:10 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:29:36 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:35:08 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:35:34 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:35:59 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:37:29 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:41:07 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:41:45 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:42:11 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:42:35 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:43:00 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:43:26 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:43:52 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:44:18 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:44:44 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:45:09 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:46:00 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:47:42 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:48:07 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 21:48:44 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:21:48 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:22:26 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:23:04 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:23:30 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:23:56 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:24:21 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:24:47 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:25:12 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:25:38 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:26:04 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:26:30 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:26:55 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:27:21 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:27:47 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:28:13 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:28:38 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:29:17 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:29:43 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:32:54 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:37:09 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:37:34 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:38:00 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:38:25 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:38:51 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:39:29 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:39:54 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:40:20 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:40:45 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 22:41:10 WARNING (MainThread) [homeassistant.components.device_tracker] Updating device list from legacy took longer than the scheduled scan interval 0:00:12
2019-09-10 23:14:55 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 49 Level (node_id: 49) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:55 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 50 Level (node_id: 50) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:55 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 51 Level (node_id: 51) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:55 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 52 Level (node_id: 52) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:55 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 53 Level (node_id: 53) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:55 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 54 Level (node_id: 54) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 55 Level (node_id: 55) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 56 Level (node_id: 56) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 57 Level (node_id: 57) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 58 Level (node_id: 58) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 59 Level (node_id: 59) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 60 Level (node_id: 60) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 61 Level (node_id: 61) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 62 Level (node_id: 62) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 63 Level (node_id: 63) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 64 Level (node_id: 64) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 65 Level (node_id: 65) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 66 Level (node_id: 66) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 67 Level (node_id: 67) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:56 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 68 Level (node_id: 68) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:57 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 69 Level (node_id: 69) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:57 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 70 Level (node_id: 70) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:57 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 71 Level (node_id: 71) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:57 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 72 Level (node_id: 72) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:57 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 73 Level (node_id: 73) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:57 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 74 Level (node_id: 74) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:57 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 75 Level (node_id: 75) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:57 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 44 Unknown (node_id: 44) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:58 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 45 Unknown (node_id: 45) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:58 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 46 Unknown (node_id: 46) not ready after 30 seconds, continuing anyway
2019-09-10 23:14:58 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity FIBARO System FGRM222 Roller Shutter Controller 2 Unknown (node_id: 41) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:10 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity FIBARO System FGRM222 Roller Shutter Controller 2 Unknown (node_id: 43) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:12 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity FIBARO System FGRM222 Roller Shutter Controller 2 Unknown (node_id: 47) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:12 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 48 Unknown (node_id: 48) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:12 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 49 Unknown (node_id: 49) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:12 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 50 Unknown (node_id: 50) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:12 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 51 Unknown (node_id: 51) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:12 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 52 Unknown (node_id: 52) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:12 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 52 Alarm Type (node_id: 52) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:12 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 52 Alarm Level (node_id: 52) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 53 Unknown (node_id: 53) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 53 Alarm Type (node_id: 53) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 53 Alarm Level (node_id: 53) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 54 Unknown (node_id: 54) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 54 Alarm Type (node_id: 54) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 54 Alarm Level (node_id: 54) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 55 Unknown (node_id: 55) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 55 Alarm Type (node_id: 55) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 55 Alarm Level (node_id: 55) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 56 Unknown (node_id: 56) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 56 Alarm Type (node_id: 56) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 56 Alarm Level (node_id: 56) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 57 Unknown (node_id: 57) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 57 Alarm Type (node_id: 57) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 57 Alarm Level (node_id: 57) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 58 Unknown (node_id: 58) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 58 Alarm Type (node_id: 58) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:13 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 58 Alarm Level (node_id: 58) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:14 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 59 Unknown (node_id: 59) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:14 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 59 Alarm Type (node_id: 59) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:14 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 59 Alarm Level (node_id: 59) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:15 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 60 Unknown (node_id: 60) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:15 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 60 Alarm Type (node_id: 60) not ready after 30 seconds, continuing anyway
2019-09-10 23:18:15 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 60 Alarm Level (node_id: 60) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 62 Unknown (node_id: 62) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 62 Alarm Type (node_id: 62) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 62 Alarm Level (node_id: 62) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 63 Unknown (node_id: 63) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 63 Alarm Type (node_id: 63) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 63 Alarm Level (node_id: 63) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 64 Unknown (node_id: 64) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 64 Alarm Type (node_id: 64) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 64 Alarm Level (node_id: 64) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity Unknown Node 65 Unknown (node_id: 65) not ready after 30 seconds, continuing anyway
2019-09-10 23:19:05 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave entity

In the integration it show a lot more modules than in the zwave page with network options though. This morning it was only 2. Non functional though.

freshcoast · September 11, 2019, 7:00pm

I think you’ll continue to have problems until these errors are addressed. Most often this indicates another process is accessing the USB stick at the same time, which is why I suggested checking ModemManager (see troubleshooting). Make sure you don’t have the OZW Control Panel running, or another instance of Home Assistant (it’s happened before).

How is Home Assistant installed? Are you using a Python venv, Docker, or Hass.io? If Docker, ModemManager needs to be disabled on the host not in the container.

Fishwaldo · September 12, 2019, 8:10am

something else is using the same serial port (another instance of HA? ModemManager, OZWCP etc). Find that other process (lsof is a good tool to help) and your problems should be solved.

mclaeys · September 12, 2019, 4:48pm

Modemmanager is not installed. It states this in the installation manual that it needed to be removed, so I did.
It is installed in docker on a Debian machine (VM). I did migrate from a normal server to a VM.

I looked into the “other process using zwave”-case. However I couldn’t find anything.

Since by yesterday the WAF (Wife Acceptancy Factor) was down the toilet I did some last debugging but went to the last resort eventually.
What I did now was I unplugged the Aeotec stick, placed it in exclusion mode and unlinked all my modules. Then I factory reset the Aeotec stick. Plugged it in and started including my modules again (and sadly one roller shutter module broke down at the same time but oh well).
Currently only 7 modules are added yet, but I encounter no issues YET.
And no errors as far as I can find in the OPZW log. If the key would have been used by another process I would have expected it to still be an issue (the hardware path remained the same). I want to add all my modules first before I’m sure though, so we’ll see.
The issues started with adding modules secure, why it’s now not the case, not 1 clue.

ha_steve · September 13, 2019, 8:36pm

Are you sure you mapped the stick into the VM correctly?

mclaeys · September 14, 2019, 8:38am

Yes, since it worked before the reboot. I checked if the path changed after the reboot (sometimes my Zigbee2MQTT stick and Z-wave stick would swap path after a reboot) but it didn’t.

I got it now working again. It starts faster too than anytime before. Ok, I had to factory reset the Z-wave stick, but it works that’s the most important thing for me :). Thanks for all the suggestions here, if it fails again I’ll try all these things again.
A total of 38 nodes up and running now! Took a few evenings of renaming all the entities to find them easily in the configuration.
Now I have to see to exclude most of the entities in the database, as most of it isn’t needed (I don’t care about the power consumption of every light, etc…, maybe only to trigger a check if the bulb is broken or not).
(I tried excluding some entities from Home Assistant, but it only puts polling on 0, not that usefull).

tmjpugh · September 14, 2019, 1:56pm

Aeotech allows stick to be backed up. Not sure how it affects recovering from these situations

mclaeys · September 14, 2019, 2:53pm

Thanks for the tip!
I looked for it on their site and it indeed has a backup tool.
I downloaded it and backed my current device links up. How/if a recovery works I don’t know, but I do at least have a backup then.
I don’t think it would have helped me now. I think I went totally wrong here by removing all devices and adding them again as new on the new server but with a password. My best guess is that there was still some old config left on the stick or something.

palengh · September 17, 2019, 9:33pm

How you back it up?

tmjpugh · September 18, 2019, 1:24am

I not try or test backup but recommended by others and likely will work to backup stick in case of it factory reset. Also should backup the .xml file for zwave device in HA configs (should backup HA also basically)

palengh · September 18, 2019, 5:05am

Thank you. I was not aware of this tool.
Maybe it is other ways to automate backup of the stick.
Anyone knows how?

mclaeys · September 20, 2019, 4:43pm

It takes a bit of time to backup, so I don’t think it could be automated. At least not easy, as it should be unlinked from Home Assistant first, then backup and link back to HA (and it’s a Windows tool)

brendan · November 3, 2019, 5:27pm

To me, this looks like the Secure Nodes are not communicating properly.
I would recommend factory reset all the secure nodes at minimum, but likely best to do it with all nodes and re-do it from the start if nothing else has solved it.
I have found back-ups to be hit and miss on restore, especially if secure nodes are used.
Hopefully it is resolved by now though.