Connection was closed errors #1307

airmaxx · 2020-12-28T14:13:10Z

airmaxx
Dec 28, 2020

My logs are full with connection was closed errors, thus the the recording does not work reliably. Start and end of trips are not reliably detected.

15:05:23.610 [info] Version: 1.20.1
15:05:25.734 [info] Refreshed api tokens
15:05:25.864 [info] Scheduling token refresh in 5 wk 5 d
15:05:25.869 [info] Running TeslaMateWeb.Endpoint with cowboy 2.8.0 at :::4000 (http)
15:05:25.880 [info] Access TeslaMateWeb.Endpoint at http://localhost
15:05:27.698 [info] Starting logger for 'Speedy Gonzales'
15:05:27.949 [info] MQTT connection has been established
15:05:29.605 car_id=2 [info] Start / :online
15:05:32.197 car_id=2 [info] Connecting ...
15:05:43.128 [info] Connection was closed (a:1|t:1|d:0). Reconnecting …
15:05:54.019 [info] Connection was closed (a:1|t:2|d:0). Reconnecting …
15:06:04.906 [info] Connection was closed (a:1|t:3|d:0). Reconnecting …
15:06:15.829 [info] Connection was closed (a:1|t:4|d:0). Reconnecting …
15:08:38.253 car_id=2 [info] Suspending logging
15:12:08.661 [warn] Too many disconnects …
15:12:08.661 car_id=2 [info] Creating new connection …
15:12:08.661 car_id=2 [info] Disconnecting ...
15:12:08.667 car_id=2 [info] Connecting ...
15:12:19.584 [info] Connection was closed (a:1|t:1|d:0). Reconnecting …
15:12:30.517 [info] Connection was closed (a:1|t:2|d:0). Reconnecting …
15:12:41.417 [info] Connection was closed (a:1|t:3|d:0). Reconnecting …
15:12:52.339 [info] Connection was closed (a:1|t:4|d:0). Reconnecting …
15:15:49.904 [info] GET /
15:15:49.927 [info] Sent 200 in 23ms

The errors here are just an excerpt, old logs where full of it for hours. Is this the Tesla API, the streaming API oder local DB?

tohojo · 2020-12-29T02:03:40Z

tohojo
Dec 29, 2020

I'm seeing a bunch of these as well, leading to missing drives.

Interestingly, Teslafi seems to have had no issue picking up the drives in the same time period; so the API can't be entirely offline?

0 replies

airmaxx · 2020-12-29T07:16:22Z

airmaxx
Dec 29, 2020
Author

I have found out the cause for this problem. It happens, if you have two loggers accessing the Tesla API. This was not a problem for a long time. I had two loggers running for 1.5 years. But now Tesla seems to have changed something.

Immediately after I turned off my second logger (Teslalogger) the error messages in Teslamate disappeared.

0 replies

tohojo · 2020-12-29T11:58:04Z

tohojo
Dec 29, 2020

airmaxx <notifications@github.com> writes:

I found out the cause for this problem. It happens, if you have two loggers accessing the Tesla API. This was not a problem for a long time. I had two loggers running for 1.5 years. But now Tesla seems to have changed something. Immediately after I turned off my second logger (Teslalogger) the error messages in Teslamate disappeared.

Ah, right! I guess I'll try turning off the Teslafi logger, then (was only keeping it around as verification, but guess that's counterproductive, then)...

0 replies

tohojo · 2020-12-29T16:29:10Z

tohojo
Dec 29, 2020

Nope, doesn't help; still getting a lot of errors (though it doesn't seem to have missed any drives today):

