Hello,
A few days ago I spinned up Home Assistant in a docker container on my raspberry pi 3 B+ running ubuntu server. Today is the second time I try to connect to the Home Assistant dashboard and it doesn’t respond. I SSH into the server and it was up and runnig, other containers were running as well, and only home assistant was stopped. I simply wrote docker start home-assistant
and everything was ok. Does anyone know why that container stops without a reason?
Thank you in advance
jay_p
January 3, 2021, 7:38pm
2
Have you looked at the logs of the container?
docker logs --tail 50 <container_name>
Yes, I’ve tried, but I can’t see nothing important: here’s the last things that happened before I started it again.
2021-01-01T17:32:21.033316193Z 2021-01-01 18:32:21 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-01T18:37:41.014007643Z 2021-01-01 19:37:41 ERROR (MainThread) [homeassistant.components.ipp] Error fetching ipp data: Invalid response from API: Timeout occurred while connecting to IPP server.
2021-01-01T21:45:01.029932500Z 2021-01-01 22:45:01 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-01T23:06:11.024431679Z 2021-01-02 00:06:11 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-02T00:30:11.045507153Z 2021-01-02 01:30:11 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T00:56:31.029335046Z 2021-01-02 01:56:31 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-02T02:05:11.029971910Z 2021-01-02 03:05:11 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-02T05:37:21.029490490Z 2021-01-02 06:37:21 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-02T09:27:21.060426800Z 2021-01-02 10:27:21 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T10:17:51.045496599Z 2021-01-02 11:17:51 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T11:39:21.045324432Z 2021-01-02 12:39:21 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T11:44:51.044490793Z 2021-01-02 12:44:51 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T12:29:21.044176586Z 2021-01-02 13:29:21 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T12:50:01.029586559Z 2021-01-02 13:50:01 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-03T19:15:39.151360265Z [s6-init] making user provided files available at /var/run/s6/etc…exited 0.
2021-01-03T19:15:39.581200636Z [s6-init] ensuring user provided files have correct perms…exited 0.
2021-01-03T19:15:39.591553363Z [fix-attrs.d] applying ownership & permissions fixes…
2021-01-03T19:15:39.604655210Z [fix-attrs.d] done.
2021-01-03T19:15:39.614961635Z [cont-init.d] executing container initialization scripts…
2021-01-03T19:15:39.625191602Z [cont-init.d] udev.sh: executing…
2021-01-03T19:15:40.225454108Z starting version 3.2.9
2021-01-03T19:15:40.270411482Z [20:15:40] INFO: Update udev information
2021-01-03T19:15:40.470114400Z [cont-init.d] udev.sh: exited 0.
2021-01-03T19:15:40.474047740Z [cont-init.d] done.
2021-01-03T19:15:40.485745991Z [services.d] starting services
2021-01-03T19:15:40.544362869Z [services.d] done.
I’ve started the container at 2021-01-03T19:15:39
so the cause should be before that moment.
jay_p
January 3, 2021, 8:00pm
4
It would be interesting to go back before the errors started flooding the logs. Do you still have the data?
Yes, but there’s not much, simply the container spinning up. I paste here the whole logs:
2020-12-31T17:39:24.089488594Z [s6-init] making user provided files available at /var/run/s6/etc...exited 0.
2020-12-31T17:39:24.559509876Z [s6-init] ensuring user provided files have correct perms...exited 0.
2020-12-31T17:39:24.570285773Z [fix-attrs.d] applying ownership & permissions fixes...
2020-12-31T17:39:24.584834571Z [fix-attrs.d] done.
2020-12-31T17:39:24.594908033Z [cont-init.d] executing container initialization scripts...
2020-12-31T17:39:24.604794468Z [cont-init.d] udev.sh: executing...
2020-12-31T17:39:25.026092006Z starting version 3.2.9
2020-12-31T17:39:25.065749623Z [17:39:25] INFO: Update udev information
2020-12-31T17:39:25.283820844Z [cont-init.d] udev.sh: exited 0.
2020-12-31T17:39:25.287669785Z [cont-init.d] done.
2020-12-31T17:39:25.298971769Z [services.d] starting services
2020-12-31T17:39:25.351609100Z [services.d] done.
2020-12-31T17:59:59.031479778Z 2020-12-31 17:59:59 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2020-12-31T18:44:39.032535879Z 2020-12-31 18:44:39 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2020-12-31T18:58:09.051419802Z 2020-12-31 18:58:09 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2020-12-31T20:53:23.044962526Z 2020-12-31 20:53:23 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2020-12-31T21:57:28.022822358Z 2020-12-31 21:57:28 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2020-12-31T23:01:33.032809364Z 2020-12-31 23:01:33 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2020-12-31T23:20:49.030760474Z 2020-12-31 23:20:49 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-01T00:05:38.022984119Z 2021-01-01 00:05:38 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T00:41:29.030052055Z 2021-01-01 00:41:29 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-01T01:09:43.023256967Z 2021-01-01 01:09:43 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T01:41:59.056299885Z 2021-01-01 01:41:59 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-01T02:12:09.029655806Z 2021-01-01 02:12:09 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-01T02:13:48.024372836Z 2021-01-01 02:13:48 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T03:17:53.023074048Z 2021-01-01 03:17:53 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T03:39:49.034676248Z 2021-01-01 03:39:49 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-01T04:21:58.023685605Z 2021-01-01 04:21:58 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T05:26:03.023061848Z 2021-01-01 05:26:03 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T06:30:08.023487274Z 2021-01-01 06:30:08 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T07:34:13.024121362Z 2021-01-01 07:34:13 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T08:38:18.023975498Z 2021-01-01 08:38:18 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T09:42:23.023526300Z 2021-01-01 09:42:23 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T10:46:28.024135296Z 2021-01-01 10:46:28 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T11:50:33.023999658Z 2021-01-01 11:50:33 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T12:54:38.026434486Z 2021-01-01 12:54:38 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T13:58:43.023504591Z 2021-01-01 13:58:43 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T14:22:29.029613413Z 2021-01-01 14:22:29 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-01T15:02:48.023705551Z 2021-01-01 15:02:48 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T16:06:53.022447117Z 2021-01-01 16:06:53 ERROR (MainThread) [metno] https://aa015h6buqvih86i1.api.met.no/weatherapi/locationforecast/2.0/complete returned Cannot connect to host aa015h6buqvih86i1.api.met.no:443 ssl:default [Try again]
2021-01-01T16:54:42.488305883Z 2021-01-01 16:54:42 WARNING (Thread-7) [homeassistant.components.mqtt] Disconnected from MQTT server 192.168.1.223:1883 (1)
2021-01-01T16:54:43.032120520Z [cont-finish.d] executing container finish scripts...
2021-01-01T16:54:43.050700825Z [cont-finish.d] done.
2021-01-01T16:54:43.052680569Z [s6-finish] waiting for services.
2021-01-01T16:54:44.531962813Z Unable to find configuration. Creating default one in /config
2021-01-01T16:54:46.969586572Z s6-svscanctl: fatal: unable to control /var/run/s6/services: supervisor not listening
2021-01-01T16:54:47.182449582Z [s6-finish] sending all processes the TERM signal.
2021-01-01T16:54:50.208370116Z [s6-finish] sending all processes the KILL signal and exiting.
(FIRST TIME I'VE FOUND THE CONTAINER STOPPED)
2021-01-01T17:17:01.871231272Z [s6-init] making user provided files available at /var/run/s6/etc...exited 0.
2021-01-01T17:17:02.322217920Z [s6-init] ensuring user provided files have correct perms...exited 0.
2021-01-01T17:17:02.332759208Z [fix-attrs.d] applying ownership & permissions fixes...
2021-01-01T17:17:02.346385157Z [fix-attrs.d] done.
2021-01-01T17:17:02.356662432Z [cont-init.d] executing container initialization scripts...
2021-01-01T17:17:02.367017051Z [cont-init.d] udev.sh: executing...
2021-01-01T17:17:02.823441869Z starting version 3.2.9
2021-01-01T17:17:02.868543346Z [18:17:02] INFO: Update udev information
2021-01-01T17:17:03.066719092Z [cont-init.d] udev.sh: exited 0.
2021-01-01T17:17:03.070662407Z [cont-init.d] done.
2021-01-01T17:17:03.082371777Z [services.d] starting services
2021-01-01T17:17:03.135736657Z [services.d] done.
2021-01-01T17:32:21.033316193Z 2021-01-01 18:32:21 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-01T18:37:41.014007643Z 2021-01-01 19:37:41 ERROR (MainThread) [homeassistant.components.ipp] Error fetching ipp data: Invalid response from API: Timeout occurred while connecting to IPP server.
2021-01-01T21:45:01.029932500Z 2021-01-01 22:45:01 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-01T23:06:11.024431679Z 2021-01-02 00:06:11 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-02T00:30:11.045507153Z 2021-01-02 01:30:11 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T00:56:31.029335046Z 2021-01-02 01:56:31 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-02T02:05:11.029971910Z 2021-01-02 03:05:11 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-02T05:37:21.029490490Z 2021-01-02 06:37:21 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
2021-01-02T09:27:21.060426800Z 2021-01-02 10:27:21 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T10:17:51.045496599Z 2021-01-02 11:17:51 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T11:39:21.045324432Z 2021-01-02 12:39:21 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T11:44:51.044490793Z 2021-01-02 12:44:51 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T12:29:21.044176586Z 2021-01-02 13:29:21 ERROR (MainThread) [homeassistant.components.upnp] Error requesting Home&Life HUB data: Server disconnected
2021-01-02T12:50:01.029586559Z 2021-01-02 13:50:01 ERROR (MainThread) [homeassistant.components.upnp] Timeout fetching Home&Life HUB data
(TODAY WHEN I FOUND IT STOPPED)
2021-01-03T19:15:39.151360265Z [s6-init] making user provided files available at /var/run/s6/etc...exited 0.
2021-01-03T19:15:39.581200636Z [s6-init] ensuring user provided files have correct perms...exited 0.
2021-01-03T19:15:39.591553363Z [fix-attrs.d] applying ownership & permissions fixes...
2021-01-03T19:15:39.604655210Z [fix-attrs.d] done.
2021-01-03T19:15:39.614961635Z [cont-init.d] executing container initialization scripts...
2021-01-03T19:15:39.625191602Z [cont-init.d] udev.sh: executing...
2021-01-03T19:15:40.225454108Z starting version 3.2.9
2021-01-03T19:15:40.270411482Z [20:15:40] INFO: Update udev information
2021-01-03T19:15:40.470114400Z [cont-init.d] udev.sh: exited 0.
2021-01-03T19:15:40.474047740Z [cont-init.d] done.
2021-01-03T19:15:40.485745991Z [services.d] starting services
2021-01-03T19:15:40.544362869Z [services.d] done.
2021-01-03T19:16:02.799249804Z 2021-01-03 20:16:02 WARNING (Recorder) [homeassistant.components.recorder] Ended unfinished session (id=2 from 2021-01-01 17:17:22.130965)
2021-01-03T19:16:19.873342838Z 2021-01-03 20:16:19 WARNING (MainThread) [homeassistant.components.upnp] Wanted UPnP/IGD device with UDN/ST "uuid:85ddd332-73a0-4d65-8bc5-61638c81d024"/"urn:schemas-upnp-org:device:InternetGatewayDevice:1" not found, aborting
2021-01-03T19:16:19.877706648Z 2021-01-03 20:16:19 WARNING (MainThread) [homeassistant.config_entries] Config entry for upnp not ready yet. Retrying in 5 seconds
2021-01-03T19:16:29.107749758Z 2021-01-03 20:16:29 WARNING (MainThread) [homeassistant.components.http.ban] Login attempt or request with invalid authentication from DESKTOP-8M24269.home-life.hub (192.168.1.215) (Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.88 Safari/537.36)
2021-01-03T19:16:29.340886130Z 2021-01-03 20:16:29 WARNING (MainThread) [homeassistant.components.http.ban] Login attempt or request with invalid authentication from DESKTOP-8M24269.home-life.hub (192.168.1.215) (Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.88 Safari/537.36)
2021-01-03T19:16:34.881269797Z 2021-01-03 20:16:34 WARNING (MainThread) [homeassistant.components.upnp] Wanted UPnP/IGD device with UDN/ST "uuid:85ddd332-73a0-4d65-8bc5-61638c81d024"/"urn:schemas-upnp-org:device:InternetGatewayDevice:1" not found, aborting
2021-01-03T19:16:34.882907821Z 2021-01-03 20:16:34 WARNING (MainThread) [homeassistant.config_entries] Config entry for upnp not ready yet. Retrying in 10 seconds
2021-01-03T19:16:54.890895029Z 2021-01-03 20:16:54 WARNING (MainThread) [homeassistant.components.upnp] Wanted UPnP/IGD device with UDN/ST "uuid:85ddd332-73a0-4d65-8bc5-61638c81d024"/"urn:schemas-upnp-org:device:InternetGatewayDevice:1" not found, aborting
2021-01-03T19:16:54.901126142Z 2021-01-03 20:16:54 WARNING (MainThread) [homeassistant.config_entries] Config entry for upnp not ready yet. Retrying in 20 seconds
2021-01-03T19:17:24.899336862Z 2021-01-03 20:17:24 WARNING (MainThread) [homeassistant.components.upnp] Wanted UPnP/IGD device with UDN/ST "uuid:85ddd332-73a0-4d65-8bc5-61638c81d024"/"urn:schemas-upnp-org:device:InternetGatewayDevice:1" not found, aborting
2021-01-03T19:17:24.901137803Z 2021-01-03 20:17:24 WARNING (MainThread) [homeassistant.config_entries] Config entry for upnp not ready yet. Retrying in 40 seconds
2021-01-03T19:37:19.009704454Z 2021-01-03 20:37:19 ERROR (MainThread) [homeassistant.components.ipp] Error fetching ipp data: Invalid response from API: Timeout occurred while connecting to IPP server.
2021-01-03T19:39:27.009417522Z 2021-01-03 20:39:27 ERROR (MainThread) [homeassistant.components.ipp] Error fetching ipp data: Invalid response from API: Timeout occurred while connecting to IPP server.
Hope it can help
NB: Home&Life HUB is my router
jay_p
January 3, 2021, 8:26pm
6
HA shutdown the container here the first time after it was disconnected from the MTTQ server, and there’s nothing on the logs the second time it stopped… Someone more familiar with HA’s internals might be able to help you there.