Hello again,
@chris I have to bring up this thread again. After upgrading to the latest nightly build from yesterday 2017-01-12 the problem with my Everspring Motion Sensor SP103 still exists. Everything else seems to work just fine right now.
The issue is: There seems to be no difference between waking up and detecting/notifying a motion. So it is not possible to react in a rule. Here are some more information out of my zwave.log (after enabling logging, thanks for that again @jaydee73).
Motion event:
2017-01-13 08:27:09.734 [ZWaveController ] - Process Message = 01 09 00 04 00 07 03 20 01 00 D7
2017-01-13 08:27:09.734 [ZWaveController ] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 07 03 20 01 00
2017-01-13 08:27:09.734 [ZWaveController ] - Incoming Message type = REQUEST
2017-01-13 08:27:09.735 [icationCommandMessageClass] - Handle Message Application Command Request
2017-01-13 08:27:09.735 [icationCommandMessageClass] - NODE 7: Application Command Request (ALIVE:DONE)
2017-01-13 08:27:09.735 [ZWaveNodeInitStageAdvancer] - NODE 7: Starting initialisation from DONE
2017-01-13 08:27:09.735 [ZWaveController ] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b392bc already registered
2017-01-13 08:27:09.735 [icationCommandMessageClass] - NODE 7: Incoming command class BASIC
2017-01-13 08:27:09.736 [icationCommandMessageClass] - NODE 7: Found Command Class BASIC, passing to handleApplicationCommandRequest
2017-01-13 08:27:09.736 [ZWaveBasicCommandClass ] - NODE 7: Received Basic Request
2017-01-13 08:27:09.736 [ZWaveBasicCommandClass ] - NODE 7: Basic Set sent to the controller will be processed as Basic Report
2017-01-13 08:27:09.736 [ZWaveBasicCommandClass ] - NODE 7: Basic report, value = 0x00
2017-01-13 08:27:09.736 [ZWaveController ] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-01-13 08:27:09.737 [ZWaveThingHandler ] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-01-13 08:27:09.737 [ZWaveThingHandler ] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-01-13 08:27:09.737 [ZWaveThingHandler ] - NODE 7: Updating channel state zwave:device:d00c896c:node7:sensor_binary to OFF [OnOffType]
Wake Up event:
2017-01-12 22:03:37.002 [ZWaveController ] - Process Message = 01 08 00 04 00 07 02 84 07 75
2017-01-12 22:03:37.002 [ZWaveController ] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 07 02 84 07
2017-01-12 22:03:37.003 [ZWaveController ] - Incoming Message type = REQUEST
2017-01-12 22:03:37.003 [icationCommandMessageClass] - Handle Message Application Command Request
2017-01-12 22:03:37.003 [icationCommandMessageClass] - NODE 7: Application Command Request (ALIVE:DONE)
2017-01-12 22:03:37.003 [ZWaveNodeInitStageAdvancer] - NODE 7: Starting initialisation from DONE
2017-01-12 22:03:37.004 [ZWaveController ] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b392bc already registered
2017-01-12 22:03:37.004 [icationCommandMessageClass] - NODE 7: Incoming command class WAKE_UP
2017-01-12 22:03:37.004 [icationCommandMessageClass] - NODE 7: Found Command Class WAKE_UP, passing to handleApplicationCommandRequest
2017-01-12 22:03:37.004 [ZWaveWakeUpCommandClass ] - NODE 7: Received Wake Up Request
2017-01-12 22:03:37.005 [ZWaveWakeUpCommandClass ] - NODE 7: Received WAKE_UP_NOTIFICATION
2017-01-12 22:03:37.005 [ZWaveWakeUpCommandClass ] - NODE 7: Is awake with 3 messages in the wake-up queue.
2017-01-12 22:03:37.005 [ZWaveController ] - Notifying event listeners: ZWaveWakeUpEvent
2017-01-12 22:03:37.006 [ZWaveThingHandler ] - NODE 7: Got an event from Z-Wave network: ZWaveWakeUpEvent
2017-01-12 22:03:37.006 [ZWaveController ] - Callback ID = 23
2017-01-12 22:03:37.007 [ZWaveController ] - Message queued. Queue length = 1. Queue={}
2017-01-12 22:03:37.007 [Controller$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-01-12 22:03:37.007 [icationCommandMessageClass] - NODE 4: Transaction not completed: node address inconsistent. lastSent=4, incoming=255
2017-01-12 22:03:37.008 [SerialMessage ] - Calculated checksum = 0xE0
2017-01-12 22:03:37.008 [SerialMessage ] - Assembled message buffer = 01 09 00 13 07 02 30 02 25 17 E0
2017-01-12 22:03:37.009 [ZWaveSerialHandler ] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 30 02 25 17 E0
2017-01-12 22:03:37.011 [ZWaveSerialHandler ] - Message SENT
2017-01-12 22:03:37.013 [Handler$ZWaveReceiveThread] - Received ACK
2017-01-12 22:03:37.016 [Handler$ZWaveReceiveThread] - Received SOF
2017-01-12 22:03:37.017 [Handler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2017-01-12 22:03:37.018 [SerialMessage ] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8
2017-01-12 22:03:37.019 [SerialMessage ] - Calculated checksum = 0xE8
2017-01-12 22:03:37.019 [SerialMessage ] - NODE 255: Checksum matched
2017-01-12 22:03:37.020 [SerialMessage ] - NODE 255: Message payload = 01
2017-01-12 22:03:37.020 [Handler$ZWaveReceiveThread] - Message is valid, sending ACK
2017-01-12 22:03:37.022 [Handler$ZWaveReceiveThread] - Response SENT
2017-01-12 22:03:37.022 [ZWaveController ] - Receive queue TAKE: Length=0
2017-01-12 22:03:37.023 [SerialMessage ] - Calculated checksum = 0xE8
2017-01-12 22:03:37.023 [SerialMessage ] - Assembled message buffer = 01 04 01 13 01 E8
2017-01-12 22:03:37.023 [ZWaveController ] - Process Message = 01 04 01 13 01 E8
2017-01-12 22:03:37.024 [ZWaveController ] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-01-12 22:03:37.024 [ZWaveController ] - Incoming Message type = RESPONSE
2017-01-12 22:03:37.024 [SendDataMessageClass ] - Handle Message Send Data Response
2017-01-12 22:03:37.024 [SendDataMessageClass ] - NODE 7: Sent Data successfully placed on stack.
2017-01-12 22:03:37.025 [SerialMessage ] - Ack Pending cleared
2017-01-12 22:03:37.028 [Handler$ZWaveReceiveThread] - Received SOF
2017-01-12 22:03:37.030 [Handler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 17 00 FE
2017-01-12 22:03:37.030 [SerialMessage ] - NODE 255: Creating new SerialMessage from buffer = 01 05 00 13 17 00 FE
2017-01-12 22:03:37.031 [SerialMessage ] - Calculated checksum = 0xFE
2017-01-12 22:03:37.032 [SerialMessage ] - NODE 255: Checksum matched
2017-01-12 22:03:37.033 [SerialMessage ] - NODE 255: Message payload = 17 00
2017-01-12 22:03:37.033 [Handler$ZWaveReceiveThread] - Message is valid, sending ACK
2017-01-12 22:03:37.035 [Handler$ZWaveReceiveThread] - Response SENT
2017-01-12 22:03:37.035 [ZWaveController ] - Receive queue TAKE: Length=0
2017-01-12 22:03:37.035 [SerialMessage ] - Calculated checksum = 0xFC
2017-01-12 22:03:37.036 [Handler$ZWaveReceiveThread] - Received SOF
2017-01-12 22:03:37.036 [SerialMessage ] - Assembled message buffer = 01 07 00 13 17 00 00 00 FC
2017-01-12 22:03:37.036 [ZWaveController ] - Process Message = 01 07 00 13 17 00 00 00 FC
2017-01-12 22:03:37.037 [ZWaveController ] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=17 00
2017-01-12 22:03:37.037 [ZWaveController ] - Incoming Message type = REQUEST
2017-01-12 22:03:37.038 [SendDataMessageClass ] - Handle Message Send Data Request
2017-01-12 22:03:37.038 [SendDataMessageClass ] - NODE 7: SendData Request. CallBack ID = 23, Status = Transmission complete and ACK received(0)
2017-01-12 22:03:37.039 [ZWaveNodeInitStageAdvancer] - NODE 7: Starting initialisation from DONE
2017-01-12 22:03:37.041 [ZWaveController ] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b392bc already registered
2017-01-12 22:03:37.042 [ZWaveCommandProcessor ] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=7, callback=23, payload=07 02 30 02
2017-01-12 22:03:37.042 [ZWaveCommandProcessor ] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=17 00
2017-01-12 22:03:37.043 [ZWaveCommandProcessor ] - Checking transaction complete: class=SendData, callback id=23, expected=ApplicationCommandHandler, cancelled=false MISMATCH
2017-01-12 22:03:37.049 [Handler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 07 03 30 03 00 C5
2017-01-12 22:03:37.050 [SerialMessage ] - NODE 255: Creating new SerialMessage from buffer = 01 09 00 04 00 07 03 30 03 00 C5
2017-01-12 22:03:37.051 [SerialMessage ] - Calculated checksum = 0xC5
2017-01-12 22:03:37.051 [SerialMessage ] - NODE 255: Checksum matched
2017-01-12 22:03:37.052 [SerialMessage ] - NODE 255: Message payload = 00 07 03 30 03 00
2017-01-12 22:03:37.052 [Handler$ZWaveReceiveThread] - Message is valid, sending ACK
2017-01-12 22:03:37.054 [Handler$ZWaveReceiveThread] - Response SENT
2017-01-12 22:03:37.055 [ZWaveController ] - Receive queue TAKE: Length=0
2017-01-12 22:03:37.055 [SerialMessage ] - Calculated checksum = 0xC5
2017-01-12 22:03:37.056 [SerialMessage ] - Assembled message buffer = 01 09 00 04 00 07 03 30 03 00 C5
2017-01-12 22:03:37.056 [ZWaveController ] - Process Message = 01 09 00 04 00 07 03 30 03 00 C5
2017-01-12 22:03:37.056 [ZWaveController ] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 07 03 30 03 00
2017-01-12 22:03:37.057 [ZWaveController ] - Incoming Message type = REQUEST
2017-01-12 22:03:37.057 [icationCommandMessageClass] - Handle Message Application Command Request
2017-01-12 22:03:37.057 [icationCommandMessageClass] - NODE 7: Application Command Request (ALIVE:DONE)
2017-01-12 22:03:37.057 [ZWaveNodeInitStageAdvancer] - NODE 7: Starting initialisation from DONE
2017-01-12 22:03:37.058 [ZWaveController ] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b392bc already registered
2017-01-12 22:03:37.058 [icationCommandMessageClass] - NODE 7: Incoming command class SENSOR_BINARY
2017-01-12 22:03:37.058 [icationCommandMessageClass] - NODE 7: Found Command Class SENSOR_BINARY, passing to handleApplicationCommandRequest
2017-01-12 22:03:37.058 [veBinarySensorCommandClass] - Handle Message Sensor Binary Request
2017-01-12 22:03:37.059 [veBinarySensorCommandClass] - NODE 7: Received SENSOR_BINARY command V1
2017-01-12 22:03:37.059 [veBinarySensorCommandClass] - Process Sensor Binary Report
2017-01-12 22:03:37.059 [veBinarySensorCommandClass] - NODE 7: Sensor Binary report, type=Unknown, value=0
2017-01-12 22:03:37.059 [ZWaveController ] - Notifying event listeners: ZWaveBinarySensorValueEvent
2017-01-12 22:03:37.060 [ZWaveThingHandler ] - NODE 7: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2017-01-12 22:03:37.060 [ZWaveThingHandler ] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 0
2017-01-12 22:03:37.060 [ZWaveThingHandler ] - NODE 7: Updating channel state zwave:device:d00c896c:node7:sensor_binary to OFF [OnOffType]
2017-01-12 22:03:37.062 [ZWaveCommandProcessor ] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=7, callback=23, payload=07 02 30 02
2017-01-12 22:03:37.063 [ZWaveCommandProcessor ] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 07 03 30 03 00
2017-01-12 22:03:37.064 [ZWaveCommandProcessor ] - Checking transaction complete: class=ApplicationCommandHandler, callback id=23, expected=ApplicationCommandHandler, cancelled=false transaction complete!
2017-01-12 22:03:37.064 [ZWaveController ] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-01-12 22:03:37.064 [ZWaveController ] - Callback ID = 24
2017-01-12 22:03:37.065 [ZWaveController ] - Message queued. Queue length = 1. Queue={}
2017-01-12 22:03:37.065 [ZWaveThingHandler ] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-01-12 22:03:37.065 [ZWaveController ] - Released. Transaction completed permit count -> 1
2017-01-12 22:03:37.065 [Controller$ZWaveSendThread] - NODE 7: Response processed after 54ms/4814ms.
2017-01-12 22:03:37.066 [Controller$ZWaveSendThread] - Acquired. Transaction completed permit count -> 0
2017-01-12 22:03:37.066 [Controller$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-01-12 22:03:37.067 [SerialMessage ] - Calculated checksum = 0xAB
2017-01-12 22:03:37.068 [SerialMessage ] - Assembled message buffer = 01 0C 00 13 07 05 71 04 00 01 00 25 18 AB
2017-01-12 22:03:37.068 [ZWaveSerialHandler ] - NODE 7: Sending REQUEST Message = 01 0C 00 13 07 05 71 04 00 01 00 25 18 AB
2017-01-12 22:03:37.069 [ZWaveSerialHandler ] - Message SENT
2017-01-12 22:03:37.072 [Handler$ZWaveReceiveThread] - Received ACK
2017-01-12 22:03:37.075 [Handler$ZWaveReceiveThread] - Received SOF
2017-01-12 22:03:37.076 [Handler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2017-01-12 22:03:37.077 [SerialMessage ] - NODE 255: Creating new SerialMessage from buffer = 01 04 01 13 01 E8
2017-01-12 22:03:37.077 [SerialMessage ] - Calculated checksum = 0xE8
2017-01-12 22:03:37.077 [SerialMessage ] - NODE 255: Checksum matched
2017-01-12 22:03:37.078 [SerialMessage ] - NODE 255: Message payload = 01
2017-01-12 22:03:37.078 [Handler$ZWaveReceiveThread] - Message is valid, sending ACK
2017-01-12 22:03:37.079 [Handler$ZWaveReceiveThread] - Response SENT
2017-01-12 22:03:37.079 [ZWaveController ] - Receive queue TAKE: Length=0
2017-01-12 22:03:37.080 [SerialMessage ] - Calculated checksum = 0xE8
2017-01-12 22:03:37.081 [SerialMessage ] - Assembled message buffer = 01 04 01 13 01 E8
2017-01-12 22:03:37.081 [ZWaveController ] - Process Message = 01 04 01 13 01 E8
2017-01-12 22:03:37.082 [ZWaveController ] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-01-12 22:03:37.082 [ZWaveController ] - Incoming Message type = RESPONSE
2017-01-12 22:03:37.083 [SendDataMessageClass ] - Handle Message Send Data Response
2017-01-12 22:03:37.083 [SendDataMessageClass ] - NODE 7: Sent Data successfully placed on stack.
2017-01-12 22:03:37.083 [SerialMessage ] - Ack Pending cleared
2017-01-12 22:03:37.087 [Handler$ZWaveReceiveThread] - Received SOF
2017-01-12 22:03:37.091 [Handler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 18 00 F1
2017-01-12 22:03:37.092 [SerialMessage ] - NODE 255: Creating new SerialMessage from buffer = 01 05 00 13 18 00 F1
2017-01-12 22:03:37.093 [SerialMessage ] - Calculated checksum = 0xF1
2017-01-12 22:03:37.093 [SerialMessage ] - NODE 255: Checksum matched
2017-01-12 22:03:37.094 [SerialMessage ] - NODE 255: Message payload = 18 00
2017-01-12 22:03:37.094 [Handler$ZWaveReceiveThread] - Message is valid, sending ACK
2017-01-12 22:03:37.096 [Handler$ZWaveReceiveThread] - Response SENT
2017-01-12 22:03:37.096 [ZWaveController ] - Receive queue TAKE: Length=0
2017-01-12 22:03:37.097 [SerialMessage ] - Calculated checksum = 0xF3
2017-01-12 22:03:37.098 [SerialMessage ] - Assembled message buffer = 01 07 00 13 18 00 00 00 F3
2017-01-12 22:03:37.098 [ZWaveController ] - Process Message = 01 07 00 13 18 00 00 00 F3
2017-01-12 22:03:37.099 [ZWaveController ] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=18 00
2017-01-12 22:03:37.099 [ZWaveController ] - Incoming Message type = REQUEST
2017-01-12 22:03:37.099 [SendDataMessageClass ] - Handle Message Send Data Request
2017-01-12 22:03:37.100 [SendDataMessageClass ] - NODE 7: SendData Request. CallBack ID = 24, Status = Transmission complete and ACK received(0)
2017-01-12 22:03:37.100 [ZWaveNodeInitStageAdvancer] - NODE 7: Starting initialisation from DONE
2017-01-12 22:03:37.101 [ZWaveController ] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b392bc already registered
2017-01-12 22:03:37.102 [ZWaveCommandProcessor ] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=7, callback=24, payload=07 05 71 04 00 01 00
2017-01-12 22:03:37.102 [ZWaveCommandProcessor ] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=18 00
2017-01-12 22:03:37.103 [ZWaveCommandProcessor ] - Checking transaction complete: class=SendData, callback id=24, expected=ApplicationCommandHandler, cancelled=false MISMATCH
2017-01-12 22:03:42.069 [Controller$ZWaveSendThread] - NODE 7: Timeout while sending message. Requeueing - 2 attempts left!
2017-01-12 22:03:42.069 [SendDataMessageClass ] - NODE 7: Handling failed message.
2017-01-12 22:03:42.070 [ZWaveWakeUpCommandClass ] - NODE 7: Is sleeping
2017-01-12 22:03:42.070 [ZWaveWakeUpCommandClass ] - NODE 7: Putting message SendData in wakeup queue.
Most interesting lines in my opinion are:
2017-01-13 08:27:09.737 [ZWaveThingHandler ] - NODE 7: Updating channel state zwave:device:d00c896c:node7:sensor_binary to OFF [OnOffType]
2017-01-12 22:03:37.060 [ZWaveThingHandler ] - NODE 7: Updating channel state zwave:device:d00c896c:node7:sensor_binary to OFF [OnOffType]
So how should my trigger for the rule be to just react on a motion, but not on a normal wakeup?
Thanks again