Zwave controller issue with no obvious trigger - Soft restart

Add-ons Bindings
1

@chris I know this is not something we can debug but for some reason today my Zwave “crashed” and restarted

I didn’t execute a softreset or similar command. but it decided to do so, by itself
I have had something similar a few days ago. but no reasons found

log shows only this:

  • Dec 26 10:46:32 homemanager : [.event.ItemStateChangedEvent] | ElectricityMeterDSMRV50_InstantVoltageL2 changed from 233.7 V to 233.6 V
  • Dec 26 10:46:32 homemanager : [.event.ItemStateChangedEvent] | ElectricityMeterDSMRV50_InstantPowerDeliveryL1 changed from 0.004 kW to 0.005 kW
  • Dec 26 10:46:32 homemanager : [.event.ItemStateChangedEvent] | ElectricityMeterDSMRV50_InstantPowerDeliveryL3 changed from 0.499 kW to 0.505 kW
  • Dec 26 10:46:37 homemanager : [e.handler.ZWaveSerialHandler] | Stopped ZWave serial handler
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:serial_zstick:166c1909e7a’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [e.handler.ZWaveSerialHandler] | Connecting to serial port ‘/dev/ttyAMA0’
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node22’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node38’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node35’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node50’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node15’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [e.handler.ZWaveSerialHandler] | Serial port is initialized
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node13’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node36’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node40’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [nal.protocol.ZWaveController] | Starting ZWave controller
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node52’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [nal.protocol.ZWaveController] | ZWave timeout is set to 5000ms. Soft reset is false.
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node21’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node19’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node44’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node51’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node12’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node49’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [ocol.ZWaveTransactionManager] | NODE 53: Not initialized (ie node unknown), ignoring message.
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node53’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node17’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node8’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node7’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node20’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [ocol.ZWaveTransactionManager] | NODE 53: Not initialized (ie node unknown), ignoring message.
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node3’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node16’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node18’ changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
  • Dec 26 10:46:37 homemanager : [home.event.ThingUpdatedEvent] | Thing ‘zwave:serial_zstick:166c1909e7a’ has been updated.
  • Dec 26 10:46:37 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_OOFBytesReceived changed from 12 to 0
  • Dec 26 10:46:37 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9517 to 9518
  • Dec 26 10:46:37 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9518 to 9519
  • Dec 26 10:46:39 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9519 to 9520
  • Dec 26 10:46:39 homemanager : [ocol.ZWaveTransactionManager] | NODE 53: Not initialized (ie node unknown), ignoring message.
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9520 to 9521
  • Dec 26 10:46:40 homemanager : [ocol.ZWaveTransactionManager] | NODE 53: Not initialized (ie node unknown), ignoring message.
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3339 to 3340
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9521 to 9522
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3340 to 3341
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9522 to 9523
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3341 to 3342
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9523 to 9524
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3342 to 3343
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9524 to 9525
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3343 to 3344
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9525 to 9526
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3344 to 3345
  • Dec 26 10:46:40 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9526 to 9527
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9527 to 9528
  • Dec 26 10:46:41 homemanager : [ocol.ZWaveTransactionManager] | NODE 53: Not initialized (ie node unknown), ignoring message.
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesCancelled changed from 7 to 8
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3345 to 3346
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9528 to 9529
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3346 to 3347
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9529 to 9530
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3347 to 3348
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9530 to 9531
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3348 to 3349
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9531 to 9532
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3349 to 3350
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9532 to 9533
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3350 to 3351
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9533 to 9534
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3351 to 3352
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9534 to 9535
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3352 to 3353
  • Dec 26 10:46:41 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9535 to 9536
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9536 to 9537
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3353 to 3354
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9537 to 9538
  • Dec 26 10:46:42 homemanager : [ocol.ZWaveTransactionManager] | NODE 53: Not initialized (ie node unknown), ignoring message.
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3354 to 3355
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9538 to 9539
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3355 to 3356
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9539 to 9540
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3356 to 3357
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9540 to 9541
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:serial_zstick:166c1909e7a’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node50’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node22’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node38’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node15’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node35’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node36’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node40’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node19’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node13’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node49’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node52’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node21’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node51’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node44’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node53’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node20’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node12’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node17’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node3’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node7’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node8’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node18’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [.ThingStatusInfoChangedEvent] | ‘zwave:device:166c1909e7a:node16’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
  • Dec 26 10:46:42 homemanager : [home.event.ThingUpdatedEvent] | Thing ‘zwave:serial_zstick:166c1909e7a’ has been updated.
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3357 to 3358
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9541 to 9542
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3358 to 3359
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9542 to 9543
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3359 to 3360
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9543 to 9544
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3360 to 3361
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_StartFrames changed from 9544 to 9545
  • Dec 26 10:46:42 homemanager : [.event.ItemStateChangedEvent] | ControllerZWave_FramesAcknowledged changed from 3361 to 3362