Dec 29 12:53:47 teslamate teslamate[434]: 2020-12-29 13:53:47.886 [info] Connection was closed (a:1|t:1|d:1). Reconnecting …
Dec 29 12:53:58 teslamate teslamate[434]: 2020-12-29 13:53:58.705 [info] Connection was closed (a:1|t:2|d:1). Reconnecting …
Dec 29 12:54:09 teslamate teslamate[434]: 2020-12-29 13:54:09.468 [info] Connection was closed (a:1|t:3|d:1). Reconnecting …
Dec 29 12:54:20 teslamate teslamate[434]: 2020-12-29 13:54:20.294 [info] Connection was closed (a:1|t:4|d:1). Reconnecting …
Dec 29 12:55:10 teslamate teslamate[434]: 2020-12-29 13:55:10.238 car_id=1 [info] Driving / Start
Dec 29 13:10:21 teslamate teslamate[434]: 2020-12-29 14:10:21.392 car_id=1 [info] Driving / Ended / 13 km – 15 min
Dec 29 13:10:21 teslamate teslamate[434]: 2020-12-29 14:10:21.393 car_id=1 [info] Start / :online
Dec 29 13:12:41 teslamate teslamate[434]: 2020-12-29 14:12:41.844 [info] Connection was closed (a:1|t:1|d:1). Reconnecting …
Dec 29 13:12:48 teslamate teslamate[434]: 2020-12-29 14:12:48.516 [error] TeslaApi.Error / 408 – %{
Dec 29 13:12:48 teslamate teslamate[434]:   "error" => "vehicle unavailable: {:error=>\"{\\\"error\\\": \\\"timeout\\\"}\"}",
Dec 29 13:12:48 teslamate teslamate[434]:   "error_description" => "",
Dec 29 13:12:48 teslamate teslamate[434]:   "response" => nil
Dec 29 13:12:48 teslamate teslamate[434]: }
Dec 29 13:12:48 teslamate teslamate[434]: 2020-12-29 14:12:48.682 car_id=1 [warn] Discarded incomplete fetch result
Dec 29 13:12:52 teslamate teslamate[434]: 2020-12-29 14:12:52.643 [info] Connection was closed (a:1|t:2|d:1). Reconnecting …
Dec 29 13:12:53 teslamate teslamate[434]: 2020-12-29 14:12:53.316 [info] control:hello – 0
Dec 29 13:13:03 teslamate teslamate[434]: 2020-12-29 14:13:03.470 [info] Connection was closed (a:1|t:3|d:1). Reconnecting …
Dec 29 13:13:04 teslamate teslamate[434]: 2020-12-29 14:13:04.095 [info] control:hello – 0
Dec 29 13:13:07 teslamate teslamate[434]: 2020-12-29 14:13:07.897 [error] TeslaApi.Error / 408 – %{
Dec 29 13:13:07 teslamate teslamate[434]:   "error" => "vehicle unavailable: {:error=>\"{\\\"error\\\": \\\"timeout\\\"}\"}",
Dec 29 13:13:07 teslamate teslamate[434]:   "error_description" => "",
Dec 29 13:13:07 teslamate teslamate[434]:   "response" => nil
Dec 29 13:13:07 teslamate teslamate[434]: }
Dec 29 13:13:08 teslamate teslamate[434]: 2020-12-29 14:13:08.074 car_id=1 [warn] Discarded incomplete fetch result
Dec 29 13:13:14 teslamate teslamate[434]: 2020-12-29 14:13:14.248 [info] Connection was closed (a:1|t:4|d:1). Reconnecting …
Dec 29 13:13:27 teslamate teslamate[434]: 2020-12-29 14:13:27.288 [error] TeslaApi.Error / 408 – %{
Dec 29 13:13:27 teslamate teslamate[434]:   "error" => "vehicle unavailable: {:error=>\"{\\\"error\\\": \\\"timeout\\\"}\"}",
Dec 29 13:13:27 teslamate teslamate[434]:   "error_description" => "",
Dec 29 13:13:27 teslamate teslamate[434]:   "response" => nil
Dec 29 13:13:27 teslamate teslamate[434]: }
Dec 29 13:13:27 teslamate teslamate[434]: 2020-12-29 14:13:27.621 car_id=1 [warn] Discarded incomplete fetch result
Dec 29 13:13:46 teslamate teslamate[434]: 2020-12-29 14:13:46.866 [error] TeslaApi.Error / 408 – %{
Dec 29 13:13:46 teslamate teslamate[434]:   "error" => "vehicle unavailable: {:error=>\"{\\\"error\\\": \\\"timeout\\\"}\"}",
Dec 29 13:13:46 teslamate teslamate[434]:   "error_description" => "",
Dec 29 13:13:46 teslamate teslamate[434]:   "response" => nil
Dec 29 13:13:46 teslamate teslamate[434]: }
Dec 29 13:13:47 teslamate teslamate[434]: 2020-12-29 14:13:47.061 car_id=1 [warn] Discarded incomplete fetch result
Dec 29 13:13:57 teslamate teslamate[434]: 2020-12-29 14:13:57.494 [error] TeslaApi.Error / 500 – %{
Dec 29 13:13:57 teslamate teslamate[434]:   "error" => "upstream internal error",
Dec 29 13:13:57 teslamate teslamate[434]:   "error_description" => "",
Dec 29 13:13:57 teslamate teslamate[434]:   "response" => nil
Dec 29 13:13:57 teslamate teslamate[434]: }
Dec 29 13:13:57 teslamate teslamate[434]: 2020-12-29 14:13:57.494 car_id=1 [error] Error / :unknown
Dec 29 13:14:17 teslamate teslamate[434]: 2020-12-29 14:14:17.925 [error] TeslaApi.Error / 500 – %{
Dec 29 13:14:17 teslamate teslamate[434]:   "error" => "upstream internal error",
Dec 29 13:14:17 teslamate teslamate[434]:   "error_description" => "",
Dec 29 13:14:17 teslamate teslamate[434]:   "response" => nil
Dec 29 13:14:17 teslamate teslamate[434]: }
Dec 29 13:14:17 teslamate teslamate[434]: 2020-12-29 14:14:17.925 car_id=1 [error] Error / :unknown
Dec 29 13:14:38 teslamate teslamate[434]: 2020-12-29 14:14:38.433 [error] TeslaApi.Error / 500 – %{
Dec 29 13:14:38 teslamate teslamate[434]:   "error" => "upstream internal error",
Dec 29 13:14:38 teslamate teslamate[434]:   "error_description" => "",
Dec 29 13:14:38 teslamate teslamate[434]:   "response" => nil
Dec 29 13:14:38 teslamate teslamate[434]: }
Dec 29 13:14:38 teslamate teslamate[434]: 2020-12-29 14:14:38.433 car_id=1 [error] Error / :unknown
Dec 29 13:14:59 teslamate teslamate[434]: 2020-12-29 14:14:59.417 car_id=1 [info] Suspending logging
Dec 29 13:15:47 teslamate teslamate[434]: 2020-12-29 14:15:47.030 [warn] Too many disconnects …
Dec 29 13:15:47 teslamate teslamate[434]: 2020-12-29 14:15:47.030 car_id=1 [info] Creating new connection …
Dec 29 13:15:47 teslamate teslamate[434]: 2020-12-29 14:15:47.030 car_id=1 [info] Disconnecting ...
Dec 29 13:15:47 teslamate teslamate[434]: 2020-12-29 14:15:47.035 car_id=1 [info] Connecting ...
Dec 29 13:15:57 teslamate teslamate[434]: 2020-12-29 14:15:57.852 [info] Connection was closed (a:1|t:1|d:0). Reconnecting …
Dec 29 13:16:08 teslamate teslamate[434]: 2020-12-29 14:16:08.669 [info] Connection was closed (a:1|t:2|d:0). Reconnecting …
Dec 29 13:16:19 teslamate teslamate[434]: 2020-12-29 14:16:19.470 [info] Connection was closed (a:1|t:3|d:0). Reconnecting …
Dec 29 13:16:30 teslamate teslamate[434]: 2020-12-29 14:16:30.238 [info] Connection was closed (a:1|t:4|d:0). Reconnecting …
Dec 29 13:22:19 teslamate teslamate[434]: 2020-12-29 14:22:19.932 [warn] Too many disconnects …
Dec 29 13:22:19 teslamate teslamate[434]: 2020-12-29 14:22:19.932 car_id=1 [info] Creating new connection …
Dec 29 13:22:19 teslamate teslamate[434]: 2020-12-29 14:22:19.932 car_id=1 [info] Disconnecting ...
Dec 29 13:22:19 teslamate teslamate[434]: 2020-12-29 14:22:19.938 car_id=1 [info] Connecting ...
Dec 29 13:22:30 teslamate teslamate[434]: 2020-12-29 14:22:30.753 [info] Connection was closed (a:1|t:1|d:0). Reconnecting …
Dec 29 13:22:41 teslamate teslamate[434]: 2020-12-29 14:22:41.575 [info] Connection was closed (a:1|t:2|d:0). Reconnecting …
Dec 29 13:22:52 teslamate teslamate[434]: 2020-12-29 14:22:52.346 [info] Connection was closed (a:1|t:3|d:0). Reconnecting …
Dec 29 13:23:03 teslamate teslamate[434]: 2020-12-29 14:23:03.113 [info] Connection was closed (a:1|t:4|d:0). Reconnecting …
Dec 29 13:27:35 teslamate teslamate[434]: 2020-12-29 14:27:35.925 [info] Connection was closed (a:1|t:5|d:8). Reconnecting …
Dec 29 13:28:06 teslamate teslamate[434]: 2020-12-29 14:28:06.573 [info] Stream.Timeout / 5
Dec 29 13:28:06 teslamate teslamate[434]: 2020-12-29 14:28:06.725 [info] Connection was closed (a:1|t:6|d:8). Reconnecting …
Dec 29 13:28:37 teslamate teslamate[434]: 2020-12-29 14:28:37.407 [info] Stream.Timeout / 6
Dec 29 13:28:37 teslamate teslamate[434]: 2020-12-29 14:28:37.563 [info] Connection was closed (a:1|t:7|d:8). Reconnecting …
Dec 29 13:28:38 teslamate teslamate[434]: 2020-12-29 14:28:38.378 [error] Vehicle Error: Vehicle is offline
Dec 29 13:29:08 teslamate teslamate[434]: 2020-12-29 14:29:08.379 [info] Stream.Timeout / 7
Dec 29 13:29:08 teslamate teslamate[434]: 2020-12-29 14:29:08.531 [info] Connection was closed (a:1|t:8|d:8). Reconnecting …
Dec 29 13:29:09 teslamate teslamate[434]: 2020-12-29 14:29:09.402 [error] Vehicle Error: Vehicle is offline
Dec 29 13:29:39 teslamate teslamate[434]: 2020-12-29 14:29:39.403 [info] Stream.Timeout / 8
Dec 29 13:29:39 teslamate teslamate[434]: 2020-12-29 14:29:39.557 [info] Connection was closed (a:1|t:9|d:8). Reconnecting …
Dec 29 13:29:40 teslamate teslamate[434]: 2020-12-29 14:29:40.403 [error] Vehicle Error: Vehicle is offline
Dec 29 13:30:10 teslamate teslamate[434]: 2020-12-29 14:30:10.403 [info] Stream.Timeout / 9
Dec 29 13:30:10 teslamate teslamate[434]: 2020-12-29 14:30:10.553 [info] Connection was closed (a:1|t:10|d:8). Reconnecting …
Dec 29 13:30:11 teslamate teslamate[434]: 2020-12-29 14:30:11.230 [info] control:hello – 0
Dec 29 13:30:11 teslamate teslamate[434]: 2020-12-29 14:30:11.429 [error] Vehicle Error: Vehicle is offline
Dec 29 13:30:41 teslamate teslamate[434]: 2020-12-29 14:30:41.430 [info] Stream.Timeout / 10

