Hi everyone,
I bought a Fibaro Motion Sensor - FGMS-001. Including the device in my fresh Openhab 2.4.0.1 on my raspi with “aeon labs z-stick gen5” works fine. In my network it is included as node16. My problem is that I could not see any update on all connected items of the sensor via the Log Viewer. I checked the log multilpe days.
my connected items are:
- zwave:device:691489f2:node16:sensor_temperature --> MotionSensorWohnzimmer_SensorTemperature
- zwave:device:691489f2:node16:alarm_motion --> MotionDetectorWohnzimmer1
- zwave:device:691489f2:node16:battery-level --> MotionSensorWohnzimmer_Batterieladung
After a successful setup the items are updated once with the current values. Unfortunately no further update on my items are recived. So I did many reboots and recognised that afer a reboot the values are updated, too. When I’m taking some movements in front of the sensor the eye changes color. It seems that the movement is recognised but not reported to my openhab. During troubleshooting I changed some preferences in PaperUI from that thing.
Currently it looks like that:
I did a DEBUG log from the zwave binding to get further information.
The debug log was started at 11:23 and stopped about 11:33:
11:22:12.892 [WARN ] [h.keygenerator.OpenSSHKeyPairProvider] - Failed to parse keypair in /var/lib/openhab2/etc/host.key. Attempting to parse it as a legacy 'simple' key
11:24:06.568 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 10 02 84 07 62
11:24:06.581 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=16, callback=0, payload=00 10 02 84 07
11:24:06.589 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=16, callback=0, payload=00 10 02 84 07
11:24:06.601 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
11:24:06.607 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Application Command Request (ALIVE:DONE)
11:24:06.615 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: resetResendCount initComplete=true isDead=false
11:24:06.622 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Is awake with 0 messages in the queue
11:24:06.629 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Start sleep timer at 1000ms
11:24:06.638 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveNodeStatusEvent
11:24:06.684 [DEBUG] [ave.internal.protocol.ZWaveController] - NODE 16: Node Status event - Node is AWAKE
11:24:06.688 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Commands processed 1.
11:24:06.692 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@9d55ff.
11:24:06.694 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing 'zwave:device:691489f2:node16' has been updated.
11:24:06.697 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
11:24:06.705 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
11:24:06.710 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:24:06.714 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:24:07.138 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: WakeupTimerTask 0 Messages waiting, state DONE
11:24:07.638 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: WakeupTimerTask 0 Messages waiting, state DONE
11:24:07.644 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: No more messages, go back to sleep
11:24:07.653 [DEBUG] [.commandclass.ZWaveWakeUpCommandClass] - NODE 16: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
11:24:07.663 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: SECURITY NOT required on COMMAND_CLASS_WAKE_UP
11:24:07.670 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
11:24:07.680 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1d00492
11:24:07.691 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Bump transaction 1416 priority from Immediate to Immediate
11:24:07.699 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Adding to device queue
11:24:07.709 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Added 1416 to queue - size 3
11:24:07.717 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:24:07.726 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 10 02 84 08 25 3D 63
11:24:07.736 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 16: Sending REQUEST Message = 01 09 00 13 10 02 84 08 25 3D 63
11:24:07.743 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:24:07.745 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:24:07.750 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1416: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 61
11:24:07.762 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:24:07.768 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:24:07.769 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
11:24:07.773 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 1416: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 61
11:24:07.777 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:24:07.777 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:24:07.781 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:24:07.788 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 3D 00 00 02 D4
11:24:07.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:24:07.796 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Request[0], dest=0, callback=61, payload=3D 00 00 02
11:24:07.804 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:24:07.812 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 1416: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 61
11:24:07.818 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:24:07.823 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 1416: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 61
11:24:07.828 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:24:07.832 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 16: sentData successfully placed on stack.
11:24:07.837 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1416: Advanced to WAIT_REQUEST
11:24:07.841 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: TID 1416: Transaction not completed
11:24:07.845 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=61, payload=3D 00 00 02
11:24:07.849 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 1416: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 61
11:24:07.852 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:24:07.856 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 1416: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 61
11:24:07.859 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 1416: (Callback 61)
11:24:07.863 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
11:24:07.866 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 1416: callback 61
11:24:07.871 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=61, payload=3D 00 00 02
11:24:07.875 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 16: SendData Request. CallBack ID = 61, Status = Transmission complete and ACK received(0)
11:24:07.879 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: resetResendCount initComplete=true isDead=false
11:24:07.884 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 1416: Transaction COMPLETED
11:24:07.887 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: Response processed after 138ms
11:24:07.891 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: TID 1416: Transaction completed
11:24:07.894 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 16: notifyTransactionResponse TID:1416 DONE
11:24:07.898 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 1416: Transaction event listener: DONE: DONE ->
11:24:07.898 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
11:24:07.904 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:24:07.905 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 16: Went to sleep COMPLETE
11:24:07.908 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
After 11:24 take some movements but no suitable log entry comes up.
Has anyone a idea why no update is reported?
