OK, easier then I thought.
- Start karaf: /usr/share/openhab2/runtime/karaf/bin/client
- Start extra log (in karaf): log:set DEBUG org.openhab.binding.zwave
- Check /var/log/openhab/* via another console
This is some debug logs:
2016-10-22 19:07:37.937 [ItemStateChangedEvent ] - zwave_serial_zstick_ef2351c6_serial_sof changed from 1871 to 1872
==> logs/openhab.log <==
2016-10-22 19:07:37.951 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 55 03 80 03 64 43
2016-10-22 19:07:37.954 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-22 19:07:37.954 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 55 03 80 03 64 43
2016-10-22 19:07:37.954 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 55 03 80 03 64 43
2016-10-22 19:07:37.954 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 55 03 80 03 64
2016-10-22 19:07:37.954 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 85: Application Command Request (ALIVE:DONE)
2016-10-22 19:07:37.956 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 85: Starting initialisation from DONE
2016-10-22 19:07:37.956 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5e8f64bf already registered
2016-10-22 19:07:37.956 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 85: Incoming command class BATTERY
2016-10-22 19:07:37.956 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 85: Received Battery Request
2016-10-22 19:07:37.956 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 85: Battery report value = 100
2016-10-22 19:07:37.956 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-22 19:07:37.957 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=85, callback=126, payload=55 02 84 08
2016-10-22 19:07:37.957 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 55 03 80 03 64
2016-10-22 19:07:37.957 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=126, expected=SendData, cancelled=false MISMATCH
==> logs/events.log <==
2016-10-22 19:07:38.080 [ItemStateChangedEvent ] - zwave_serial_zstick_ef2351c6_serial_sof changed from 1872 to 1873
==> logs/openhab.log <==
2016-10-22 19:07:38.094 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 55 03 80 03 64 43
2016-10-22 19:07:38.096 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-22 19:07:38.096 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 55 03 80 03 64 43
2016-10-22 19:07:38.096 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 55 03 80 03 64 43
2016-10-22 19:07:38.096 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 55 03 80 03 64
2016-10-22 19:07:38.096 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 85: Application Command Request (ALIVE:DONE)
2016-10-22 19:07:38.096 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 85: Starting initialisation from DONE
2016-10-22 19:07:38.096 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5e8f64bf already registered
2016-10-22 19:07:38.096 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 85: Incoming command class BATTERY
2016-10-22 19:07:38.097 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 85: Received Battery Request
2016-10-22 19:07:38.097 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 85: Battery report value = 100
2016-10-22 19:07:38.097 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-22 19:07:38.097 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=85, callback=126, payload=55 02 84 08
2016-10-22 19:07:38.097 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 55 03 80 03 64
2016-10-22 19:07:38.097 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=126, expected=SendData, cancelled=false MISMATCH
==> logs/events.log <==
2016-10-22 19:07:38.198 [ItemStateChangedEvent ] - zwave_serial_zstick_ef2351c6_serial_sof changed from 1873 to 1874
==> logs/openhab.log <==
2016-10-22 19:07:38.209 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 04 55 02 84 07 23
2016-10-22 19:07:38.211 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-22 19:07:38.211 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 04 04 55 02 84 07 23
2016-10-22 19:07:38.211 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 08 00 04 04 55 02 84 07 23
2016-10-22 19:07:38.212 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=04 55 02 84 07
2016-10-22 19:07:38.212 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 85: Application Command Request (ALIVE:DONE)
2016-10-22 19:07:38.212 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 85: Starting initialisation from DONE
2016-10-22 19:07:38.212 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5e8f64bf already registered
2016-10-22 19:07:38.212 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 85: Incoming command class WAKE_UP
2016-10-22 19:07:38.212 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 85: Received Wake Up Request
2016-10-22 19:07:38.212 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 85: Received WAKE_UP_NOTIFICATION
2016-10-22 19:07:38.212 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 85: Is awake with 0 messages in the wake-up queue.
2016-10-22 19:07:38.212 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveWakeUpEvent
2016-10-22 19:07:38.212 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=85, callback=126, payload=55 02 84 08
2016-10-22 19:07:38.213 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=04 55 02 84 07
2016-10-22 19:07:38.213 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=126, expected=SendData, cancelled=true MISMATCH
2016-10-22 19:07:39.213 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 85: No more messages, go back to sleep
2016-10-22 19:07:39.213 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 85: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2016-10-22 19:07:39.213 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2016-10-22 19:07:39.213 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2016-10-22 19:07:39.213 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 55 02 84 08 25 7F 64
2016-10-22 19:07:39.213 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 85: Sending REQUEST Message = 01 09 00 13 55 02 84 08 25 7F 64
==> logs/events.log <==
2016-10-22 19:07:39.216 [ItemStateChangedEvent ] - zwave_serial_zstick_ef2351c6_serial_ack changed from 651 to 652
2016-10-22 19:07:39.222 [ItemStateChangedEvent ] - zwave_serial_zstick_ef2351c6_serial_sof changed from 1874 to 1875
==> logs/openhab.log <==
2016-10-22 19:07:39.229 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2016-10-22 19:07:39.230 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-22 19:07:39.230 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2016-10-22 19:07:39.230 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2016-10-22 19:07:39.230 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2016-10-22 19:07:39.231 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 85: Sent Data successfully placed on stack.
==> logs/events.log <==
2016-10-22 19:07:39.238 [ItemStateChangedEvent ] - zwave_serial_zstick_ef2351c6_serial_sof changed from 1875 to 1876
==> logs/openhab.log <==
2016-10-22 19:07:39.251 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 7F 00 00 02 96
2016-10-22 19:07:39.254 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-22 19:07:39.255 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 7F 00 00 02 00 00 98
2016-10-22 19:07:39.255 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 7F 00 00 02 00 00 98
2016-10-22 19:07:39.255 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=7F 00 00 02
2016-10-22 19:07:39.255 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 85: SendData Request. CallBack ID = 127, Status = Transmission complete and ACK received(0)
2016-10-22 19:07:39.255 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 85: Starting initialisation from DONE
2016-10-22 19:07:39.255 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5e8f64bf already registered
2016-10-22 19:07:39.255 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=85, callback=127, payload=55 02 84 08
2016-10-22 19:07:39.255 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=7F 00 00 02
2016-10-22 19:07:39.255 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=127, expected=SendData, cancelled=false transaction complete!
2016-10-22 19:07:39.255 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2016-10-22 19:07:39.255 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 85: Went to sleep
2016-10-22 19:07:39.255 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 85: Is sleeping
2016-10-22 19:07:39.256 [DEBUG] [curityCommandClassWithInitialization] - NODE 85: updating lastSentMessageTimestamp
2016-10-22 19:07:39.256 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 85: Response processed after 42ms/4842ms.