0 replies

airmaxx · 2020-12-29T16:41:32Z

airmaxx
Dec 29, 2020
Author

Unfortunately, this happened to me again today. Even two trips were not recorded.

So it was just a coincidence that the error was gone after the second logger was switched offline.

0 replies

DrMichael · 2020-12-29T17:01:29Z

DrMichael
Dec 29, 2020
Maintainer

I usually run three TM instances in parallel with only occasionally losing a trip. (That is the reason, why three...)

But I also see tons of reconnections.

0 replies

airmaxx · 2020-12-29T17:27:46Z

airmaxx
Dec 29, 2020
Author

Problem seems to be discusses also here: #988

I'm now trying out using a second driver account for Teslamate. I could expierence the Teslamate disconnect, when I opend the Tesla app.

0 replies

trulow · 2020-12-30T23:32:54Z

trulow
Dec 30, 2020

Problem seems to be discusses also here: #988

I'm now trying out using a second driver account for Teslamate. I could expierence the Teslamate disconnect, when I opend the Tesla app.

I'm also trying a second account but I'm still having the same issues.

EDIT: Added logs

teslamate_1  | 2021-01-03 11:21:22.216 [info] Stream.Timeout / 7
teslamate_1  | 2021-01-03 11:21:22.260 [info] Connection was closed (a:1|t:8|d:2). Reconnecting …
teslamate_1  | 2021-01-03 11:21:52.530 [info] Stream.Timeout / 8
teslamate_1  | 2021-01-03 11:21:52.578 [info] Connection was closed (a:1|t:9|d:2). Reconnecting …
teslamate_1  | 2021-01-03 11:22:22.766 [info] Stream.Timeout / 9
teslamate_1  | 2021-01-03 11:22:22.789 [info] Connection was closed (a:1|t:10|d:2). Reconnecting …
teslamate_1  | 2021-01-03 11:22:53.024 [info] Stream.Timeout / 10
teslamate_1  | 2021-01-03 11:22:53.048 [info] Connection was closed (a:1|t:11|d:2). Reconnecting …
teslamate_1  | 2021-01-03 11:23:48.871 [info] Stream.Timeout / 11
teslamate_1  | 2021-01-03 11:23:48.916 [info] Connection was closed (a:1|t:12|d:3). Reconnecting …
teslamate_1  | 2021-01-03 11:24:45.135 [info] Stream.Timeout / 12
teslamate_1  | 2021-01-03 11:24:45.179 [info] Connection was closed (a:1|t:13|d:4). Reconnecting …
teslamate_1  | 2021-01-03 11:24:45.386 [info] control:hello – 0
teslamate_1  | 2021-01-03 11:25:15.387 [info] Stream.Timeout / 13
teslamate_1  | 2021-01-03 11:25:15.430 [info] Connection was closed (a:1|t:14|d:4). Reconnecting …
teslamate_1  | 2021-01-03 11:26:12.502 [info] Stream.Timeout / 14
teslamate_1  | 2021-01-03 11:26:12.545 [info] Connection was closed (a:1|t:15|d:5). Reconnecting …
teslamate_1  | 2021-01-03 11:27:23.379 [info] Stream.Timeout / 15
teslamate_1  | 2021-01-03 11:27:23.425 [info] Connection was closed (a:1|t:16|d:6). Reconnecting …
teslamate_1  | 2021-01-03 11:28:34.876 [info] Stream.Timeout / 16
teslamate_1  | 2021-01-03 11:28:34.922 [info] Connection was closed (a:1|t:17|d:7). Reconnecting …
teslamate_1  | 2021-01-03 11:29:45.927 [info] Stream.Timeout / 17
teslamate_1  | 2021-01-03 11:29:45.973 [info] Connection was closed (a:1|t:18|d:8). Reconnecting …
teslamate_1  | 2021-01-03 11:30:57.275 [info] Stream.Timeout / 18
teslamate_1  | 2021-01-03 11:30:57.321 [info] Connection was closed (a:1|t:19|d:9). Reconnecting …

0 replies

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Connection was closed errors #1307

{{title}}

{{editor}}'s edit

{{editor}}'s edit

Replies: 8 comments

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

Select a reply

Connection was closed errors #1307

airmaxx Dec 28, 2020

Replies: 8 comments

tohojo Dec 29, 2020

airmaxx Dec 29, 2020 Author

tohojo Dec 29, 2020

tohojo Dec 29, 2020

airmaxx Dec 29, 2020 Author

DrMichael Dec 29, 2020 Maintainer

airmaxx Dec 29, 2020 Author

trulow Dec 30, 2020

airmaxx
Dec 28, 2020

tohojo
Dec 29, 2020

airmaxx
Dec 29, 2020
Author

tohojo
Dec 29, 2020

tohojo
Dec 29, 2020

airmaxx
Dec 29, 2020
Author

DrMichael
Dec 29, 2020
Maintainer

airmaxx
Dec 29, 2020
Author

trulow
Dec 30, 2020