Any thoughts?

2

It’s a bit hard to read your log (please use the </> button, or proper code fences to format logs) but I’m not sure why you think the device did a soft reset? It looks to me like the bridge restarted for some reason.

3

Strange page.
It restarted for some reason, after restart I loose all the updates from Z-wave devices. And my rules therefore go silent.

Log from Text editor: https://pastebin.com/dpcGrK6u

I also enabled debug if this happens will post more. but this behaviour happened the last days. And when it happens also my Influx (persisten) stops getting metrics

4

What is “it”? Not the controller? It looks like the binding restarted - at least, this log shows the binding starting. There’s no sign of it resetting, and from this log, there is very little to go on at all as there are only 3 lines of information from the controller.

Sorry - I’m not sure what to suggest - maybe a serial port issue or something like that…

5

Sorry Chris, My Greek-english mixup stroke again.

The Z-Wave Module for some reason did restart, by itself. After the restart it seems like the polling didn’t continue. Though I could control z-wave devices.
I was not receiving the changes on their states on my panel. Because whenever this “self restart” happens on this z-wave controller, I loose updates like: Motion, Temperature, Door Contacts. I need to recover it by:

  1. Restart Binding or
  2. Restart Z-Wave

Is there some kind of bug that might be causing it?

For further debugging I enabled debug log, in order to gather more about this issue.

(I wish I wrote it in an understandable way)

6

Did you save any Item or Thing files, reconfigure anything, etc.? Also, what hardware do you have OH running on?

7

Good Day,

I forgot to add these info:

Running Openhabian with:

  • Z.wave.me Razberry2 module (HAT)
  • Disabled Bluetooth as per hat requirements
  • Latest firmware running on Razberry2 (from the beggining of my setup
  • uFL Connector modification and External Antenna

That moment I have had that node 53 added (10 minutes before the “issues”) and linked the items to Openhab.

8

Ok, I guess the controller can crash or reset for some reason. In the log though, this is not evident - the log shows that the serial port was reinitialised and the binding restarted.

From looking at the log, it looks like there were no messages being received from the controller.

Well, I don’t know what “it” is really so it’s hard to answer. From the log you showed, it looks like the controller is not sending data - either a serial port issue, or a controller issue, or a binding bug that has stopped it receiving data. I think it’s unlikely to be a binding bug as I don’t think I’ve seen this before, but without understanding a bit more of what happened, it’s hard to really say.

No problem at all :slight_smile:

9

I had again my motion sensors sending temperature updates but not motion… I tried to heal them… nothing (manual wake)

Performing a NIF fixed the issue. Is this something to consider Device or Binding issue?

10

I don’t know why sending a NIF would make any difference, but really without seeing logs it’s hard (ie impossible!) to know what is happening. I can guess, but, well, it would just be guessing :slight_smile:

11

I am trying to export the logs! :slight_smile: and send them.

I do have debug log too but they are huge.

I have a lot of triggers of the device by moving hand in front of it (before healing, after healing and after NIF) magically NIF fixed this. :\ I will post soon the results

12

log.txt (996.7 KB)

here is the log

NODE 44 is the one of the nodes I have had the issue with, Heal didn’t fix, NIF did fix. :\

Also this is strange at device config. This parameter always becomes blank:

52
Screen Shot 2018-12-28 at 15.40.52.png1864×366 12.3 KB

13

Please note that these are very different things - a heal is only related to the network mesh updates and routing. It isn’t a reconfiguration or a “fix for everything”…

In your logs, it looks like you have changed the format which has broken the log display software. Your log no longer logs milliseconds - please can you take a look at fixing that? Also, you changed the delimiter from - to | which also breaks the processor - any chance you can change that too as it now doesn’t display some entries properly and I can’t change the log software or it will incorrectly find other occurrences?

I don’t think it’s related. It’s probably a database issue - have you had a look at the definition of this parameter?

14

Is it possible that can you update your log format, and then provide a new log so I can look over that?

15

What part of the log is confusing. asking in order to make it readable as much as possible

16

As above - if you change the | delimiter to - at least for the one before the NODE then it will work. Also please restore the milliseconds rather than just making it 000.

17

2018-12-28 14:22:35.966 DEBUG O.B.Z: [ion.ZWaveNodeInitStageAdvancer] - NODE 44: Node advancer - advancing to VERSION

This format better?

18

I am sending you the log by mail. It is 1.7Mbyte

19

Yep - looks like it should be fine - thanks.

20

Ok, that’s fine. Thanks.