System: OpenHab2 running on Windows 7 x64, Using Aeon Labs Gen5 Z-stick, Habmin2 to do most of my config
I got some Monoprice sensors working no-problem. Excited about being able to use my technical know-how to work with cheap sensors I invested in the Go-Control 3 pack of two door sensors and a multi/motion sensor. I can’t get the motion sensor which is a WAPIRZ-1 to initialize - it remains “unknown device” after letting it sit many days, repeated wake-ups by opening the cover etc… It has yet to populate the manufacturer info in the thing attributes. I’m getting messages after wake in the log but still not results. Is this a new thing config that needs to be added? I’m a little lost on next steps and I’m not fully set up with the dev environment on this new host to crack anything open. TIL - the cheap stuff will work like cheap stuf
Log during last wake-up cycle/session for this node:
2017-04-02 18:32:24.059 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling deferred until initialisation complete
2017-04-03 22:45:46.043 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DETAILS)
2017-04-03 22:45:46.043 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class WAKE_UP
2017-04-03 22:45:46.043 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Received Wake Up Request
2017-04-03 22:45:46.043 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Received WAKE_UP_NOTIFICATION
2017-04-03 22:45:46.043 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Is awake with 2 messages in the wake-up queue.
2017-04-03 22:45:46.044 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Wakeup during initialisation.
2017-04-03 22:45:46.044 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - DETAILS: queue length(1), free to send(false)
2017-04-03 22:45:46.044 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - queued packet. Queue length is 1
2017-04-03 22:45:46.044 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveWakeUpEvent
2017-04-03 22:45:46.044 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 08 00 13 06 01 00 25 42 84
2017-04-03 22:45:46.052 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.
2017-04-03 22:45:46.068 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 66, Status = Transmission complete and ACK received(0)
2017-04-03 22:45:46.069 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - DETAILS: Transaction complete (SendData:Request) success(true)
2017-04-03 22:45:46.069 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2017-04-03 22:45:46.069 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - message removed from queue. Queue size 0.
2017-04-03 22:45:46.069 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - DETAILS: queue length(0), free to send(true)
2017-04-03 22:45:46.069 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: loop - DETAILS try 2: stageAdvanced(false)
2017-04-03 22:45:46.069 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: DETAILS - send RequestNodeInfo
2017-04-03 22:45:46.070 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - queued packet. Queue length is 1
2017-04-03 22:45:46.070 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-04-03 22:45:46.070 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 26ms/4991ms.
2017-04-03 22:45:46.070 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 08 00 13 06 01 00 25 44 82
2017-04-03 22:45:46.078 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.
2017-04-03 22:45:46.095 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 68, Status = Transmission complete and ACK received(0)
2017-04-03 22:45:46.095 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - DETAILS: Transaction complete (SendData:Request) success(true)
2017-04-03 22:45:46.095 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2017-04-03 22:45:46.095 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-04-03 22:45:46.095 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 25ms/4991ms.
2017-04-03 22:45:46.096 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 09 00 13 06 02 84 08 25 43 0B
2017-04-03 22:45:46.104 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.
2017-04-03 22:45:46.121 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 67, Status = Transmission complete and ACK received(0)
2017-04-03 22:45:46.121 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - DETAILS: Transaction complete (SendData:Request) success(true)
2017-04-03 22:45:46.121 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2017-04-03 22:45:46.121 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-04-03 22:45:46.121 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Went to sleep
2017-04-03 22:45:46.121 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Is sleeping
2017-04-03 22:45:46.121 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 25ms/4991ms.
2017-04-03 22:45:46.164 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 6: Application update request. Node information received.
2017-04-03 22:45:46.164 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Creating new instance of command class ASSOCIATION
2017-04-03 22:45:46.164 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Command class ASSOCIATION, endpoint null created
2017-04-03 22:45:46.164 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 6: Application update is adding command class ASSOCIATION.
2017-04-03 22:45:46.164 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Adding command class ASSOCIATION to the list of supported command classes.
2017-04-03 22:45:46.164 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Creating new instance of command class BATTERY
2017-04-03 22:45:46.164 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Command class BATTERY, endpoint null created
2017-04-03 22:45:46.164 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 6: Application update is adding command class BATTERY.
2017-04-03 22:45:46.164 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Adding command class BATTERY to the list of supported command classes.
2017-04-03 22:45:46.164 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Creating new instance of command class MANUFACTURER_SPECIFIC
2017-04-03 22:45:46.165 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Command class MANUFACTURER_SPECIFIC, endpoint null created
2017-04-03 22:45:46.165 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 6: Application update is adding command class MANUFACTURER_SPECIFIC.
2017-04-03 22:45:46.165 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Adding command class MANUFACTURER_SPECIFIC to the list of supported command classes.
2017-04-03 22:45:46.165 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Creating new instance of command class VERSION
2017-04-03 22:45:46.165 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Command class VERSION, endpoint null created
2017-04-03 22:45:46.165 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 6: Application update is adding command class VERSION.
2017-04-03 22:45:46.165 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Adding command class VERSION to the list of supported command classes.
2017-04-03 22:45:46.165 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Creating new instance of command class SENSOR_MULTILEVEL
2017-04-03 22:45:46.165 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Command class SENSOR_MULTILEVEL, endpoint null created
2017-04-03 22:45:46.165 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 6: Application update is adding command class SENSOR_MULTILEVEL.
2017-04-03 22:45:46.165 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Adding command class SENSOR_MULTILEVEL to the list of supported command classes.
2017-04-03 22:45:46.165 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Creating new instance of command class CONFIGURATION
2017-04-03 22:45:46.165 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Command class CONFIGURATION, endpoint null created
2017-04-03 22:45:46.165 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 6: Application update is adding command class CONFIGURATION.
2017-04-03 22:45:46.165 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Adding command class CONFIGURATION to the list of supported command classes.
2017-04-03 22:45:46.165 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Is awake with 0 messages in the wake-up queue.
2017-04-03 22:45:46.165 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Wakeup during initialisation.
2017-04-03 22:45:46.165 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - DETAILS: queue length(1), free to send(false)
2017-04-03 22:45:46.165 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: loop - DETAILS try 3: stageAdvanced(false)
2017-04-03 22:45:46.165 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: received RequestNodeInfo
2017-04-03 22:45:46.165 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - advancing to INCLUSION_START
2017-04-03 22:45:46.165 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2017-04-03 22:45:46.165 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: loop - INCLUSION_START try 0: stageAdvanced(true)
2017-04-03 22:45:46.165 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: Unknown node state INCLUSION_START encountered.
2017-04-03 22:45:46.165 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - advancing to IDENTIFY_NODE
2017-04-03 22:45:46.165 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2017-04-03 22:45:46.166 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: loop - IDENTIFY_NODE try 0: stageAdvanced(true)
2017-04-03 22:45:46.166 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: PROTOINFO - send IdentifyNode
2017-04-03 22:45:46.166 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - queued packet. Queue length is 1
2017-04-03 22:45:46.166 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveWakeUpEvent
2017-04-03 22:45:46.166 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - IDENTIFY_NODE: Transaction complete (RequestNodeInfo:Request) success(true)
2017-04-03 22:45:46.166 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: ProtocolInfo
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: Listening = false
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: Routing = true
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: Beaming = true
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: Version = 4
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: FLIRS = false
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: Security = false
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: Max Baud = 40000
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: Basic = Routing Slave
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: Generic = Binary Sensor
2017-04-03 22:45:46.169 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 6: Specific = Routing Binary Sensor
2017-04-03 22:45:46.169 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Creating new instance of command class NO_OPERATION
2017-04-03 22:45:46.169 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Command class NO_OPERATION, endpoint null created
2017-04-03 22:45:46.169 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Version = 1, version set. Enabling extra functionality.
2017-04-03 22:45:46.169 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Creating new instance of command class BASIC
2017-04-03 22:45:46.169 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Command class BASIC, endpoint null created
2017-04-03 22:45:46.169 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Creating new instance of command class SENSOR_BINARY
2017-04-03 22:45:46.169 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Command class SENSOR_BINARY, endpoint null created
2017-04-03 22:45:46.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - IDENTIFY_NODE: Transaction complete (IdentifyNode:Request) success(true)
2017-04-03 22:45:46.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2017-04-03 22:45:46.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - message removed from queue. Queue size 0.
2017-04-03 22:45:46.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - IDENTIFY_NODE: queue length(0), free to send(true)
2017-04-03 22:45:46.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: loop - IDENTIFY_NODE try 1: stageAdvanced(false)
2017-04-03 22:45:46.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - advancing to MANUFACTURER
2017-04-03 22:45:46.170 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2017-04-03 22:45:46.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: loop - MANUFACTURER try 0: stageAdvanced(true)
2017-04-03 22:45:46.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer: MANUFACTURER - send ManufacturerSpecific
2017-04-03 22:45:46.170 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 6: Creating new message for command MANUFACTURER_SPECIFIC_GET
2017-04-03 22:45:46.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - queued packet. Queue length is 1
2017-04-03 22:45:46.170 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 09 00 13 06 02 72 04 25 45 F7
2017-04-03 22:45:46.179 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.
2017-04-03 22:45:51.172 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Timeout while sending message. Requeueing - 2 attempts left!
2017-04-03 22:45:51.172 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Is sleeping
2017-04-03 22:45:51.172 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Putting message SendData in wakeup queue.
2017-04-03 22:45:54.461 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 69, Status = Transmission complete, no ACK received(1)
2017-04-03 22:45:54.461 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Is sleeping
2017-04-03 22:45:54.461 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Message already on the wake-up queue. Removing original.
2017-04-03 22:45:54.461 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Putting message SendData in wakeup queue.
2017-04-03 22:45:54.461 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Node advancer - MANUFACTURER: Transaction complete (SendData:Request) success(false)
2017-04-03 22:45:54.461 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-04-03 22:45:54.461 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 6: Putting message SendData in wakeup queue.