Web interface failing to come up

the62694 · February 7, 2018, 5:14pm

Hello - I installed hass.io using the bottom portion of this blog post: https://home-assistant.io/blog/2017/11/29/hassio-virtual-machine/

All works well and I haven’t had any issues with add-ons or things like that.

But, I have this weird issue where sometimes when I reboot, the hass.io interface doesn’t come up. Often I’ll keep rebooting and it will come up, but I’ve been at it for about a half hour and no luck. In looking through the logs and the dockert status, it looks like hassi.io actually starts, but it’s not.

I also don’t see the hass.io port opened to connect to the front-end. I can reach Samba shares, which I know is running via hass.io, so what else is going on with the web front-end?

I’ve manually tried restarting - it restarts, but the interface doesn’t come up. Any other ideas?

root@haunit:~# sudo docker ps
CONTAINER ID        IMAGE                                    COMMAND                  CREATED             STATUS              PORTS               NAMES
c84c182b5a6e        27e642c6/amd64-addon-nodered             "/run.sh"                15 minutes ago      Up 15 minutes                           addon_27e642c6_nodered
dfb2a0738217        homeassistant/qemux86-64-homeassistant   "/usr/bin/entry.sh p…"   15 minutes ago      Up 5 seconds                            homeassistant
daf5ed7ff1f3        homeassistant/amd64-addon-samba          "/usr/bin/entry.sh /…"   15 minutes ago      Up 15 minutes                           addon_core_samba
b751f9087f71        homeassistant/amd64-hassio-supervisor    "/usr/bin/entry.sh p…"   8 days ago          Up 18 seconds                           hassio_supervisor

