Recent experience > 0.45 - dumps / zwave issues

[collse]

Hi,

not sure where else to put this but I was wondering if anyone else is seeing odd behaviour recently?

My issues are that my zwave seems to stop responding, devices no longer reporting or automations based on zwave not executing /happening … no idea where this is coming from …

I run on a NUC, spinning disk with Ubuntu 16.04.3 LTS, latest builds and an Aeon Labs Zstick Gen5 devices I have connected are:

Aeotec ZW075 Smart Switch Gen5 (Z-Wave) (3)
Aeotec ZW090 Z-Stick Gen5 (Z-Wave) (1)
Aeotec ZW100 MultiSensor 6 (Z-Wave) (3)
FIBARO System FGMS001 Motion Sensor (Z-Wave) (2)
FIBARO System FGMS001-ZW5 Motion Sensor (Z-Wave) (3)
KAIPULEK Wireless Z-Wave Plus Door / Window Sensor (1)
Neo CoolCam Door/Window Sensor (Z-Wave) (1)
Neo CoolCam EU Wall Switch (Z-Wave) (2)

interestingly I cannot track the issue down to a single device not working because while one isnt working another seems to work fine - so chasing tails here …

I have already considered temperature issues on the Zstick and used an usb extension which however didnt change the behaviour/faults I was seeing.

I cannot use the healing functionality as it breaks zwave completely when I do it … in most cases only a complete shutdown/power down seems to help to cure it for a time - I tried restarting hass via cron every 24hrs to reduce this but this doesnt seem to work either … In addition to that hass seems to dump every now and then which makes this even more difficult to track as there is nothing in the logs …

question: obviously, how to track down this behaviour and is anyone else seeing similar things?

cheers
collse

[Tinkerer (aka DubhAd on GitHub)]

So… I’ve had one Z-Wave mesh hang so far, and that was cured by a heal. If you can’t run a heal without it breaking things completely then:

• You’ve got a faulty Z-Stick
• The mesh is only borderline

If you look at the zwave entities, what do the numbers look like? My controller has zero for sentFailed, and retries is also zero for all my devices.

Edit: Can’t splel

[collse]

@Tinkerer

So… I’ve had one Z-Wave mesh hang so far, and that was cured by a heal. If you can’t run a heal without it breaking things completely then:

• You’ve got a faulty Z-Stick
• The mesh is only borderline

If you look at the zwave entities, what do the numbers look like? My controller has zero for sentFailed, and retries is also zero for all my devices.

Edit: Can’t splel

the stick itself doesnt have any failures but the devices report failed sents … from 0 - 8 with an hass /server uptime of a bit more than 70 minutes … but it looks like zwave isnt fully up yet as some are still (cacheload) …

[Tinkerer (aka DubhAd on GitHub)]

I’ve got mostly zeros for sentFailed on powered nodes, except for one that has a one. The battery devices have single digits values there

[collse]

still waiting on a new stick and usb cable to potentially improve the situation but it still dumps …

[email protected]: Unit entered failed state.
[email protected]: Failed with result ‘core-dump’.

last messages on OZW_Log.txt

