I installed 4 more light switches in my house this week and I’ve been unable to add the new nodes to the network for a day or two now, even after multiple full reboots and waiting for overnight heals.
Upon clicking the inclusion button inside habmin the binding seems to run through every device already on the network checking them against the device database entries one by one alphabetically to find a match. This takes a second or two and then it immediately says inclusion failed. I’ve also noticed that the settings for my controller are “Network Wide Inclusion” in the habmin settings but when I start inclusion the debug log shows “Setting controller into INCLUSION mode, highPower:true networkWide:false.” and then within milliseconds it will say inclusion failed. In the debug log 12 seconds after that it will say stopping transaction timer, ENDING inclusion mode.
All other actions on the zwave network work fine, lights on off, secure door lock open and close, temperature updates etc.
I am using an Aeon Z-Stick S2, the development branch ZWave Binding 2.1.0.201709182108 in order to get security and most recent database updates to include a GE switch type I added on Chris’s website recently.
Any help at all would be greatly appreciated. I can unplug the stick and carry it around but have never added devices that way.
Not sure how much of the debug log to add.
2017-09-24 10:53:30.899 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.
2017-09-24 10:53:30.906 [DEBUG] [ve.internal.protocol.ZWaveController] - Neither inclusion nor exclusion was active!
2017-09-24 10:53:30.915 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Starting ZWave inclusion scan for zwave:serial_zstick:ZStickController
2017-09-24 10:53:30.920 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery completed
2017-09-24 10:53:30.925 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Checking zwave:act_45602_00_000
2017-09-24 10:53:30.930 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Checking zwave:act_zdm230_00_000
2017-09-24 10:53:30.935 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Checking zwave:act_zdp200_00_000
...
2017-09-24 10:53:31.618 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Checking zwave:eco_tiltzwave25eco_00_000
2017-09-24 10:53:31.622 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Checking zwave:eco_tiltzwave2_00_000
2017-09-24 10:53:31.627 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery resolved to thingType zwave:eco_tiltzwave2_00_000
2017-09-24 10:53:31.632 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery completed
2017-09-24 10:53:31.637 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Checking zwave:act_45602_00_000
2017-09-24 10:53:31.640 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Checking zwave:act_zdm230_00_000
...
2017-09-24 10:53:48.495 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 21: Checking zwave:schlage_be468_00_000
2017-09-24 10:53:48.498 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 21: Checking zwave:schlage_be469_00_000
2017-09-24 10:53:48.502 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 21: Device discovery resolved to thingType zwave:schlage_be469_00_000
2017-09-24 10:53:48.507 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
2017-09-24 10:53:48.513 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:false.
2017-09-24 10:53:48.516 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Adding to controller queue
2017-09-24 10:53:48.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added to queue - size 1
2017-09-24 10:53:48.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-09-24 10:53:48.524 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:53:48.530 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Node not awake!
2017-09-24 10:53:48.535 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Node not awake!
2017-09-24 10:53:48.541 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:53:48.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:53:48.552 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 11: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.555 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 11: Node not awake!
2017-09-24 10:53:48.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-09-24 10:53:48.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
2017-09-24 10:53:48.564 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 81 B6 87
2017-09-24 10:53:48.568 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 81 B6 87
2017-09-24 10:53:48.574 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2017-09-24 10:53:48.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-09-24 10:53:48.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-09-24 10:53:48.582 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-09-24 10:53:48.585 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-09-24 10:53:48.587 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2017-09-24 10:53:48.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-09-24 10:53:48.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-09-24 10:53:48.586 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2017-09-24 10:53:48.594 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A B6 01 00 00 05
2017-09-24 10:53:48.596 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 334: Transaction Start type AddNodeToNetwork
2017-09-24 10:53:48.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 334: [WAIT_REQUEST] requiresResponse=true callback: 182
2017-09-24 10:53:48.605 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
2017-09-24 10:53:48.608 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-09-24 10:53:48.609 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: null
2017-09-24 10:53:48.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=1, callback=182, payload=B6 01 00 00
2017-09-24 10:53:48.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
2017-09-24 10:53:48.620 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=1, callback=182, payload=B6 01 00 00
2017-09-24 10:53:48.619 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Sun Sep 24 10:53:53 PDT 2017 - 5000ms
2017-09-24 10:53:48.624 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 334: [WAIT_REQUEST] requiresResponse=true callback: 182
2017-09-24 10:53:48.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-09-24 10:53:48.632 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Sun Sep 24 10:53:53 PDT 2017 - 4987ms
2017-09-24 10:53:48.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-09-24 10:53:48.638 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-09-24 10:53:48.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Sun Sep 24 10:53:53 PDT 2017 - 4978ms
2017-09-24 10:53:48.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=1, callback=182, payload=B6 01 00 00
2017-09-24 10:53:48.649 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 334: [WAIT_REQUEST] requiresResponse=true callback: 182
2017-09-24 10:53:48.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2017-09-24 10:53:48.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 334: [WAIT_REQUEST] requiresResponse=true callback: 182
2017-09-24 10:53:48.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 334: (Callback 182)
2017-09-24 10:53:48.663 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2017-09-24 10:53:48.666 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 334: callback 182
2017-09-24 10:53:48.671 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=1, callback=182, payload=B6 01 00 00
2017-09-24 10:53:48.675 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2017-09-24 10:53:48.682 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-09-24 10:53:48.690 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 334: Transaction COMPLETED
2017-09-24 10:53:48.692 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 334: TransactionAdvance ST: DONE
2017-09-24 10:53:48.697 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 334: TransactionAdvance WT: null {}
2017-09-24 10:53:48.700 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 334: TransactionAdvance RX: Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=1, callback=182, payload=B6 01 00 00
2017-09-24 10:53:48.702 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 334: TransactionAdvance TO: DONE
2017-09-24 10:53:48.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 108ms
2017-09-24 10:53:48.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 334: Transaction completed
2017-09-24 10:53:48.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:334 DONE
2017-09-24 10:53:48.709 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-09-24 10:53:48.711 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-09-24 10:53:48.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-09-24 10:53:48.715 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:53:48.718 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Node not awake!
2017-09-24 10:53:48.721 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.723 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Node not awake!
2017-09-24 10:53:48.725 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.727 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:53:48.728 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.730 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:53:48.732 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 11: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:53:48.733 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 11: Node not awake!
2017-09-24 10:53:48.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-09-24 10:53:48.737 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2017-09-24 10:53:48.738 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-09-24 10:54:00.919 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.
2017-09-24 10:54:00.922 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2017-09-24 10:54:00.925 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Adding to controller queue
2017-09-24 10:54:00.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added to queue - size 1
2017-09-24 10:54:00.930 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-09-24 10:54:00.933 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:00.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:54:00.938 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:00.940 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Node not awake!
2017-09-24 10:54:00.943 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:00.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Node not awake!
2017-09-24 10:54:00.948 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:00.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:54:00.953 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:00.956 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:54:00.958 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 11: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:00.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 11: Node not awake!
2017-09-24 10:54:00.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-09-24 10:54:00.966 [DEBUG] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
2017-09-24 10:54:00.970 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 B7 02
2017-09-24 10:54:00.973 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 B7 02
2017-09-24 10:54:00.979 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2017-09-24 10:54:00.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-09-24 10:54:00.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-09-24 10:54:00.989 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-09-24 10:54:00.991 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-09-24 10:54:00.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2017-09-24 10:54:00.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-09-24 10:54:00.995 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2017-09-24 10:54:00.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-09-24 10:54:00.999 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 335: Transaction Start type AddNodeToNetwork
2017-09-24 10:54:01.004 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 335: [WAIT_REQUEST] requiresResponse=true callback: 183
2017-09-24 10:54:01.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
2017-09-24 10:54:01.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: null
2017-09-24 10:54:01.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
2017-09-24 10:54:01.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Sun Sep 24 10:54:06 PDT 2017 - 5000ms
2017-09-24 10:54:01.022 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 335: [WAIT_REQUEST] requiresResponse=true callback: 183
2017-09-24 10:54:01.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-09-24 10:54:01.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Sun Sep 24 10:54:06 PDT 2017 - 4990ms
2017-09-24 10:54:01.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-09-24 10:54:01.031 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller end inclusion
2017-09-24 10:54:01.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-09-24 10:54:01.033 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A B7 06 00 00 03
2017-09-24 10:54:01.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Sun Sep 24 10:54:06 PDT 2017 - 4980ms
2017-09-24 10:54:01.043 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-09-24 10:54:01.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=6, callback=183, payload=B7 06 00 00
2017-09-24 10:54:01.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=6, callback=183, payload=B7 06 00 00
2017-09-24 10:54:01.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=6, callback=183, payload=B7 06 00 00
2017-09-24 10:54:01.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 335: [WAIT_REQUEST] requiresResponse=true callback: 183
2017-09-24 10:54:01.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2017-09-24 10:54:01.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 335: [WAIT_REQUEST] requiresResponse=true callback: 183
2017-09-24 10:54:01.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 335: (Callback 183)
2017-09-24 10:54:01.072 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2017-09-24 10:54:01.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 335: callback 183
2017-09-24 10:54:01.078 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=6, callback=183, payload=B7 06 00 00
2017-09-24 10:54:01.081 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2017-09-24 10:54:01.083 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-09-24 10:54:01.086 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 335: TransactionAdvance ST: WAIT_REQUEST
2017-09-24 10:54:01.089 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 335: TransactionAdvance WT: null {}
2017-09-24 10:54:01.092 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 335: TransactionAdvance RX: Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], dest=6, callback=183, payload=B7 06 00 00
2017-09-24 10:54:01.095 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 335: TransactionAdvance RQ: RREQ=true, RCLS=null
2017-09-24 10:54:01.097 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 335: TransactionAdvance TO: DONE
2017-09-24 10:54:01.099 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 335: Advanced to DONE
2017-09-24 10:54:01.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 104ms
2017-09-24 10:54:01.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 335: Transaction completed
2017-09-24 10:54:01.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:335 DONE
2017-09-24 10:54:01.110 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-09-24 10:54:01.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-09-24 10:54:01.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-09-24 10:54:01.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:01.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:54:01.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:01.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Node not awake!
2017-09-24 10:54:01.129 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:01.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Node not awake!
2017-09-24 10:54:01.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:01.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:54:01.139 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:01.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 9: Node not awake!
2017-09-24 10:54:01.145 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 11: listening == false, frequentlyListening == false, awake == false
2017-09-24 10:54:01.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 11: Node not awake!
2017-09-24 10:54:01.150 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-09-24 10:54:01.152 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2017-09-24 10:54:01.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer