Hi @chris ,
i am getting an error log
Sent Data was not placed on stack due to error 0
for my zwave door lock zm1701,
i just want to know in which scenario this log comes…? (ideal reason behind this error)
It means the stick didn’t accept the request - probably it is busy or something.
Hi
i am pasting some logs for doorlock zm1701
2016-08-24 09:07:12.539 [INFO ] [.o.core.internal.CoreActivator] - openHAB runtime has been started (v1.7.0).
2016-08-24 09:07:16.792 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - mDNS service has been started
2016-08-24 09:07:17.142 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - Service Discovery initialization completed.
2016-08-24 09:07:17.165 [INFO ] [.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2016-08-24 09:07:17.168 [INFO ] [o.i.t.m.i.MqttBrokerConnection] - Starting MQTT broker connection 'mosquitto'
2016-08-24 09:07:24.423 [INFO ] [.z.internal.ZWaveActiveBinding] - Update config, port = /dev/ttyACM0
2016-08-24 09:07:24.424 [INFO ] [.z.internal.ZWaveActiveBinding] - Update config, healtime = 2
2016-08-24 09:07:24.462 [INFO ] [.p.c.ZWaveSecurityCommandClass] - Update networkKey
2016-08-24 09:07:24.465 [INFO ] [.service.AbstractActiveService] - ZWave Refresh Service has been started
2016-08-24 09:07:24.562 [INFO ] [b.z.i.protocol.ZWaveController] - Starting Z-Wave controller
2016-08-24 09:07:24.564 [INFO ] [b.z.i.protocol.ZWaveController] - Z-Wave timeout is set to 5000ms. Soft reset is false.
2016-08-24 09:07:24.565 [INFO ] [b.z.i.protocol.ZWaveController] - Connecting to serial port /dev/ttyACM0
2016-08-24 09:07:24.676 [ERROR] [b.z.i.protocol.ZWaveController] - Serial Error: Port /dev/ttyACM0 does not exist
2016-08-24 09:07:25.215 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'wave.rules'
2016-08-24 09:07:25.846 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'wave.items'
2016-08-24 09:07:29.466 [INFO ] [.service.AbstractActiveService] - ZWave Refresh Service has been shut down
2016-08-24 12:38:12.220 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0x00,handlesMode = 0x10, doorCondition = 0x02, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 14:25:56.016 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0x00,handlesMode = 0x10, doorCondition = 0x02, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 14:26:04.893 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0x00,handlesMode = 0x10, doorCondition = 0x02, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 14:26:19.396 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0x00,handlesMode = 0x10, doorCondition = 0x02, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 15:04:52.711 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0x00,handlesMode = 0x10, doorCondition = 0x02, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 16:09:48.969 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0x00,handlesMode = 0x10, doorCondition = 0x02, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 19:52:28.930 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0xff,handlesMode = 0x10, doorCondition = 0x00, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 20:26:05.476 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0x00,handlesMode = 0x10, doorCondition = 0x02, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 21:06:19.382 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: Received new nonce from device, ejecting Nonce RECEIVED 3A 02 79 59 C5 8F D5 BC ; time left=4943.
2016-08-24 21:06:19.467 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0x00,handlesMode = 0x10, doorCondition = 0x02, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 21:22:08.920 [WARN ] [b.z.i.c.ZWaveDoorLockConverter] - NODE 107: No converter found for item = DOOR_LOCK_233_234_6_1, endpoint = 0, command =ON
2016-08-24 21:22:13.927 [ERROR] [WaveController$ZWaveSendThread] - NODE 107: Timeout while sending message. Requeueing - 2 attempts left!
2016-08-24 21:22:13.928 [ERROR] [b.z.i.p.s.SendDataMessageClass] - NODE 107: Got an error while sending data. Resending message: Message: class = SendData (0x13), type = Request (0x00), payload = 6B 02 98 40 , callbackid = 23
2016-08-24 21:22:18.940 [ERROR] [WaveController$ZWaveSendThread] - NODE 107: Timeout while sending message. Requeueing - 1 attempts left!
2016-08-24 21:22:18.941 [ERROR] [b.z.i.p.s.SendDataMessageClass] - NODE 107: Got an error while sending data. Resending message: Message: class = SendData (0x13), type = Request (0x00), payload = 6B 02 98 40 , callbackid = 23
2016-08-24 21:22:18.954 [ERROR] [b.z.i.p.s.SendDataMessageClass] - NODE 107: Sent Data was not placed on stack due to error 0.
2016-08-24 21:22:19.886 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: Received new nonce from device, ejecting Nonce RECEIVED FA 77 59 98 3B 49 C5 0A ; time left=4920.
2016-08-24 21:22:19.933 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: nonce was received, but we have no requestNonceTimer
2016-08-24 21:22:19.934 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: Received new nonce from device, ejecting Nonce RECEIVED FA 77 59 98 3B 49 C5 0A ; time left=4953.
2016-08-24 21:22:23.950 [ERROR] [WaveController$ZWaveSendThread] - NODE 107: Timeout while sending message. Requeueing - 0 attempts left!
2016-08-24 21:22:23.952 [ERROR] [b.z.i.p.s.SendDataMessageClass] - NODE 107: Got an error while sending data. Resending message: Message: class = SendData (0x13), type = Request (0x00), payload = 6B 02 98 40 , callbackid = 23
2016-08-24 21:22:28.164 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: nonce was received, but we have no requestNonceTimer
2016-08-24 21:22:28.165 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: Received new nonce from device, ejecting Nonce RECEIVED FA 77 59 98 3B 49 C5 0A ; time left=-3230.
2016-08-24 21:22:28.173 [ERROR] [b.z.i.p.s.SendDataMessageClass] - NODE 107: Sent Data was not placed on stack due to error 0.
2016-08-24 21:22:33.168 [ERROR] [WaveController$ZWaveSendThread] - NODE 107: Timeout while sending message. Requeueing - 2 attempts left!
2016-08-24 21:22:33.169 [ERROR] [b.z.i.p.s.SendDataMessageClass] - NODE 107: Got an error while sending data. Resending message: Message: class = SendData (0x13), type = Request (0x00), payload = 6B 03 62 01 FF , callbackid = 24
2016-08-24 21:22:35.112 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: nonce was received, but we have no requestNonceTimer
2016-08-24 21:22:35.113 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: Received new nonce from device, ejecting Nonce RECEIVED FA 77 59 98 3B 49 C5 0A ; time left=-1948.
2016-08-24 21:22:37.161 [ERROR] [b.z.i.p.s.SendDataMessageClass] - NODE 107: Got an error while sending data. Resending message: Message: class = SendData (0x13), type = Request (0x00), payload = 6B 03 62 01 FF , callbackid = 24
2016-08-24 21:22:37.163 [INFO ] [.p.c.ZWaveSecurityCommandClass] - NODE 107: Duplicate messge found, removing from payloadEncapsulationQueue: NODE 107: SecurityPayload (part 1 of 1) for DOOR_LOCK : Message: class = SendData (0x13), type = Request (0x00), payload = 62 01 FF
2016-08-24 21:22:37.977 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: nonce was received, but we have no requestNonceTimer
2016-08-24 21:22:37.978 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: Received new nonce from device, ejecting Nonce RECEIVED 1E 4B 7D B7 49 2C C9 7D ; time left=2136.
2016-08-24 21:22:39.111 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: Received new nonce from device, ejecting Nonce RECEIVED 1E 4B 7D B7 49 2C C9 7D ; time left=3867.
2016-08-24 21:22:48.712 [WARN ] [b.z.i.c.ZWaveDoorLockConverter] - NODE 107: No converter found for item = DOOR_LOCK_233_234_6_1, endpoint = 0, command =ON
2016-08-24 21:22:48.715 [INFO ] [.p.c.ZWaveSecurityCommandClass] - NODE 107: Duplicate messge found, removing from payloadEncapsulationQueue: NODE 107: SecurityPayload (part 1 of 1) for DOOR_LOCK : Message: class = SendData (0x13), type = Request (0x00), payload = 62 01 FF
2016-08-24 21:22:50.100 [WARN ] [.i.p.c.ZWaveSecureNonceTracker] - NODE 107: Received new nonce from device, ejecting Nonce RECEIVED C1 6F F3 4F 4E 38 CD 9D ; time left=4729.
2016-08-24 21:22:55.232 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0xff,handlesMode = 0x10, doorCondition = 0x00, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
2016-08-24 21:22:55.857 [INFO ] [.p.c.ZWaveDoorLockCommandClass] - NODE 107: Door Lock report - lockState = 0xff,handlesMode = 0x10, doorCondition = 0x00, lockTimeoutMinutes = 0xfe,lockTimeoutSeconds = 0xfe
the scenario happen is that i sent a command and that command got missed…but after 40 seconds, all commands worked well.
by looking at log can you just tell me what is happening at zwave level…
i just want your opinion…
i got error line what can be meaning of this…?
nonce was received, but we have no requestNonceTimer
I can’t really say much more I’m afraid. The error simply means that the stick didn’t accept the request - probably it was busy. Maybe there was some sort of network updata if you have multiple controllers - I don’t know why it would last for a short time then be ok.
This is also a security transaction so that makes things more difficult as there’s more traffic that needs to be sent. I’m not completely convinced that the security classes handle transactions properly in OH1, so this might also explain the problem.
When you provide logs, please can you format using the <> button - this makes it easier to read (otherwise I have to reformat your message).