I upgraded today in a big leap from 0.43 to 0.54. Obviously this was relatively involved and I dealt with the new zwave entity IDs, etc.
I also removed some first alert smoke/CO alarms from the z-wave network so that I could attach them to Abode (the main motivation for upgrading HA).
However, now the HA service is randomly killed. The web interface stops responding and the service status is killed. Unfortunately the log hasn’t given me any useful info. I’ve already logged down to INFO level, and so far at crash point the logs have all been different and seemingly without error. Here’s the last one:
2017-10-01 20:35:26 INFO (MainThread) [homeassistant.core] Bus:Handling <Event zwave.node_event[L]: entity_id=zwave.entryway, node_id=36, basic_level=255>
I’m running now with debug logging but I’m not optimistic. I’m at a bit of a loss due to the absence of errors or issues in the log. Any ideas?
EDIT: Just had another crash, this time with DEBUG logging on. I grabbed the following from the tail of syslog:
Oct 1 21:01:34 homeassistant hass[1616]: #033[36m2017-10-01 21:01:34 DEBUG (Dummy-13) [libopenzwave] notif_callback : new notification#033[0m
Oct 1 21:01:34 homeassistant hass[1616]: #033[36m2017-10-01 21:01:34 DEBUG (Dummy-13) [libopenzwave] notif_callback : Notification type : 10, nodeId : 22#033[0m
Oct 1 21:01:34 homeassistant hass[1616]: #033[36m2017-10-01 21:01:34 DEBUG (Dummy-13) [libopenzwave] notif_callback : call callback context#033[0m
Oct 1 21:01:34 homeassistant hass[1616]: #033[36m2017-10-01 21:01:34 DEBUG (Dummy-13) [openzwave] zwcallback args=[{'homeId': 4017489018, 'event': 255, 'notificationType': 'NodeEvent', 'nodeId': 22}]#033[0m
Oct 1 21:01:34 homeassistant hass[1616]: #033[36m2017-10-01 21:01:34 DEBUG (Dummy-13) [openzwave] Z-Wave Notification NodeEvent : {'homeId': 4017489018, 'event': 255, 'notificationType': 'NodeEvent', 'nodeId': 22}#033[0m
Oct 1 21:01:34 homeassistant hass[1616]: #033[36m2017-10-01 21:01:34 DEBUG (Dummy-13) [libopenzwave] notif_callback : end#033[0m
Oct 1 21:01:34 homeassistant hass[1616]: #033[32m2017-10-01 21:01:34 INFO (MainThread) [homeassistant.core] Bus:Handling <Event zwave.node_event[L]: basic_level=255, node_id=22, entity_id=zwave.zooz_4in1_livingroom>#033[0m
Oct 1 21:01:34 homeassistant systemd[1]: home-assistant.service: main process exited, code=killed, status=11/SEGV
Oct 1 21:01:34 homeassistant systemd[1]: Unit home-assistant.service entered failed state.
…and 1 more, because I thought maybe that device was at fault. I excluded it from the network and restarting but got another different fail quickly:
Oct 1 21:21:59 homeassistant hass[1777]: #033[36m2017-10-01 21:21:59 DEBUG (Dummy-13) [libopenzwave] notif_callback : Notification type : 26, nodeId : 38#033[0m
Oct 1 21:21:59 homeassistant hass[1777]: #033[36m2017-10-01 21:21:59 DEBUG (Dummy-13) [libopenzwave] notif_callback : call callback context#033[0m
Oct 1 21:21:59 homeassistant hass[1777]: #033[36m2017-10-01 21:21:59 DEBUG (Dummy-13) [openzwave] zwcallback args=[{'notificationType': 'Notification', 'notificationCode': 1, 'homeId': 4017489018, 'nodeId': 38}]#033[0m
Oct 1 21:21:59 homeassistant hass[1777]: #033[36m2017-10-01 21:21:59 DEBUG (Dummy-13) [openzwave] Z-Wave Notification : {'notificationType': 'Notification', 'notificationCode': 1, 'homeId': 4017489018, 'nodeId': 38}#033[0m
Oct 1 21:21:59 homeassistant hass[1777]: #033[36m2017-10-01 21:21:59 DEBUG (Dummy-13) [libopenzwave] notif_callback : end#033[0m
Oct 1 21:22:00 homeassistant hass[1777]: #033[32m2017-10-01 21:22:00 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state zwave.kitchen_cans=Session; manufacturer_name=HomeSeer, node_name=kitchen_cans, lastResponseRTT=0, capabilities={'listening', 'beaming', 'routing'}, receivedUnsolicited=0, is_zwave_plus=True, max_baud_rate=40000, node_id=38, is_awake=True, is_ready=False, receivedDups=0, neighbors={1, 2, 4, 5, 6, 7, 36, 37, 39, 11, 40, 43, 15, 17, 18, 19, 30}, new_entity_id=zwave.kitchen_cans, product_name=HS-WD100+ Wall Dimmer, averageResponseRTT=0, query_stage=Session, lastRequestRTT=24, sentTS=2017-10-01 21:21:49:952 , sentCnt=5, is_failed=False, is_info_received=True, receivedTS=2017-10-01 21:15:22:209 , old_entity_id=zwave.kitchen_cans_38, receivedCnt=0, friendly_name=kitchen_cans, averageRequestRTT=24, retries=0, sentFailed=0 @ 2017-10-01T21:21:50.051363-07:00>, old_state=<state zwave.kitchen_cans=Session; manufacturer_name=HomeSeer, node_name=kitchen_cans, lastResponseRTT=0, capabilities={'listening', 'beaming', 'routing'}, receivedUnsolicited=0, is_zwave_plus=True, max_baud_rate=40000, node_id=38, is_awake=True, is_ready=False, receivedDups=0, neighbors={1, 2, 4, 5, 6, 7, 36, 37, 39, 11, 40, 43, 15, 17, 18, 19, 30}, new_entity_id=zwave.kitchen_cans, product_name=HS-WD100+ Wall Dimmer, averageResponseRTT=0, query_stage=Session, lastRequestRTT=23, sentTS=2017-10-01 21:21:39:934 , sentCnt=4, is_failed=False, is_info_received=True, receivedTS=2017-10-01 21:15:22:209 , old_entity_id=zwave.kitchen_cans_38, receivedCnt=0, friendly_name=kitchen_cans, averageRequestRTT=24, retries=0, sentFailed=0 @ 2017-10-01T21:21:50.051363-07:00>, entity_id=zwave.kitchen_cans>#033[0m
Oct 1 21:22:00 homeassistant hass[1777]: #033[36m2017-10-01 21:22:00 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 1838686672: Sending {'event': {'event_type': 'state_changed', 'data': {'new_state': <state zwave.kitchen_cans=Session; manufacturer_name=HomeSeer, node_name=kitchen_cans, lastResponseRTT=0, capabilities={'listening', 'beaming', 'routing'}, receivedUnsolicited=0, is_zwave_plus=True, max_baud_rate=40000, node_id=38, is_awake=True, is_ready=False, receivedDups=0, neighbors={1, 2, 4, 5, 6, 7, 36, 37, 39, 11, 40, 43, 15, 17, 18, 19, 30}, new_entity_id=zwave.kitchen_cans, product_name=HS-WD100+ Wall Dimmer, averageResponseRTT=0, query_stage=Session, lastRequestRTT=24, sentTS=2017-10-01 21:21:49:952 , sentCnt=5, is_failed=False, is_info_received=True, receivedTS=2017-10-01 21:15:22:209 , old_entity_id=zwave.kitchen_cans_38, receivedCnt=0, friendly_name=kitchen_cans, averageRequestRTT=24, retries=0, sentFailed=0 @ 2017-10-01T21:21:50.051363-07:00>, 'old_state': <state zwave.kitchen_cans=Session; manufacturer_name=HomeSeer, node_name=kitchen_cans, lastResponseRTT=0, capabilities={'listening', 'beaming', 'routing'}, receivedUnsolicited=0, is_zwave_plus=True, max_baud_rate=40000, node_id=38, is_awake=True, is_ready=False, receivedDups=0, neighbors={1, 2, 4, 5, 6, 7, 36, 37, 39, 11, 40, 43, 15, 17, 18, 19, 30}, new_entity_id=zwave.kitchen_cans, product_name=HS-WD100+ Wall Dimmer, averageResponseRTT=0, query_stage=Session, lastRequestRTT=23, sentTS=2017-10-01 21:21:39:934 , sentCnt=4, is_failed=False, is_info_received=True, receivedTS=2017-10-01 21:15:22:209 , old_entity_id=zwave.kitchen_cans_38, receivedCnt=0, friendly_name=kitchen_cans, averageRequestRTT=24, retries=0, sentFailed=0 @ 2017-10-01T21:21:50.051363-07:00>, 'entity_id': 'zwave.kitchen_cans'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2017, 10, 2, 4, 22, 0, 69652, tzinfo=<UTC>)}, 'type': 'event', 'id': 2}#033[0m
Oct 1 21:22:00 homeassistant systemd[1]: home-assistant.service: main process exited, code=killed, status=11/SEGV
Oct 1 21:22:00 homeassistant systemd[1]: Unit home-assistant.service entered failed state.
I don’t get it – am I missing something obvious or is there zero info in this log about why it exited?