$ tac /home/homeassistant/.homeassistant/OZW_Log.txt |more
2017-08-28 12:14:14.621 Detail, Node037, Queuing (Send) MultiChannel Encapsulated (instance=2): SwitchBinaryCmd_Get (Node=37): 0x01, 0x0d, 0x00, 0x13, 0x25, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25,
0xd6, 0x7b
2017-08-28 12:14:14.621 Detail, Node037, Queuing (Send) MultiChannel Encapsulated (instance=2): SwitchBinaryCmd_Set (Node=37): 0x01, 0x0e, 0x00, 0x13, 0x25, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x01, 0x00,
0x25, 0xd5, 0x79
2017-08-28 12:14:14.621 Info, Node037, SwitchBinary::Set - Setting node 37 to Off
2017-08-28 12:14:14.621 Info, Node037, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch Rear - 0 - 2 - False
2017-08-28 12:14:14.620 Detail, Node037, Queuing (Send) MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Get (Node=37): 0x01, 0x0d, 0x00, 0x13, 0x25, 0x06, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x02, 0x25,
0xd4, 0x7a
2017-08-28 12:14:14.620 Detail, Node037, Queuing (Send) MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Set (Node=37): 0x01, 0x0e, 0x00, 0x13, 0x25, 0x07, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x01, 0x00,
0x25, 0xd3, 0x7c
2017-08-28 12:14:14.620 Info, Node037, SwitchBinary::Set - Setting node 37 to Off
2017-08-28 12:14:14.620 Info, Node037, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch Front - 0 - 3 - False
2017-08-28 12:14:14.494 Detail, Node019, Notification: ValueChanged
2017-08-28 12:14:14.494 Detail, Node019, Changes to this value are not verified
2017-08-28 12:14:14.494 Detail, Node019, Refreshed Value: old value=52, new value=53, type=decimal
2017-08-28 12:14:14.494 Info, Node019, Received SensorMultiLevel report from node 19, instance 1, Luminance: value=53lux
2017-08-28 12:14:14.494 Detail,
2017-08-28 12:14:14.494 Detail, Node019, Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x13, 0x06, 0x31, 0x05, 0x03, 0x0a, 0x00, 0x35, 0xea
2017-08-28 12:14:13.745 Detail, Node028, Notification: ValueChanged
2017-08-28 12:14:13.745 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-08-28 12:14:13.745 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-08-28 12:14:13.739 Detail, Node028, Notification: ValueChanged
2017-08-28 12:14:13.739 Detail, Node028, Changes to this value are not verified
2017-08-28 12:14:13.739 Detail, Node028, Refreshed Value: old value=0.647, new value=0.578, type=decimal
2017-08-28 12:14:13.739 Info, Node028, Received Meter report from node 28: Power=0.578W
2017-08-28 12:14:13.739 Detail, Node028, Changes to this value are not verified
2017-08-28 12:14:13.739 Detail, Node028, Refreshed Value: old value=false, new value=false, type=bool
2017-08-28 12:14:13.739 Detail,
2017-08-28 12:14:13.739 Detail, Node028, Decrypted Packet: 0x00, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x02, 0x42, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2017-08-28 12:14:13.739 Info, Raw: 0x98, 0x81, 0xb2, 0xb8, 0x43, 0x3a, 0x72, 0x55, 0xc4, 0x24, 0xa3, 0x0e, 0x54, 0x5d, 0x7a, 0xc4, 0xc2, 0xa0, 0x24, 0x45, 0x64, 0xe4, 0xcb, 0x99, 0xc2, 0x3f, 0xbc, 0x33, 0
x4d, 0xb6, 0x2c, 0xa4, 0xd6, 0x44, 0x18
2017-08-28 12:14:13.739 Detail, Node028, Received: 0x01, 0x28, 0x00, 0x04, 0x00, 0x1c, 0x22, 0x98, 0x81, 0xb2, 0xb8, 0x43, 0x3a, 0x72, 0x55, 0xc4, 0x24, 0xa3, 0x0e, 0x54, 0x5d, 0x7a, 0xc4, 0xc2, 0xa0, 0
x24, 0x45, 0x64, 0xe4, 0xcb, 0x99, 0xc2, 0x3f, 0xbc, 0x33, 0x4d, 0xb6, 0x2c, 0xa4, 0xd6, 0x44, 0x18
2017-08-28 12:14:13.660 Detail, ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2017-08-28 12:14:13.660 Detail, Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x0d, 0xe7
2017-08-28 12:14:13.530 Detail, ZW_SEND_DATA delivered to Z-Wave stack
2017-08-28 12:14:13.530 Detail, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-08-28 12:14:13.520 Info, Node028, Sending (Command) message (Callback ID=0x01, Expected Reply=0x00) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x1c, 0x0a, 0x98, 0x80, 0x3f, 0x03, 0xaa, 0x86, 0x64, 0xf5
, 0x5f, 0x4f, 0x05, 0x01, 0x66:
2017-08-28 12:14:13.520 Info, NONCES: 0x42, 0x10, 0xa3, 0x32, 0x7e, 0x42, 0xc0, 0x2e
2017-08-28 12:14:13.520 Info, NONCES: 0xcd, 0x20, 0xed, 0xf9, 0xd8, 0x51, 0x46, 0xac
2017-08-28 12:14:13.520 Info, NONCES: 0xeb, 0xde, 0x89, 0x12, 0x3b, 0x01, 0x78, 0xe0
2017-08-28 12:14:13.520 Info, NONCES: 0xb0, 0xfc, 0x43, 0x7c, 0x47, 0x60, 0x4a, 0xd9
2017-08-28 12:14:13.520 Info, NONCES: 0xd4, 0xf0, 0xca, 0xf6, 0xf1, 0x6e, 0x9e, 0x8d
2017-08-28 12:14:13.520 Info, NONCES: 0xa6, 0xcc, 0xa8, 0x0c, 0x2c, 0xb8, 0x63, 0x4c
2017-08-28 12:14:13.520 Info, NONCES: 0xce, 0x91, 0x8a, 0x94, 0xaf, 0x26, 0x5c, 0x77
2017-08-28 12:14:13.520 Info, NONCES: 0x3f, 0x03, 0xaa, 0x86, 0x64, 0xf5, 0x5f, 0x4f
2017-08-28 12:14:13.520 Info, Node028, Received SecurityCmd_NonceGet from node 28
2017-08-28 12:14:13.520 Detail, Node028, Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x1c, 0x02, 0x98, 0x40, 0x35
2017-08-28 12:14:10.743 Detail, Node028, Notification: ValueChanged
2017-08-28 12:14:10.742 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-08-28 12:14:10.742 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-08-28 12:14:10.739 Detail, Node028, Notification: ValueChanged
2017-08-28 12:14:10.739 Detail, Node028, Changes to this value are not verified
2017-08-28 12:14:10.739 Detail, Node028, Refreshed Value: old value=0.612, new value=0.647, type=decimal
2017-08-28 12:14:10.739 Info, Node028, Received Meter report from node 28: Power=0.647W
2017-08-28 12:14:10.739 Detail, Node028, Changes to this value are not verified
2017-08-28 12:14:10.739 Detail, Node028, Refreshed Value: old value=false, new value=false, type=bool
2017-08-28 12:14:10.739 Detail,
2017-08-28 12:14:10.739 Detail, Node028, Decrypted Packet: 0x00, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x02, 0x87, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2017-08-28 12:14:10.739 Info, Raw: 0x98, 0x81, 0x54, 0x12, 0x6b, 0x33, 0xc5, 0x84, 0x83, 0x70, 0xfe, 0x1e, 0xc2, 0xe9, 0xd8, 0x17, 0x48, 0xf8, 0x07, 0x4d, 0x54, 0x9c, 0x76, 0x71, 0x08, 0x42, 0x5d, 0x88, 0
xdf, 0xc1, 0x3c, 0x1d, 0x1d, 0x3c, 0xe8
2017-08-28 12:14:10.739 Detail, Node028, Received: 0x01, 0x28, 0x00, 0x04, 0x00, 0x1c, 0x22, 0x98, 0x81, 0x54, 0x12, 0x6b, 0x33, 0xc5, 0x84, 0x83, 0x70, 0xfe, 0x1e, 0xc2, 0xe9, 0xd8, 0x17, 0x48, 0xf8, 0
x07, 0x4d, 0x54, 0x9c, 0x76, 0x71, 0x08, 0x42, 0x5d, 0x88, 0xdf, 0xc1, 0x3c, 0x1d, 0x1d, 0x3c, 0xe8
2017-08-28 12:14:10.660 Detail, ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2017-08-28 12:14:10.660 Detail, Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x0d, 0xe7
2017-08-28 12:14:10.530 Detail, ZW_SEND_DATA delivered to Z-Wave stack
2017-08-28 12:14:10.530 Detail, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-08-28 12:14:10.520 Info, Node028, Sending (Command) message (Callback ID=0x01, Expected Reply=0x00) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x1c, 0x0a, 0x98, 0x80, 0x42, 0x10, 0xa3, 0x32, 0x7e, 0x42
, 0xc0, 0x2e, 0x05, 0x01, 0xe6:
2017-08-28 12:14:10.520 Info, NONCES: 0x42, 0x10, 0xa3, 0x32, 0x7e, 0x42, 0xc0, 0x2e
2017-08-28 12:14:10.520 Info, NONCES: 0xcd, 0x20, 0xed, 0xf9, 0xd8, 0x51, 0x46, 0xac
2017-08-28 12:14:10.520 Info, NONCES: 0xeb, 0xde, 0x89, 0x12, 0x3b, 0x01, 0x78, 0xe0
2017-08-28 12:14:10.520 Info, NONCES: 0xb0, 0xfc, 0x43, 0x7c, 0x47, 0x60, 0x4a, 0xd9
2017-08-28 12:14:10.520 Info, NONCES: 0xd4, 0xf0, 0xca, 0xf6, 0xf1, 0x6e, 0x9e, 0x8d
2017-08-28 12:14:10.520 Info, NONCES: 0xa6, 0xcc, 0xa8, 0x0c, 0x2c, 0xb8, 0x63, 0x4c
2017-08-28 12:14:10.520 Info, NONCES: 0xce, 0x91, 0x8a, 0x94, 0xaf, 0x26, 0x5c, 0x77
2017-08-28 12:14:10.520 Info, NONCES: 0xa8, 0x9c, 0x41, 0x5d, 0xb6, 0xcc, 0x4b, 0x1c
2017-08-28 12:14:10.520 Info, Node028, Received SecurityCmd_NonceGet from node 28
2017-08-28 12:14:10.520 Detail, Node028, Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x1c, 0x02, 0x98, 0x40, 0x35
2017-08-28 12:14:07.754 Detail, Node028, Notification: ValueChanged
2017-08-28 12:14:07.753 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-08-28 12:14:07.753 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-08-28 12:14:07.749 Detail, Node028, Notification: ValueChanged
2017-08-28 12:14:07.749 Detail, Node028, Changes to this value are not verified
2017-08-28 12:14:07.749 Detail, Node028, Refreshed Value: old value=0.651, new value=0.612, type=decimal
2017-08-28 12:14:07.749 Info, Node028, Received Meter report from node 28: Power=0.612W
2017-08-28 12:14:07.749 Detail, Node028, Changes to this value are not verified
2017-08-28 12:14:07.749 Detail, Node028, Refreshed Value: old value=false, new value=false, type=bool
2017-08-28 12:14:07.749 Detail,
2017-08-28 12:14:07.749 Detail, Node028, Decrypted Packet: 0x00, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x02, 0x64, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
2017-08-28 12:14:07.749 Info, Raw: 0x98, 0x81, 0x38, 0xed, 0x98, 0xd5, 0x02, 0xef, 0x50, 0x45, 0x48, 0x3b, 0xb3, 0x9b, 0x12, 0x8d, 0xf5, 0x5e, 0xe2, 0x9f, 0xd2, 0xa5, 0xf9, 0x95, 0x26, 0xcd, 0x5b, 0x09, 0
xae, 0x91, 0xca, 0x50, 0x8b, 0xee, 0xe4
2017-08-28 12:14:07.749 Detail, Node028, Received: 0x01, 0x28, 0x00, 0x04, 0x00, 0x1c, 0x22, 0x98, 0x81, 0x38, 0xed, 0x98, 0xd5, 0x02, 0xef, 0x50, 0x45, 0x48, 0x3b, 0xb3, 0x9b, 0x12, 0x8d, 0xf5, 0x5e, 0
xe2, 0x9f, 0xd2, 0xa5, 0xf9, 0x95, 0x26, 0xcd, 0x5b, 0x09, 0xae, 0x91, 0xca, 0x50, 0x8b, 0xee, 0xe4
2017-08-28 12:14:07.663 Detail, ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2017-08-28 12:14:07.663 Detail, Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x0d, 0xe7
2017-08-28 12:14:07.530 Detail, ZW_SEND_DATA delivered to Z-Wave stack
2017-08-28 12:14:07.530 Detail, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-08-28 12:14:07.520 Info, Node028, Sending (Command) message (Callback ID=0x01, Expected Reply=0x00) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x1c, 0x0a, 0x98, 0x80, 0xcd, 0x20, 0xed, 0xf9, 0xd8, 0x51
, 0x46, 0xac, 0x05, 0x01, 0x6d:
2017-08-28 12:14:07.520 Info, NONCES: 0x57, 0x20, 0x1b, 0x8c, 0x7d, 0x22, 0x00, 0xa3
2017-08-28 12:14:07.520 Info, NONCES: 0xcd, 0x20, 0xed, 0xf9, 0xd8, 0x51, 0x46, 0xac
2017-08-28 12:14:07.520 Info, NONCES: 0xeb, 0xde, 0x89, 0x12, 0x3b, 0x01, 0x78, 0xe0
2017-08-28 12:14:07.520 Info, NONCES: 0xb0, 0xfc, 0x43, 0x7c, 0x47, 0x60, 0x4a, 0xd9
2017-08-28 12:14:07.520 Info, NONCES: 0xd4, 0xf0, 0xca, 0xf6, 0xf1, 0x6e, 0x9e, 0x8d
2017-08-28 12:14:07.520 Info, NONCES: 0xa6, 0xcc, 0xa8, 0x0c, 0x2c, 0xb8, 0x63, 0x4c
2017-08-28 12:14:07.520 Info, NONCES: 0xce, 0x91, 0x8a, 0x94, 0xaf, 0x26, 0x5c, 0x77
2017-08-28 12:14:07.520 Info, NONCES: 0xa8, 0x9c, 0x41, 0x5d, 0xb6, 0xcc, 0x4b, 0x1c
2017-08-28 12:14:07.520 Info, Node028, Received SecurityCmd_NonceGet from node 28
2017-08-28 12:14:07.520 Detail, Node028, Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x1c, 0x02, 0x98, 0x40, 0x35
2017-08-28 12:13:58.933 Detail, Node018, Notification: ValueChanged
2017-08-28 12:13:58.933 Detail, Node018, Changes to this value are not verified
2017-08-28 12:13:58.933 Detail, Node018, Refreshed Value: old value=26.3, new value=26.3, type=decimal
2017-08-28 12:13:58.933 Info, Node018, Received SensorMultiLevel report from node 18, instance 1, Temperature: value=26.3C