Active Internet connections (only servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State       PID/Program name
tcp        0      0 0.0.0.0:139             0.0.0.0:*               LISTEN      1870/smbd
tcp        0      0 0.0.0.0:22              0.0.0.0:*               LISTEN      1179/sshd
tcp        0      0 0.0.0.0:1880            0.0.0.0:*               LISTEN      2837/node-red
tcp        0      0 0.0.0.0:445             0.0.0.0:*               LISTEN      1870/smbd
tcp6       0      0 :::139                  :::*                    LISTEN      1870/smbd
tcp6       0      0 :::22                   :::*                    LISTEN      1179/sshd
tcp6       0      0 :::445                  :::*                    LISTEN      1870/smbd
tcp6       0      0 :::46501                :::*                    LISTEN      2837/node-red
root@haunit:~#

When I run the following: root@haunit:~# sudo docker restart homeassistant

Logs show the following:

commandcontrol@haunit:~$ tail -f -n 500 /var/log/{messages,kernel,dmesg,syslog}
Feb  7 11:58:06 haunit dockerd[1173]: time="2018-02-07T11:58:06.176841796-05:00" level=warning msg="unknown container" container=dfb2a07382173b4544ac7c747b2af874221b1685132d0db3e90656c012ba0152 module=libcontainerd namespace=plugins.moby
Feb  7 11:58:06 haunit dockerd[1173]: time="2018-02-07T11:58:06-05:00" level=info msg="shim reaped" id=dfb2a07382173b4544ac7c747b2af874221b1685132d0db3e90656c012ba0152 module="containerd/tasks"
Feb  7 11:58:06 haunit dockerd[1173]: time="2018-02-07T11:58:06.223727894-05:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Feb  7 11:58:06 haunit dockerd[1173]: time="2018-02-07T11:58:06.223732705-05:00" level=info msg="ignoring event" module=libcontainerd namespace=plugins.moby topic=/tasks/delete type="*events.TaskDelete"
Feb  7 11:58:06 haunit dockerd[1173]: time="2018-02-07T11:58:06.552719097-05:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/delete type="*events.ContainerDelete"
Feb  7 11:58:07 haunit dockerd[1173]: time="2018-02-07T11:58:07.182005653-05:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/create type="*events.ContainerCreate"
Feb  7 11:58:07 haunit dockerd[1173]: time="2018-02-07T11:58:07-05:00" level=info msg="shim docker-containerd-shim started" address="/containerd-shim/moby/dfb2a07382173b4544ac7c747b2af874221b1685132d0db3e90656c012ba0152/shim.sock" debug=false module="containerd/tasks" pid=8167
Feb  7 11:58:07 haunit dockerd[1173]: time="2018-02-07T11:58:07.293009001-05:00" level=warning msg="unknown container" container=dfb2a07382173b4544ac7c747b2af874221b1685132d0db3e90656c012ba0152 module=libcontainerd namespace=plugins.moby
Feb  7 11:58:07 haunit dockerd[1173]: time="2018-02-07T11:58:07.316648866-05:00" level=warning msg="unknown container" container=dfb2a07382173b4544ac7c747b2af874221b1685132d0db3e90656c012ba0152 module=libcontainerd namespace=plugins.moby
Feb  7 11:58:07 haunit systemd-udevd[8251]: Process '/sbin/crda' failed with exit code 234.
Feb  7 11:58:07 haunit kernel: [ 1453.975572] kvm: disabled by bios

Home Assistant log

commandcontrol@haunit:~$ tail -f -n 500 /usr/share/hassio/homeassistant/home-assistant.log
2018-02-07 11:58:10 WARNING (Recorder) [homeassistant.components.recorder] Ended unfinished session (id=118 from 2018-02-07 16:50:15.998364)
2018-02-07 11:58:13 WARNING (SyncWorker_10) [homeassistant.components.emulated_hue] When targeting Google Home, listening port has to be port 80
2018-02-07 11:58:24 WARNING (MainThread) [homeassistant.setup] Setup of zha is taking over 10 seconds.

When I run the following sudo docker restart hassio_supervisor

Log files show

commandcontrol@haunit:~$ tail -f -n 500 /var/log/{messages,kernel,dmesg,syslog}
Feb  7 12:04:52 haunit hassio-start[5865]: #033[32m18-02-07 17:04:52 INFO (MainThread) [__main__] Stopping HassIO#033[0m
Feb  7 12:04:52 haunit hassio-start[5865]: #033[32m18-02-07 17:04:52 INFO (MainThread) [hassio.misc.dns] Stop DNS forwarding#033[0m
Feb  7 12:04:52 haunit hassio-start[5865]: #033[32m18-02-07 17:04:52 INFO (MainThread) [__main__] Close Hassio#033[0m
Feb  7 12:04:52 haunit dockerd[1173]: time="2018-02-07T12:04:52.519017816-05:00" level=warning msg="unknown container" container=b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e module=libcontainerd namespace=plugins.moby
Feb  7 12:04:52 haunit dockerd[1173]: time="2018-02-07T12:04:52-05:00" level=info msg="shim reaped" id=b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e module="containerd/tasks"
Feb  7 12:04:52 haunit dockerd[1173]: time="2018-02-07T12:04:52.562038320-05:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Feb  7 12:04:52 haunit dockerd[1173]: time="2018-02-07T12:04:52.562064470-05:00" level=info msg="ignoring event" module=libcontainerd namespace=plugins.moby topic=/tasks/delete type="*events.TaskDelete"
Feb  7 12:04:52 haunit kernel: [ 1859.061366] docker0: port 1(veth8525c90) entered disabled state
Feb  7 12:04:52 haunit kernel: [ 1859.061521] veth1fb5740: renamed from eth0
Feb  7 12:04:52 haunit avahi-daemon[945]: Interface veth8525c90.IPv6 no longer relevant for mDNS.
Feb  7 12:04:52 haunit avahi-daemon[945]: Leaving mDNS multicast group on interface veth8525c90.IPv6 with address fe80::c45b:e9ff:feab:92ab.
Feb  7 12:04:52 haunit kernel: [ 1859.267686] docker0: port 1(veth8525c90) entered disabled state
Feb  7 12:04:52 haunit kernel: [ 1859.269364] device veth8525c90 left promiscuous mode
Feb  7 12:04:52 haunit kernel: [ 1859.269373] docker0: port 1(veth8525c90) entered disabled state
Feb  7 12:04:52 haunit avahi-daemon[945]: Withdrawing address record for fe80::c45b:e9ff:feab:92ab on veth8525c90.
Feb  7 12:04:53 haunit kernel: [ 1859.500312] hassio: port 1(vethb30480e) entered disabled state
Feb  7 12:04:53 haunit kernel: [ 1859.500454] vetha997a78: renamed from eth1
Feb  7 12:04:53 haunit avahi-daemon[945]: Interface vethb30480e.IPv6 no longer relevant for mDNS.
Feb  7 12:04:53 haunit avahi-daemon[945]: Leaving mDNS multicast group on interface vethb30480e.IPv6 with address fe80::48b1:6ff:fe33:1f0d.
Feb  7 12:04:53 haunit kernel: [ 1859.648514] hassio: port 1(vethb30480e) entered disabled state
Feb  7 12:04:53 haunit kernel: [ 1859.649777] device vethb30480e left promiscuous mode
Feb  7 12:04:53 haunit kernel: [ 1859.649784] hassio: port 1(vethb30480e) entered disabled state
Feb  7 12:04:53 haunit avahi-daemon[945]: Withdrawing address record for fe80::48b1:6ff:fe33:1f0d on vethb30480e.
Feb  7 12:04:53 haunit dockerd[1173]: time="2018-02-07T12:04:53.539521355-05:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/delete type="*events.ContainerDelete"
Feb  7 12:04:53 haunit kernel: [ 1860.146237] device veth2696b0c entered promiscuous mode
Feb  7 12:04:53 haunit kernel: [ 1860.146438] IPv6: ADDRCONF(NETDEV_UP): veth2696b0c: link is not ready
Feb  7 12:04:53 haunit kernel: [ 1860.146450] docker0: port 1(veth2696b0c) entered forwarding state
Feb  7 12:04:53 haunit kernel: [ 1860.146465] docker0: port 1(veth2696b0c) entered forwarding state
Feb  7 12:04:53 haunit systemd-udevd[10322]: Could not generate persistent MAC address for vethe3325a9: No such file or directory
Feb  7 12:04:53 haunit systemd-udevd[10324]: Could not generate persistent MAC address for veth2696b0c: No such file or directory
Feb  7 12:04:53 haunit kernel: [ 1860.146869] docker0: port 1(veth2696b0c) entered disabled state
Feb  7 12:04:54 haunit kernel: [ 1860.553963] device veth9a631bf entered promiscuous mode
Feb  7 12:04:54 haunit kernel: [ 1860.554122] IPv6: ADDRCONF(NETDEV_UP): veth9a631bf: link is not ready
Feb  7 12:04:54 haunit kernel: [ 1860.554136] hassio: port 1(veth9a631bf) entered forwarding state
Feb  7 12:04:54 haunit kernel: [ 1860.554154] hassio: port 1(veth9a631bf) entered forwarding state
Feb  7 12:04:54 haunit kernel: [ 1860.554217] hassio: port 1(veth9a631bf) entered disabled state
Feb  7 12:04:54 haunit systemd-udevd[10375]: Could not generate persistent MAC address for vethb77c3da: No such file or directory
Feb  7 12:04:54 haunit systemd-udevd[10379]: Could not generate persistent MAC address for veth9a631bf: No such file or directory
Feb  7 12:04:54 haunit dockerd[1173]: time="2018-02-07T12:04:54.539554910-05:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/create type="*events.ContainerCreate"
Feb  7 12:04:54 haunit dockerd[1173]: time="2018-02-07T12:04:54-05:00" level=info msg="shim docker-containerd-shim started" address="/containerd-shim/moby/b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e/shim.sock" debug=false module="containerd/tasks" pid=10425
Feb  7 12:04:54 haunit kernel: [ 1861.155353] eth0: renamed from vethe3325a9
Feb  7 12:04:54 haunit kernel: [ 1861.171527] IPv6: ADDRCONF(NETDEV_CHANGE): veth2696b0c: link becomes ready
Feb  7 12:04:54 haunit kernel: [ 1861.171565] docker0: port 1(veth2696b0c) entered forwarding state
Feb  7 12:04:54 haunit kernel: [ 1861.171584] docker0: port 1(veth2696b0c) entered forwarding state
Feb  7 12:04:54 haunit kernel: [ 1861.275842] eth1: renamed from vethb77c3da
Feb  7 12:04:54 haunit kernel: [ 1861.291069] IPv6: ADDRCONF(NETDEV_CHANGE): veth9a631bf: link becomes ready
Feb  7 12:04:54 haunit kernel: [ 1861.291108] hassio: port 1(veth9a631bf) entered forwarding state
Feb  7 12:04:54 haunit kernel: [ 1861.291130] hassio: port 1(veth9a631bf) entered forwarding state
Feb  7 12:04:54 haunit dockerd[1173]: time="2018-02-07T12:04:54.999437232-05:00" level=warning msg="unknown container" container=b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e module=libcontainerd namespace=plugins.moby
Feb  7 12:04:55 haunit dockerd[1173]: time="2018-02-07T12:04:55.020796118-05:00" level=warning msg="unknown container" container=b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e module=libcontainerd namespace=plugins.moby
Feb  7 12:04:56 haunit avahi-daemon[945]: Joining mDNS multicast group on interface veth9a631bf.IPv6 with address fe80::6c07:70ff:fee3:2991.
Feb  7 12:04:56 haunit avahi-daemon[945]: New relevant interface veth9a631bf.IPv6 for mDNS.
Feb  7 12:04:56 haunit avahi-daemon[945]: Registering new address record for fe80::6c07:70ff:fee3:2991 on veth9a631bf.*.
Feb  7 12:04:56 haunit avahi-daemon[945]: Joining mDNS multicast group on interface veth2696b0c.IPv6 with address fe80::84f2:e0ff:fe0f:9d31.
Feb  7 12:04:56 haunit avahi-daemon[945]: New relevant interface veth2696b0c.IPv6 for mDNS.
Feb  7 12:04:56 haunit avahi-daemon[945]: Registering new address record for fe80::84f2:e0ff:fe0f:9d31 on veth2696b0c.*.
Feb  7 12:04:56 haunit kernel: [ 1863.111270] device veth9d604c6 entered promiscuous mode
Feb  7 12:04:56 haunit kernel: [ 1863.111523] IPv6: ADDRCONF(NETDEV_UP): veth9d604c6: link is not ready
Feb  7 12:04:56 haunit systemd-udevd[10552]: Could not generate persistent MAC address for veth9d604c6: No such file or directory
Feb  7 12:04:56 haunit systemd-udevd[10551]: Could not generate persistent MAC address for vethe36323f: No such file or directory
Feb  7 12:04:57 haunit dockerd[1173]: time="2018-02-07T12:04:57.016605275-05:00" level=warning msg="Failed to allocate and map port 22-22: Error starting userland proxy: listen tcp 0.0.0.0:22: bind: address already in use"
Feb  7 12:04:57 haunit kernel: [ 1863.514959] hassio: port 2(veth9d604c6) entered disabled state
Feb  7 12:04:57 haunit kernel: [ 1863.516590] device veth9d604c6 left promiscuous mode
Feb  7 12:04:57 haunit kernel: [ 1863.516598] hassio: port 2(veth9d604c6) entered disabled state
Feb  7 12:04:57 haunit dockerd[1173]: time="2018-02-07T12:04:57.409789328-05:00" level=error msg="5c34589bba57894b42771257add8512eaa51d6670ba155f19c806298752002ca cleanup: failed to delete container from containerd: no such container"
Feb  7 12:04:57 haunit dockerd[1173]: time="2018-02-07T12:04:57.409864741-05:00" level=error msg="Handler for POST /v1.35/containers/5c34589bba57894b42771257add8512eaa51d6670ba155f19c806298752002ca/start returned error: driver failed programming external connectivity on endpoint addon_core_ssh (2cb2d9077a756b6053e2e7eec477d58f146a5e9005a8901b326c654ee00c3590): Error starting userland proxy: listen tcp 0.0.0.0:22: bind: address already in use"
Feb  7 12:05:05 haunit dockerd[1173]: time="2018-02-07T12:05:05.127016368-05:00" level=info msg="Container b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e failed to exit within 10 seconds of signal 15 - using the force"
Feb  7 12:05:05 haunit dockerd[1173]: time="2018-02-07T12:05:05.203254760-05:00" level=warning msg="unknown container" container=b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e module=libcontainerd namespace=plugins.moby
Feb  7 12:05:05 haunit dockerd[1173]: time="2018-02-07T12:05:05-05:00" level=info msg="shim reaped" id=b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e module="containerd/tasks"
Feb  7 12:05:05 haunit dockerd[1173]: time="2018-02-07T12:05:05.250157308-05:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Feb  7 12:05:05 haunit dockerd[1173]: time="2018-02-07T12:05:05.250214059-05:00" level=info msg="ignoring event" module=libcontainerd namespace=plugins.moby topic=/tasks/delete type="*events.TaskDelete"
Feb  7 12:05:05 haunit kernel: [ 1871.753663] vethe3325a9: renamed from eth0
Feb  7 12:05:05 haunit kernel: [ 1871.776009] docker0: port 1(veth2696b0c) entered disabled state
Feb  7 12:05:05 haunit avahi-daemon[945]: Interface veth2696b0c.IPv6 no longer relevant for mDNS.
Feb  7 12:05:05 haunit kernel: [ 1871.976279] docker0: port 1(veth2696b0c) entered disabled state
Feb  7 12:05:05 haunit avahi-daemon[945]: Leaving mDNS multicast group on interface veth2696b0c.IPv6 with address fe80::84f2:e0ff:fe0f:9d31.
Feb  7 12:05:05 haunit kernel: [ 1871.980613] device veth2696b0c left promiscuous mode
Feb  7 12:05:05 haunit kernel: [ 1871.980621] docker0: port 1(veth2696b0c) entered disabled state
Feb  7 12:05:05 haunit avahi-daemon[945]: Withdrawing address record for fe80::84f2:e0ff:fe0f:9d31 on veth2696b0c.
Feb  7 12:05:05 haunit kernel: [ 1872.192011] hassio: port 1(veth9a631bf) entered disabled state
Feb  7 12:05:05 haunit kernel: [ 1872.192147] vethb77c3da: renamed from eth1
Feb  7 12:05:05 haunit avahi-daemon[945]: Interface veth9a631bf.IPv6 no longer relevant for mDNS.
Feb  7 12:05:05 haunit avahi-daemon[945]: Leaving mDNS multicast group on interface veth9a631bf.IPv6 with address fe80::6c07:70ff:fee3:2991.
Feb  7 12:05:05 haunit kernel: [ 1872.357063] hassio: port 1(veth9a631bf) entered disabled state
Feb  7 12:05:05 haunit kernel: [ 1872.358544] device veth9a631bf left promiscuous mode
Feb  7 12:05:05 haunit kernel: [ 1872.358552] hassio: port 1(veth9a631bf) entered disabled state
Feb  7 12:05:05 haunit avahi-daemon[945]: Withdrawing address record for fe80::6c07:70ff:fee3:2991 on veth9a631bf.
Feb  7 12:05:06 haunit dockerd[1173]: time="2018-02-07T12:05:06.238620225-05:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/delete type="*events.ContainerDelete"
Feb  7 12:05:06 haunit docker[10315]: hassio_supervisor
Feb  7 12:05:16 haunit systemd[1]: hassio-supervisor.service: Service hold-off time over, scheduling restart.
Feb  7 12:05:16 haunit systemd[1]: Stopped Hass.io supervisor.
Feb  7 12:05:16 haunit systemd[1]: Starting Hass.io supervisor...
Feb  7 12:05:16 haunit docker[10755]: hassio_supervisor
Feb  7 12:05:16 haunit systemd[1]: Started Hass.io supervisor.
Feb  7 12:05:16 haunit kernel: [ 1883.067526] device veth62581a8 entered promiscuous mode
Feb  7 12:05:16 haunit kernel: [ 1883.067635] IPv6: ADDRCONF(NETDEV_UP): veth62581a8: link is not ready
Feb  7 12:05:16 haunit systemd-udevd[10796]: Could not generate persistent MAC address for vethcf72421: No such file or directory
Feb  7 12:05:16 haunit systemd-udevd[10797]: Could not generate persistent MAC address for veth62581a8: No such file or directory
Feb  7 12:05:16 haunit kernel: [ 1883.387076] device vethc693f05 entered promiscuous mode
Feb  7 12:05:16 haunit kernel: [ 1883.387270] IPv6: ADDRCONF(NETDEV_UP): vethc693f05: link is not ready
Feb  7 12:05:16 haunit kernel: [ 1883.387284] hassio: port 1(vethc693f05) entered forwarding state
Feb  7 12:05:16 haunit kernel: [ 1883.387306] hassio: port 1(vethc693f05) entered forwarding state
Feb  7 12:05:16 haunit kernel: [ 1883.387384] hassio: port 1(vethc693f05) entered disabled state
Feb  7 12:05:16 haunit systemd-udevd[10854]: Could not generate persistent MAC address for vethc693f05: No such file or directory
Feb  7 12:05:16 haunit systemd-udevd[10850]: Could not generate persistent MAC address for vethfba2a92: No such file or directory
Feb  7 12:05:17 haunit dockerd[1173]: time="2018-02-07T12:05:17.307944706-05:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/create type="*events.ContainerCreate"
Feb  7 12:05:17 haunit dockerd[1173]: time="2018-02-07T12:05:17-05:00" level=info msg="shim docker-containerd-shim started" address="/containerd-shim/moby/b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e/shim.sock" debug=false module="containerd/tasks" pid=10903
Feb  7 12:05:17 haunit kernel: [ 1883.883139] eth0: renamed from vethcf72421
Feb  7 12:05:17 haunit kernel: [ 1883.894398] IPv6: ADDRCONF(NETDEV_CHANGE): veth62581a8: link becomes ready
Feb  7 12:05:17 haunit kernel: [ 1883.894425] docker0: port 1(veth62581a8) entered forwarding state
Feb  7 12:05:17 haunit kernel: [ 1883.894436] docker0: port 1(veth62581a8) entered forwarding state
Feb  7 12:05:17 haunit kernel: [ 1883.987463] eth1: renamed from vethfba2a92
Feb  7 12:05:17 haunit kernel: [ 1884.006688] IPv6: ADDRCONF(NETDEV_CHANGE): vethc693f05: link becomes ready
Feb  7 12:05:17 haunit kernel: [ 1884.006729] hassio: port 1(vethc693f05) entered forwarding state
Feb  7 12:05:17 haunit kernel: [ 1884.006749] hassio: port 1(vethc693f05) entered forwarding state
Feb  7 12:05:17 haunit dockerd[1173]: time="2018-02-07T12:05:17.713341113-05:00" level=warning msg="unknown container" container=b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e module=libcontainerd namespace=plugins.moby
Feb  7 12:05:17 haunit dockerd[1173]: time="2018-02-07T12:05:17.735278018-05:00" level=warning msg="unknown container" container=b751f9087f7157fa32a6845734b2dc9ee6fa749a14e047bc648d4225effc015e module=libcontainerd namespace=plugins.moby
Feb  7 12:05:17 haunit hassio-start[10765]: starting version 3.2.4
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [__main__] Initialize Hassio setup#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [__main__] Setup HassIO#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (SyncWorker_0) [hassio.docker.supervisor] Attach to supervisor homeassistant/amd64-hassio-supervisor with version 0.85#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.misc.host_control] Send 'info' to HostControl.#033[0m
Feb  7 12:05:18 haunit avahi-daemon[945]: Joining mDNS multicast group on interface veth62581a8.IPv6 with address fe80::e053:2cff:feed:4b03.
Feb  7 12:05:18 haunit avahi-daemon[945]: New relevant interface veth62581a8.IPv6 for mDNS.
Feb  7 12:05:18 haunit avahi-daemon[945]: Registering new address record for fe80::e053:2cff:feed:4b03 on veth62581a8.*.
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.misc.host_control] Receive from HostControl: { "type": "generic", "version": "0.3", "last_version": "0.3", "hostname": "haunit", "features": ["shutdown", "reboot", "update"], "os": "GNU/Linux" }.#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (SyncWorker_1) [hassio.docker.interface] Attach to image homeassistant/qemux86-64-homeassistant with version 0.62.1#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[33m18-02-07 17:05:18 WARNING (MainThread) [hassio.addons.data] Can't read repository information from /data/addons/git/7c7e82cf/repository.json#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.addons.git] Load addon /data/addons/core repository#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.addons.git] Load addon /data/addons/git/27e642c6 repository#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[33m18-02-07 17:05:18 WARNING (MainThread) [hassio.addons.data] Can't read repository information from /data/addons/git/7c7e82cf/repository.json#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.addons] Load addons: 22 all - 22 new - 0 remove#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (SyncWorker_1) [hassio.docker.interface] Attach to image homeassistant/amd64-addon-samba with version 3.0#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (SyncWorker_3) [hassio.docker.interface] Attach to image homeassistant/amd64-addon-ssh with version 3.0#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (SyncWorker_4) [hassio.docker.interface] Attach to image 27e642c6/amd64-addon-nodered with version 0.1.6#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.updater] Fetch update data from https://raw.githubusercontent.com/home-assistant/hassio/master/version.json#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.snapshots] Found 1 snapshot files#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.addons] Startup initialize run 0 addons#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [__main__] Run HassIO#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.misc.dns] Start DNS port forwarding for host add-ons#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.core] Ignore Hass.io auto updates on beta mode#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.core] Start API on 172.30.32.2#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.addons] Startup system run 0 addons#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (MainThread) [hassio.addons] Startup services run 2 addons#033[0m
Feb  7 12:05:18 haunit hassio-start[10765]: #033[32m18-02-07 17:05:18 INFO (SyncWorker_10) [hassio.docker.interface] Clean homeassistant/amd64-addon-ssh docker application#033[0m
Feb  7 12:05:19 haunit avahi-daemon[945]: Joining mDNS multicast group on interface vethc693f05.IPv6 with address fe80::cc87:d7ff:fe9b:ee75.
Feb  7 12:05:19 haunit avahi-daemon[945]: New relevant interface vethc693f05.IPv6 for mDNS.
Feb  7 12:05:19 haunit avahi-daemon[945]: Registering new address record for fe80::cc87:d7ff:fe9b:ee75 on vethc693f05.*.
Feb  7 12:05:19 haunit kernel: [ 1885.841077] device veth1577cac entered promiscuous mode
Feb  7 12:05:19 haunit systemd-udevd[11019]: Could not generate persistent MAC address for veth9e77c5a: No such file or directory
Feb  7 12:05:19 haunit systemd-udevd[11020]: Could not generate persistent MAC address for veth1577cac: No such file or directory
Feb  7 12:05:19 haunit kernel: [ 1885.842416] IPv6: ADDRCONF(NETDEV_UP): veth1577cac: link is not ready
Feb  7 12:05:20 haunit dockerd[1173]: time="2018-02-07T12:05:20.010991061-05:00" level=warning msg="Failed to allocate and map port 22-22: Error starting userland proxy: listen tcp 0.0.0.0:22: bind: address already in use"
Feb  7 12:05:20 haunit kernel: [ 1886.514499] hassio: port 2(veth1577cac) entered disabled state
Feb  7 12:05:20 haunit kernel: [ 1886.516057] device veth1577cac left promiscuous mode
Feb  7 12:05:20 haunit kernel: [ 1886.516063] hassio: port 2(veth1577cac) entered disabled state
Feb  7 12:05:20 haunit dockerd[1173]: time="2018-02-07T12:05:20.397881062-05:00" level=error msg="29f734f5d0c1a250794ed89592a27a45e3d242dc571f479d30517abd059d15f7 cleanup: failed to delete container from containerd: no such container"
Feb  7 12:05:20 haunit dockerd[1173]: time="2018-02-07T12:05:20.398004698-05:00" level=error msg="Handler for POST /v1.35/containers/29f734f5d0c1a250794ed89592a27a45e3d242dc571f479d30517abd059d15f7/start returned error: driver failed programming external connectivity on endpoint addon_core_ssh (f846c0c9c6ff4f24f0476ab68856ea9f0d94dcdc5d8560d6fb86f577bcb19890): Error starting userland proxy: listen tcp 0.0.0.0:22: bind: address already in use"
Feb  7 12:05:20 haunit hassio-start[10765]: #033[31m18-02-07 17:05:20 ERROR (SyncWorker_10) [hassio.docker] Can't start addon_core_ssh: 500 Server Error: Internal Server Error ("driver failed programming external connectivity on endpoint addon_core_ssh (f846c0c9c6ff4f24f0476ab68856ea9f0d94dcdc5d8560d6fb86f577bcb19890): Error starting userland proxy: listen tcp 0.0.0.0:22: bind: address already in use")#033[0m
Feb  7 12:05:25 haunit hassio-start[10765]: #033[32m18-02-07 17:05:25 INFO (MainThread) [hassio.addons] Startup application run 1 addons#033[0m
Feb  7 12:05:30 haunit hassio-start[10765]: #033[32m18-02-07 17:05:30 INFO (MainThread) [hassio.tasks] All core tasks are scheduled#033[0m
Feb  7 12:05:30 haunit hassio-start[10765]: #033[32m18-02-07 17:05:30 INFO (MainThread) [hassio.core] Hass.io is up and running#033[0m
Feb  7 12:05:32 haunit kernel: [ 1898.941845] docker0: port 1(veth62581a8) entered forwarding state
Feb  7 12:05:32 haunit kernel: [ 1899.037848] hassio: port 1(vethc693f05) entered forwarding state