last entries in home-assistant.log

tac /home/homeassistant/.homeassistant/home-assistant.log |more
2017-08-28 12:14:18 INFO (MainThread) [homeassistant.components.http] Serving /api/camera_proxy/camera.baby_cam to 192.168.1.11 (auth: False)
2017-08-28 12:14:18 INFO (Thread-14) [homeassistant.components.device_tracker.nmap_tracker] nmap scan successful
2017-08-28 12:14:18 INFO (Thread-14) [homeassistant.components.device_tracker.nmap_tracker] Scanning…
2017-08-28 12:14:17 INFO (Thread-2) [homeassistant.components.device_tracker.ddwrt] Checking ARP
2017-08-28 12:14:16 INFO (Thread-15) [plexapi] GET http://192.168.1.250:32400/status/sessions?X-Plex-Token=TOKEN
2017-08-28 12:14:16 INFO (Thread-15) [plexapi] GET http://192.168.1.250:32400/clients?X-Plex-Token=TOKEN
2017-08-28 12:14:15 DEBUG (Thread-24) [pychromecast.socket_client] Failed to connect, retrying in 5.0s
2017-08-28 12:14:14 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139901994500896: Sending {‘type’: ‘event’, ‘event’: {‘origin’: ‘LOCAL’, ‘data’: {‘old_state’: <state automation.light__li
vingroom__lights__off=on; last_triggered=2017-08-28T12:13:15.197134+01:00, icon=mdi:arrow-right-drop-circle, friendly_name=Light - Livingroom - Lights - Off @ 2017-08-28T04:00:20.512689+01:00>, ‘new_state
‘: <state automation.light__livingroom__lights__off=on; last_triggered=2017-08-28T12:14:14.767578+01:00, icon=mdi:arrow-right-drop-circle, friendly_name=Light - Livingroom - Lights - Off @ 2017-08-28T04:0
0:20.512689+01:00>, ‘entity_id’: ‘automation.light__livingroom__lights__off’}, ‘event_type’: ‘state_changed’, ‘time_fired’: datetime.datetime(2017, 8, 28, 11, 14, 14, 767695, tzinfo=)}, ‘id’: 2}
2017-08-28 12:14:14 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139900443644872: Sending {‘type’: ‘event’, ‘event’: {‘origin’: ‘LOCAL’, ‘data’: {‘old_state’: <state automation.light__li
vingroom__lights__off=on; last_triggered=2017-08-28T12:13:15.197134+01:00, icon=mdi:arrow-right-drop-circle, friendly_name=Light - Livingroom - Lights - Off @ 2017-08-28T04:00:20.512689+01:00>, ‘new_state
‘: <state automation.light__livingroom__lights__off=on; last_triggered=2017-08-28T12:14:14.767578+01:00, icon=mdi:arrow-right-drop-circle, friendly_name=Light - Livingroom - Lights - Off @ 2017-08-28T04:0
0:20.512689+01:00>, ‘entity_id’: ‘automation.light__livingroom__lights__off’}, ‘event_type’: ‘state_changed’, ‘time_fired’: datetime.datetime(2017, 8, 28, 11, 14, 14, 767695, tzinfo=)}, ‘id’: 2}
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state automation.light__livingroom__lights__off=on; last_triggered=2017-08-28T12:14:14.767578+01
:00, icon=mdi:arrow-right-drop-circle, friendly_name=Light - Livingroom - Lights - Off @ 2017-08-28T04:00:20.512689+01:00>, old_state=<state automation.light__livingroom__lights__off=on; last_triggered=20
17-08-28T12:13:15.197134+01:00, icon=mdi:arrow-right-drop-circle, friendly_name=Light - Livingroom - Lights - Off @ 2017-08-28T04:00:20.512689+01:00>, entity_id=automation.light__livingroom__lights__off>
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=139902139377424-255>
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=139902139377424-257>
2017-08-28 12:14:14 DEBUG (Thread-20) [phue] GET /api/A7ob0zolUiMr1jRdEzhRENiE6T4WBvx0Tg8DaFuC None
2017-08-28 12:14:14 DEBUG (Thread-19) [phue] [[{‘success’: {’/lights/22/state/alert’: ‘none’}}, {‘success’: {’/lights/22/state/on’: False}}]]
2017-08-28 12:14:14 DEBUG (Thread-19) [phue] PUT /api/A7ob0zolUiMr1jRdEzhRENiE6T4WBvx0Tg8DaFuC/lights/22/state {‘on’: False, ‘alert’: ‘none’}
2017-08-28 12:14:14 DEBUG (Thread-19) [phue] {‘on’: False, ‘alert’: ‘none’}
2017-08-28 12:14:14 DEBUG (Thread-4) [phue] [[{‘success’: {‘/lights/10/state/alert’: ‘none’}}, {‘success’: {‘/lights/10/state/on’: False}}]]
2017-08-28 12:14:14 DEBUG (Thread-4) [phue] PUT /api/A7ob0zolUiMr1jRdEzhRENiE6T4WBvx0Tg8DaFuC/lights/10/state {‘on’: False, ‘alert’: ‘none’}
2017-08-28 12:14:14 DEBUG (Thread-4) [phue] {‘on’: False, ‘alert’: ‘none’}
2017-08-28 12:14:14 DEBUG (Thread-11) [phue] [[{‘success’: {‘/lights/11/state/alert’: ‘none’}}, {‘success’: {‘/lights/11/state/on’: False}}]]
2017-08-28 12:14:14 DEBUG (Thread-11) [phue] PUT /api/A7ob0zolUiMr1jRdEzhRENiE6T4WBvx0Tg8DaFuC/lights/11/state {‘on’: False, ‘alert’: ‘none’}
2017-08-28 12:14:14 DEBUG (Thread-11) [phue] {‘on’: False, ‘alert’: ‘none’}
2017-08-28 12:14:14 DEBUG (Thread-9) [phue] [[{‘success’: {‘/lights/12/state/alert’: ‘none’}}, {‘success’: {‘/lights/12/state/on’: False}}]]
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=139902139377424-256>
2017-08-28 12:14:14 DEBUG (Thread-9) [phue] PUT /api/A7ob0zolUiMr1jRdEzhRENiE6T4WBvx0Tg8DaFuC/lights/12/state {‘on’: False, ‘alert’: ‘none’}
2017-08-28 12:14:14 DEBUG (Thread-9) [phue] {‘on’: False, ‘alert’: ‘none’}
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service_call_id=139902139377424-257, service_data=entity_id=[‘light.corner_lamp_1’, ‘light.cor
ner_lamp_2’, ‘light.corner_lamp_3’, ‘light.livingroom_floor_lamp’], service=turn_off>
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=switch, service_call_id=139902139377424-256, service_data=entity_id=[‘switch.livingroom_switch_front’
, ‘switch.livingroom_switch_rear’], service=turn_off>
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=homeassistant, service_call_id=139902139377424-255, service_data=entity_id=['switch.livingroom_switch
front’, ‘switch.livingroom_switch_rear’, ‘light.corner_lamp_1’, ‘light.corner_lamp_2’, ‘light.corner_lamp_3’, ‘light.livingroom_floor_lamp’], service=turn_off>
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.helpers.script] Script Light - Livingroom - Lights - Off: Executing step call service
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.helpers.script] Script Light - Livingroom - Lights - Off: Running script
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event logbook_entry[L]: message=has been triggered, domain=automation, entity_id=automation.light__livingroom__lights__off, name=Li
ght - Livingroom - Lights - Off>
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.components.automation] Executing Light - Livingroom - Lights - Off
2017-08-28 12:14:14 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139901994500896: Sending {‘type’: ‘event’, ‘event’: {‘origin’: ‘LOCAL’, ‘data’: {‘old_state’: <state sensor.livingroom_se
nsor_luminance=52.0; unit_of_measurement=lux, icon=mdi:brightness-7, new_entity_id=sensor.livingroom_sensor_luminance, value_instance=1, old_entity_id=sensor.livingroom_sensor_luminance_19_3, node_id=19,
value_index=3, friendly_name=Livingroom Light Level @ 2017-08-28T12:13:15.037174+01:00>, ‘new_state’: <state sensor.livingroom_sensor_luminance=53.0; unit_of_measurement=lux, icon=mdi:brightness-7, new_en
tity_id=sensor.livingroom_sensor_luminance, value_instance=1, old_entity_id=sensor.livingroom_sensor_luminance_19_3, node_id=19, value_index=3, friendly_name=Livingroom Light Level @ 2017-08-28T12:14:14.6
02043+01:00>, ‘entity_id’: ‘sensor.livingroom_sensor_luminance’}, ‘event_type’: ‘state_changed’, ‘time_fired’: datetime.datetime(2017, 8, 28, 11, 14, 14, 602070, tzinfo=)}, ‘id’: 2}
2017-08-28 12:14:14 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139901994500896: Sending {‘type’: ‘event’, ‘event’: {‘origin’: ‘LOCAL’, ‘data’: {‘old_state’: <state zwave.livingroom_sen
sor=CacheLoad; old_entity_id=zwave.livingroom_sensor_19, is_ready=False, receivedCnt=677, friendly_name=Livingroom Sensor, retries=0, product_name=FGMS001 Motion Sensor, averageRequestRTT=0, capabilities=
{‘beaming’, ‘routing’}, manufacturer_name=FIBARO System, is_zwave_plus=False, receivedUnsolicited=677, sentTS=2017-08-28 04:00:36:756 , is_awake=True, sentCnt=1, receivedDups=15, query_stage=CacheLoad, is
info_received=True, sentFailed=1, averageResponseRTT=0, max_baud_rate=40000, battery_level=92, node_id=19, is_failed=False, wake_up_interval=7200, lastRequestRTT=0, node_name=Livingroom Sensor, lastRespo
nseRTT=0, receivedTS=2017-08-28 12:13:14:928 , new_entity_id=zwave.livingroom_sensor @ 2017-08-28T04:52:30.747697+01:00>, ‘new_state’: <state zwave.livingroom_sensor=CacheLoad; old_entity_id=zwave.livingr
oom_sensor_19, is_ready=False, receivedCnt=678, friendly_name=Livingroom Sensor, retries=0, product_name=FGMS001 Motion Sensor, averageRequestRTT=0, capabilities={‘beaming’, ‘routing’}, manufacturer_name=
FIBARO System, is_zwave_plus=False, receivedUnsolicited=678, sentTS=2017-08-28 04:00:36:756 , is_awake=True, sentCnt=1, receivedDups=15, query_stage=CacheLoad, is_info_received=True, sentFailed=1, average
ResponseRTT=0, max_baud_rate=40000, battery_level=92, node_id=19, is_failed=False, wake_up_interval=7200, lastRequestRTT=0, node_name=Livingroom Sensor, lastResponseRTT=0, receivedTS=2017-08-28 12:14:14:4
94 , new_entity_id=zwave.livingroom_sensor @ 2017-08-28T04:52:30.747697+01:00>, ‘entity_id’: ‘zwave.livingroom_sensor’}, ‘event_type’: ‘state_changed’, ‘time_fired’: datetime.datetime(2017, 8, 28, 11, 14,
14, 598857, tzinfo=)}, ‘id’: 2}
2017-08-28 12:14:14 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139900443644872: Sending {‘type’: ‘event’, ‘event’: {‘origin’: ‘LOCAL’, ‘data’: {‘old_state’: <state sensor.livingroom_se
nsor_luminance=52.0; unit_of_measurement=lux, icon=mdi:brightness-7, new_entity_id=sensor.livingroom_sensor_luminance, value_instance=1, old_entity_id=sensor.livingroom_sensor_luminance_19_3, node_id=19,
value_index=3, friendly_name=Livingroom Light Level @ 2017-08-28T12:13:15.037174+01:00>, ‘new_state’: <state sensor.livingroom_sensor_luminance=53.0; unit_of_measurement=lux, icon=mdi:brightness-7, new_en
tity_id=sensor.livingroom_sensor_luminance, value_instance=1, old_entity_id=sensor.livingroom_sensor_luminance_19_3, node_id=19, value_index=3, friendly_name=Livingroom Light Level @ 2017-08-28T12:14:14.6
02043+01:00>, ‘entity_id’: ‘sensor.livingroom_sensor_luminance’}, ‘event_type’: ‘state_changed’, ‘time_fired’: datetime.datetime(2017, 8, 28, 11, 14, 14, 602070, tzinfo=)}, ‘id’: 2}
2017-08-28 12:14:14 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139900443644872: Sending {‘type’: ‘event’, ‘event’: {‘origin’: ‘LOCAL’, ‘data’: {‘old_state’: <state zwave.livingroom_sen
sor=CacheLoad; old_entity_id=zwave.livingroom_sensor_19, is_ready=False, receivedCnt=677, friendly_name=Livingroom Sensor, retries=0, product_name=FGMS001 Motion Sensor, averageRequestRTT=0, capabilities=
{‘beaming’, ‘routing’}, manufacturer_name=FIBARO System, is_zwave_plus=False, receivedUnsolicited=677, sentTS=2017-08-28 04:00:36:756 , is_awake=True, sentCnt=1, receivedDups=15, query_stage=CacheLoad, is
info_received=True, sentFailed=1, averageResponseRTT=0, max_baud_rate=40000, battery_level=92, node_id=19, is_failed=False, wake_up_interval=7200, lastRequestRTT=0, node_name=Livingroom Sensor, lastRespo
nseRTT=0, receivedTS=2017-08-28 12:13:14:928 , new_entity_id=zwave.livingroom_sensor @ 2017-08-28T04:52:30.747697+01:00>, ‘new_state’: <state zwave.livingroom_sensor=CacheLoad; old_entity_id=zwave.livingr
oom_sensor_19, is_ready=False, receivedCnt=678, friendly_name=Livingroom Sensor, retries=0, product_name=FGMS001 Motion Sensor, averageRequestRTT=0, capabilities={‘beaming’, ‘routing’}, manufacturer_name=
FIBARO System, is_zwave_plus=False, receivedUnsolicited=678, sentTS=2017-08-28 04:00:36:756 , is_awake=True, sentCnt=1, receivedDups=15, query_stage=CacheLoad, is_info_received=True, sentFailed=1, average
ResponseRTT=0, max_baud_rate=40000, battery_level=92, node_id=19, is_failed=False, wake_up_interval=7200, lastRequestRTT=0, node_name=Livingroom Sensor, lastResponseRTT=0, receivedTS=2017-08-28 12:14:14:4
94 , new_entity_id=zwave.livingroom_sensor @ 2017-08-28T04:52:30.747697+01:00>, ‘entity_id’: ‘zwave.livingroom_sensor’}, ‘event_type’: ‘state_changed’, ‘time_fired’: datetime.datetime(2017, 8, 28, 11, 14,
14, 598857, tzinfo=)}, ‘id’: 2}
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state sensor.livingroom_sensor_luminance=53.0; unit_of_measurement=lux, icon=mdi:brightness-7, n
ew_entity_id=sensor.livingroom_sensor_luminance, value_instance=1, old_entity_id=sensor.livingroom_sensor_luminance_19_3, node_id=19, value_index=3, friendly_name=Livingroom Light Level @ 2017-08-28T12:14
:14.602043+01:00>, old_state=<state sensor.livingroom_sensor_luminance=52.0; unit_of_measurement=lux, icon=mdi:brightness-7, new_entity_id=sensor.livingroom_sensor_luminance, value_instance=1, old_entity
id=sensor.livingroom_sensor_luminance_19_3, node_id=19, value_index=3, friendly_name=Livingroom Light Level @ 2017-08-28T12:13:15.037174+01:00>, entity_id=sensor.livingroom_sensor_luminance>
2017-08-28 12:14:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state zwave.livingroom_sensor=CacheLoad; old_entity_id=zwave.livingroom_sensor_19, is_ready=Fals
e, receivedCnt=678, friendly_name=Livingroom Sensor, retries=0, product_name=FGMS001 Motion Sensor, averageRequestRTT=0, capabilities={‘beaming’, ‘routing’}, manufacturer_name=FIBARO System, is_zwave_plus
=False, receivedUnsolicited=678, sentTS=2017-08-28 04:00:36:756 , is_awake=True, sentCnt=1, receivedDups=15, query_stage=CacheLoad, is_info_received=True, sentFailed=1, averageResponseRTT=0, max_baud_rate
=40000, battery_level=92, node_id=19, is_failed=False, wake_up_interval=7200, lastRequestRTT=0, node_name=Livingroom Sensor, lastResponseRTT=0, receivedTS=2017-08-28 12:14:14:494 , new_entity_id=zwave.liv
ingroom_sensor @ 2017-08-28T04:52:30.747697+01:00>, old_state=<state zwave.livingroom_sensor=CacheLoad; old_entity_id=zwave.livingroom_sensor_19, is_ready=False, receivedCnt=677, friendly_name=Livingroom
Sensor, retries=0, product_name=FGMS001 Motion Sensor, averageRequestRTT=0, capabilities={‘beaming’, ‘routing’}, manufacturer_name=FIBARO System, is_zwave_plus=False, receivedUnsolicited=677, sentTS=2017-
08-28 04:00:36:756 , is_awake=True, sentCnt=1, receivedDups=15, query_stage=CacheLoad, is_info_received=True, sentFailed=1, averageResponseRTT=0, max_baud_rate=40000, battery_level=92, node_id=19, is_fail
ed=False, wake_up_interval=7200, lastRequestRTT=0, node_name=Livingroom Sensor, lastResponseRTT=0, receivedTS=2017-08-28 12:13:14:928 , new_entity_id=zwave.livingroom_sensor @ 2017-08-28T04:52:30.747697+0
1:00>, entity_id=zwave.livingroom_sensor>
2017-08-28 12:14:14 INFO (Thread-5) [homeassistant.components.sensor.command_line] Running command: ssl-cert-check -b -c /home/homeassistant/fullchain.pem | awk ‘{ print $NF }’
2017-08-28 12:14:14 DEBUG (Dummy-25) [libopenzwave] notif_callback : end
2017-08-28 12:14:14 DEBUG (Dummy-25) [openzwave] Z-Wave Notification ValueChanged : {‘homeId’: 4194397412, ‘nodeId’: 19, ‘notificationType’: ‘ValueChanged’, ‘valueId’: {‘value’: 53.0, ‘genre’: ‘User’, ‘no
deId’: 19, ‘type’: ‘Decimal’, ‘label’: ‘Luminance’, ‘readOnly’: True, ‘commandClass’: ‘COMMAND_CLASS_SENSOR_MULTILEVEL’, ‘instance’: 1, ‘index’: 3, ‘id’: 72057594361692210, ‘homeId’: 4194397412, ‘units’:
‘lux’}}
2017-08-28 12:14:14 DEBUG (Dummy-25) [openzwave] zwcallback args=[{‘homeId’: 4194397412, ‘nodeId’: 19, ‘notificationType’: ‘ValueChanged’, ‘valueId’: {‘value’: 53.0, ‘genre’: ‘User’, ‘nodeId’: 19, ‘type’:
‘Decimal’, ‘label’: ‘Luminance’, ‘readOnly’: True, ‘commandClass’: ‘COMMAND_CLASS_SENSOR_MULTILEVEL’, ‘instance’: 1, ‘index’: 3, ‘id’: 72057594361692210, ‘homeId’: 4194397412, ‘units’: ‘lux’}}]
2017-08-28 12:14:14 DEBUG (Dummy-25) [libopenzwave] notif_callback : call callback context
2017-08-28 12:14:14 DEBUG (Dummy-25) [libopenzwave] addValueId : Notification : {‘homeId’: 4194397412, ‘nodeId’: 19, ‘notificationType’: ‘ValueChanged’, ‘valueId’: {‘value’: 53.0, ‘genre’: ‘User’, 'nodeId
': 19, ‘type’: ‘Decimal’, ‘label’: ‘Luminance’, ‘readOnly’: True, ‘commandClass’: ‘COMMAND_CLASS_SENSOR_MULTILEVEL’, ‘instance’: 1, ‘index’: 3, ‘id’: 72057594361692210, ‘homeId’: 4194397412, ‘units’: 'lux
'}}
2017-08-28 12:14:14 DEBUG (Dummy-25) [libopenzwave] addValueId : GetCommandClassId : 49, GetType : 2
2017-08-28 12:14:14 DEBUG (Dummy-25) [libopenzwave] addValueId : ValueID : 72057594361692210
2017-08-28 12:14:14 DEBUG (Dummy-25) [libopenzwave] notif_callback : Notification type : 2, nodeId : 19
2017-08-28 12:14:14 DEBUG (Dummy-25) [libopenzwave] notif_callback : new notification
2017-08-28 12:14:13 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139901994500896: Sending {‘type’: ‘event’, ‘event’: {‘origin’: ‘LOCAL’, ‘data’: {‘old_state’: <state sensor.aeotec_zw075
smart_switch_gen5_current_4=0.07; unit_of_measurement=A, power_consumption=0.647, icon=mdi:mdi:power-socket, new_entity_id=sensor.aeotec_zw075_smart_switch_gen5_current, value_instance=1, hidden=True, old
entity_id=sensor.aeotec_zw075_smart_switch_gen5_current_28_20, node_id=28, value_index=20, friendly_name=Aeotec ZW075 Smart Switch Gen5 Current @ 2017-08-28T12:14:10.857208+01:00>, ‘new_state’: <state se
nsor.aeotec_zw075_smart_switch_gen5_current_4=0.07; unit_of_measurement=A, power_consumption=0.578, icon=mdi:mdi:power-socket, new_entity_id=sensor.aeotec_zw075_smart_switch_gen5_current, value_instance=1
, hidden=True, old_entity_id=sensor.aeotec_zw075_smart_switch_gen5_current_28_20, node_id=28, value_index=20, friendly_name=Aeotec ZW075 Smart Switch Gen5 Current @ 2017-08-28T12:14:13.861081+01:00>, ‘ent
ity_id’: ‘sensor.aeotec_zw075_smart_switch_gen5_current_4’}, ‘event_type’: ‘state_changed’, ‘time_fired’: datetime.datetime(2017, 8, 28, 11, 14, 13, 861095, tzinfo=)}, ‘id’: 2}
2017-08-28 12:14:13 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139901994500896: Sending {‘type’: ‘event’, ‘event’: {‘origin’: ‘LOCAL’, ‘data’: {‘old_state’: <state sensor.aeotec_zw075
smart_switch_gen5_voltage_4=251.41; unit_of_measurement=V, power_consumption=0.647, icon=mdi:mdi:power-socket, new_entity_id=sensor.aeotec_zw075_smart_switch_gen5_voltage, value_instance=1, hidden=True, o
ld_entity_id=sensor.aeotec_zw075_smart_switch_gen5_voltage_28_16, node_id=28, value_index=16, friendly_name=Aeotec ZW075 Smart Switch Gen5 Voltage @ 2017-08-28T12:14:10.855564+01:00>, ‘new_state’: <state
sensor.aeotec_zw075_smart_switch_gen5_voltage_4=251.41; unit_of_measurement=V, power_consumption=0.578, icon=mdi:mdi:power-socket, new_entity_id=sensor.aeotec_zw075_smart_switch_gen5_voltage, value_instan
ce=1, hidden=True, old_entity_id=sensor.aeotec_zw075_smart_switch_gen5_voltage_28_16, node_id=28, value_index=16, friendly_name=Aeotec ZW075 Smart Switch Gen5 Voltage @ 2017-08-28T12:14:13.859630+01:00>,
‘entity_id’: ‘sensor.aeotec_zw075_smart_switch_gen5_voltage_4’}, ‘event_type’: ‘state_changed’, ‘time_fired’: datetime.datetime(2017, 8, 28, 11, 14, 13, 859647, tzinfo=)}, ‘id’: 2}
2017-08-28 12:14:13 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139901994500896: Sending {‘type’: ‘event’, ‘event’: {‘origin’: ‘LOCAL’, ‘data’: {‘old_state’: <state sensor.aeotec_zw075
smart_switch_gen5_previous_reading_7=2130327.5; unit_of_measurement=W, power_consumption=0.647, icon=mdi:mdi:power-socket, new_entity_id=sensor.aeotec_zw075_smart_switch_gen5_previous_reading, value_insta
nce=1, hidden=True, old_entity_id=sensor.aeotec_zw075_smart_switch_gen5_previous_reading_28_9, node_id=28, value_index=9, friendly_name=Aeotec ZW075 Smart Switch Gen5 Previous Reading @ 2017-08-28T12:14:1
0.854148+01:00>, ‘new_state’: <state sensor.aeotec_zw075_smart_switch_gen5_previous_reading_7=2130327.5; unit_of_measurement=W, power_consumption=0.578, icon=mdi:mdi:power-socket, new_entity_id=sensor.aeo
tec_zw075_smart_switch_gen5_previous_reading, value_instance=1, hidden=True, old_entity_id=sensor.aeotec_zw075_smart_switch_gen5_previous_reading_28_9, node_id=28, value_index=9, friendly_name=Aeotec ZW07

[zarthan]

If you are going to replace the stick, Aeotec does have backup software.

Z-Stick Gen5 Backup Software

How many powered Zwave devices do you have? As @Tinkerer said you may be having a mesh issue. Look at how your devices are spread out. Can you move or add a device to get better coverage (powered only)

[Tinkerer (aka DubhAd on GitHub)]

Core dump is a sign of a fatal error, either a major bug, or a hardware fault.

I’d suspect the latter. If it’s only HA that’s core dumping, then probably the fault is with the Z-Wave stick. It’s worth trawling the system logs (and checking for core files) to see if there’s anything else core dumping.

[collse]

@zarthan

If you are going to replace the stick, Aeotec does have backup software.
Z-Stick Gen5 Backup Software : Aeotec Help Desk

How many powered Zwave devices do you have? As @Tinkerer said you may be having a mesh issue. Look at how your devices are spread out. Can you move or add a device to get better coverage (powered only)

I have replaced the stick but results arent any better …

my devices are:

ground floor
light switch kitchen (powered)
motion sensor kitchen (powered)
light switch lounge (powered)
socket lounge (powered)
motion sensor lounge (battery)
light switch hall (powered)
motion sensor hall (battery)

1st floor
1 motion sensor at the bottom of staircase (battery)
2 sockets office (powered)
1 motion sensor office (powered)
1 motion sensor landing (battery)

2nd floor
1 motion sensor on top of staircase (battery)
1 socket (powered)

thats pretty much the setup I would guess this shouldnt have much of an impact on the mesh

after a lot of playing around with settings I believe this may be due to the usb bridge on my NUC … not sure what the optimal setting is but I could see if I change some parameters the stick stalls in neighbors forever …that may be an indicator of whats wrong …

[Tinkerer (aka DubhAd on GitHub)]

Only the powered units will form the mesh (from my understanding), however yeah. It could be a USB issue, but I’d expect dmesg to spit out messages to support that (probably).

[collse]

Not sure what is causing this but after everything seemed to work perfectly fine for a while last night I was greeted with the attached this morning - I might disable the midnight healing completely as a next step to see if that improves anything … it almost looks like the issue occurs when restarts / reinits / heals happen …

[collse]

Tried to connect remotely just now to figure out it has core dumped again … will see when I get back home what the logs say this time

[collse]

back on the vpn as too curious …

kern.log

Aug 29 08:08:26 homeassistant kernel: [38340.914106] hass[11486]: segfault at eb685147 ip 00007f14eb582746 sp 00007ffd05a87e68 error 4 in
libc-2.23.so[7f14eb4f7000+1c0000]
Aug 29 08:08:26 homeassistant kernel: [38340.914100] show_signal_msg: 12 callbacks suppressed

syslog

Aug 29 08:08:26 homeassistant systemd[1]: [email protected]: Failed with result ‘core-dump’.
Aug 29 08:08:26 homeassistant systemd[1]: [email protected]: Unit entered failed state.
Aug 29 08:08:26 homeassistant systemd[1]: [email protected]: Main process exited, code=dumped, status=11/SEGV
Aug 29 08:08:26 homeassistant kernel: [38340.914106] hass[11486]: segfault at eb685147 ip 00007f14eb582746 sp 00007ffd05a87e68 error 4 in
libc-2.23.so[7f14eb4f7000+1c0000]
Aug 29 08:08:26 homeassistant kernel: [38340.914100] show_signal_msg: 12 callbacks suppressed

so nowI at least got an indicator but I am unable to trackback what line this causes might need to look into this more in detail how to get from address to line …

[zarthan]

Make sure you don’t have filesystem corruption.

[Tinkerer (aka DubhAd on GitHub)]

What else does the box do?

Do you actually get a core dump? Digging through some posts for segfault error 4 in libc-2.23.so is a mixed bag, but this one details somebody stepping through the diagnosis process.

[collse]

@zarthan

Make sure you don’t have filesystem corruption.

as mentioned its no Pi its a bear metal which should not lead to corruption on spinning disks …

@Tinkerer

What else does the box do?

Do you actually get a core dump? Digging through some posts for segfault error 4 in libc-2.23.so is a mixed bag, but this one details somebody stepping through the diagnosis process.

PiVPN, PiHole, glances, mosquitto etc - all nothing major and plenty of cpu&ram available …

but I installed a VM yesterday and monitor its behaviour at the moment …

[zarthan]

Having a spinning disk or SSD with a journalling file system isn’t a guarantee that you can’t have a corrupted file.

[collse]

@zarthan

Having a spinning disk or SSD with a journalling file system isn’t a guarantee that you can’t have a corrupted file.

Though that is true the likelihood with uptime without boots and low read/write cycles is quite low - Not sure what it is but I am on 17.04 now so all new kernel, libs etc too difficult to tell - I have disabled some components still but it seems to work fine fior the moment - if it stays that way I dont mind it and just keep it running in VMware instead of bear metal … knocking on wood and tine will tell - I want to see at least a week before I add the other components back in to see if it still fails …

[zarthan]

It is simple enough to check the filesystem to make sure.

[collse]

yeah fsck didnt bring up anything all good … I might just repurpose the NUC for something else if this works - might make things easier as well …

[zarthan]

I moved my setup to a VM a month ago or so. I have been Ok with it but especially like having a nightly backup. I love being able to bring up a backup image and experiment with it, then blow it away if things go bad.