the62694 · February 8, 2018, 2:34am

Strangely enough, I had to rebuild my configuration.yaml file. Even though configuration checks out as valid, When hass.io is stopped/rebooted, it appears to be failing because of the zha line

zha:
  usb_path: /dev/ttyUSB1
  database_path: zigbee.db

I don’t understand why. The usb stick and lights paired with it work just fine.

After going through the whole config, I had to uncomment my zwave line, which I commented, thinking maybe it was causing an issue.

zwave:
  usb_path: /dev/ttyACM0

After uncommenting this line, the web front-end restarts and is available with no issue.

commandcontrol@haunit:~$ sudo netstat -ptln
[sudo] password for commandcontrol:
Active Internet connections (only servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State       PID/Program name
tcp        0      0 0.0.0.0:139             0.0.0.0:*               LISTEN      1787/smbd
tcp        0      0 10.1.30.194:8300        0.0.0.0:*               LISTEN      1825/python3
tcp        0      0 0.0.0.0:22              0.0.0.0:*               LISTEN      1166/sshd
tcp        0      0 0.0.0.0:1880            0.0.0.0:*               LISTEN      3351/node-red
tcp        0      0 0.0.0.0:8123            0.0.0.0:*               LISTEN      1825/python3
tcp        0      0 0.0.0.0:445             0.0.0.0:*               LISTEN      1787/smbd
tcp6       0      0 :::139                  :::*                    LISTEN      1787/smbd
tcp6       0      0 :::41967                :::*                    LISTEN      3351/node-red
tcp6       0      0 :::22                   :::*                    LISTEN      1166/sshd
tcp6       0      0 :::445                  :::*                    LISTEN      1787/smbd

Well - things are working, but I have no idea how or why those two config statements may have been causing the issue.

I see that the front-end using python, which I know is used by the USB dongle - the HUSBZ stick. So are they related?

Either way - I’m back up and running.

the62694 · February 8, 2018, 12:46pm

An interesting evening, but learned a lot. I’m going to share what I learned, because it appears that this is still an existing problem.

So after thinking I figured out the issue - the problem continued. Anytime I reloaded the config or rebooted - the problem would reappear - web interface would not come up.

After reading tons of posts, some folks said it’s the other Aeotech stick or you have to disable Z-Wave on other devices or unplug and plug the usb stick several times. But none of this worked - not all the time at least.

Sometimes the web interface would come up, but there would and error card. When this happened, my Zigbee devices would not work - the automations would show they were happening, but the lights weren’t going on.

The error was very generic:

hass.io is configured incorrectly

This made no sense, unless the stick itself was faulty. But, after a few more reboots, eventually it would be like magic, the web interface would come up, no error cards and Zigbee devices would work.

When this issue happens, I was also unable to add new Zigbee devices like expected. Calling zha.permit resulted in nothing happening.

The most confusing part is that because this wasn’t a config issue, there is no real message from hass.io to help you figure out what’s going on. While watching the logs, I purposely made the config invalid, tried restarting hass.io and you would see right in the logs the failure, the reason and why the restart would fail. So I knew something else was going on. I honestly thought it was a faulty stick.

I continued searching and then I found this thread: hass.io won't start when zha component enabled · Issue #8922 · home-assistant/core · GitHub

As I read through the posts, it was clear that I was running into the same issue. It appears to be an active issue as well.

Long story short - it’s a known issue - captured in that issue thread and the fix is just keep rebooting/restarting until the problem stops and everything works. Don’t just stop at no errors - make sure you devices and automations work!

Hope this helps someone!

Nowork · April 13, 2018, 6:04pm

broken for me, too

fresh install hass.io on raspberry pi today 0.66.1

works ok (HUSBZB-1) until I add
zha:
usb_path: /dev/ttyUSB1
database_path: /config/zigbee.db

hass.io web interface broken

Rutger_August · March 28, 2019, 8:23am

Had this issue too.

My issue was also on a VM.
The issue appeard after a reboot of the host (windows updates YAY).

My VM had the adapter for wifi set as main adapter.
after a reboot Wifi does not connect automatic if you also have ethernet connection.

So i rebooted multiple times as the solution here stated.
This did not help.

i started looking at the firewall (WINDOWS mainHOST).
no resolution with that.

i connected wifi and the issue was resolved when i rebooted the VM.

Now i have it set to ethernet instead of wifi.

I really hopes this helps other people with this issue (host Winbased)

severinbeauvais · January 31, 2021, 10:00pm

sorry, posted to wrong thread

adamf663 · February 2, 2021, 1:28am

I also had a home assistant install where the web interface wouldn’t come up. The error was something like "couldn’t connect the home assistant ". It turned out to be an expired cert.