search

teslamate-org / teslamate

A self-hosted data logger for your Tesla 🚘
https://docs.teslamate.org
MIT License
5.94k stars 740 forks source link

Drives not getting logged #2745

Closed natrlhy closed 2 years ago

natrlhy commented 2 years ago

Is there an existing issue for this?

What happened?

I moved to the :edge release a few weeks back. I'm not seeing drives get logged

Expected Behavior

Current Drives should show up in the Drives Grafana Dashboard

Steps To Reproduce

  1. Click on Drives
  2. Observe recent drive
  3. Drives not showing up

Relevant log output

root@teslamate:~/docker# docker-compose logs teslamate | grep car_id=1
teslamate_1  | 2022-07-12 22:21:37.305 car_id=1 [info] Start / :asleep
teslamate_1  | 2022-07-13 08:18:33.672 car_id=1 [info] Start / :online
teslamate_1  | 2022-07-13 08:18:34.016 car_id=1 [info] Connecting ...
teslamate_1  | 2022-07-13 08:18:49.582 car_id=1 [info] Driving / Start
teslamate_1  | 2022-07-13 09:04:24.810 car_id=1 [info] Start / :online
teslamate_1  | 2022-07-13 09:04:25.044 car_id=1 [info] Connecting ...
teslamate_1  | 2022-07-13 09:06:36.966 car_id=1 [info] Start / :asleep
teslamate_1  | 2022-07-13 09:06:37.021 car_id=1 [info] Disconnecting ...
teslamate_1  | 2022-07-13 09:12:48.914 car_id=1 [info] Start / :online
teslamate_1  | 2022-07-13 09:12:48.979 car_id=1 [info] Connecting ...
teslamate_1  | 2022-07-13 09:12:49.392 car_id=1 [info] Charging / SOC: 18% / Work
teslamate_1  | 2022-07-13 09:12:49.393 car_id=1 [info] Disconnecting ...

Screenshots

No response

Additional data

drives.csv positions.csv

Type of installation

Docker

Version

1.27.0-dev

natrlhy commented 2 years ago

Moved from edge to latest and still no past or recent drives. I can see the map always knows where the car is when driving

natrlhy commented 2 years ago

Some history to what I've done in the past month:

Changed to :edge branch (Encryption keys were new to me) upgraded to postgres 14 (followed steps here) changed grafana to :edge

As of today I've gone back to the :latest for teslamate and grafana now

Could ANY of this case this issue where drives aren't logged? What should I be looking for in the respective containers?

natrlhy commented 2 years ago

In case here's my yml file:


version: '3'
services:
  teslamate:
    image: teslamate/teslamate:latest
    restart: always
    environment:
      - ENCRYPTION_KEY=long key here with upper/lower case letters and numbers (no special chars as it seemed like it didn't like that)
      - DATABASE_USER=teslamate
      - DATABASE_PASS=passhere
      - DATABASE_NAME=teslamate
      - DATABASE_HOST=db
      - MQTT_HOST=mosquitto
      - TZ=America/Los_Angeles       # (optional) replace to use local time in debug logs. See "Configuration".
      - VIRTUAL_HOST=10.10.10.8 # if you're going to access the UI from another  machine replace
                               # "localhost" with the hostname / IP address of the docker host.
    ports:
      - 4000:4000
    cap_drop:
      - all

  db:
    image: postgres:14
    restart: always
    environment:
      - POSTGRES_USER=teslamate
      - POSTGRES_PASSWORD=passhere
      - POSTGRES_DB=teslamate
    volumes:
      - teslamate-db:/var/lib/postgresql/data

  grafana:
    image: teslamate/grafana:latest
    restart: always
    environment:
      - DATABASE_USER=teslamate
      - DATABASE_PASS=passhere
      - DATABASE_NAME=teslamate
      - DATABASE_HOST=db
    ports:
      - 3000:3000
    volumes:
      - teslamate-grafana-data:/var/lib/grafana

  mosquitto:
    image: eclipse-mosquitto:2
    restart: always
    ports:
      - 1883:1883
    volumes:
      - mosquitto-conf:/mosquitto/config
      - mosquitto-data:/mosquitto/data

volumes:
    teslamate-db:
    teslamate-grafana-data:
    mosquitto-conf:
    mosquitto-data:
natrlhy commented 2 years ago 

root@teslamate:~/docker# docker-compose logs --tail 100 mosquitto
Attaching to docker_mosquitto_1
mosquitto_1  | 1657894397: mosquitto version 2.0.14 starting
mosquitto_1  | 1657894397: Config loaded from /mosquitto/config/mosquitto.conf.
mosquitto_1  | 1657894397: Starting in local only mode. Connections will only be possible from clients running on this machine.
mosquitto_1  | 1657894397: Create a configuration file which defines a listener to allow remote access.
mosquitto_1  | 1657894397: For more details see https://mosquitto.org/documentation/authentication-methods/
mosquitto_1  | 1657894397: Opening ipv4 listen socket on port 1883.
mosquitto_1  | 1657894397: Opening ipv6 listen socket on port 1883.
mosquitto_1  | 1657894397: Error: Address not available
mosquitto_1  | 1657894397: mosquitto version 2.0.14 running
mosquitto_1  | 1657894923: mosquitto version 2.0.14 terminating
mosquitto_1  | 1657894971: mosquitto version 2.0.14 starting
mosquitto_1  | 1657894971: Config loaded from /mosquitto/config/mosquitto.conf.
mosquitto_1  | 1657894971: Starting in local only mode. Connections will only be possible from clients running on this machine.
mosquitto_1  | 1657894971: Create a configuration file which defines a listener to allow remote access.
mosquitto_1  | 1657894971: For more details see https://mosquitto.org/documentation/authentication-methods/
mosquitto_1  | 1657894971: Opening ipv4 listen socket on port 1883.
mosquitto_1  | 1657894971: Opening ipv6 listen socket on port 1883.
mosquitto_1  | 1657894971: Error: Address not available
mosquitto_1  | 1657894971: mosquitto version 2.0.14 running
natrlhy commented 2 years ago

So I checked the default docker-compose.yml from the teslamate docs and saw this was missing from my mosquitto config area:

command: mosquitto -c /mosquitto-no-auth.conf Restarted the mosquitto container:


root@teslamate:~/docker# docker-compose restart mosquitto
Restarting docker_mosquitto_1 ... done
root@teslamate:~/docker# docker-compose logs --tail 100 mosquitto
Attaching to docker_mosquitto_1
mosquitto_1  | 1657933191: mosquitto version 2.0.14 starting
mosquitto_1  | 1657933191: Config loaded from /mosquitto-no-auth.conf.
mosquitto_1  | 1657933191: Opening ipv4 listen socket on port 1883.
mosquitto_1  | 1657933191: Opening ipv6 listen socket on port 1883.
mosquitto_1  | 1657933191: mosquitto version 2.0.14 running
mosquitto_1  | 1657933196: New connection from 172.18.0.2:60926 on port 1883.
mosquitto_1  | 1657933196: New client connected from 172.18.0.2:60926 as TESLAMATE_302E3133313 (p2, c1, k60).
mosquitto_1  | 1657933199: mosquitto version 2.0.14 terminating
mosquitto_1  | 1657933203: mosquitto version 2.0.14 starting
mosquitto_1  | 1657933203: Config loaded from /mosquitto-no-auth.conf.
mosquitto_1  | 1657933203: Opening ipv4 listen socket on port 1883.
mosquitto_1  | 1657933203: Opening ipv6 listen socket on port 1883.
mosquitto_1  | 1657933203: mosquitto version 2.0.14 running
mosquitto_1  | 1657933203: New connection from 172.18.0.2:60932 on port 1883.
mosquitto_1  | 1657933203: New client connected from 172.18.0.2:60932 as TESLAMATE_302E3133313 (p2, c0, k60).
mosquitto_1  | 1657933203: Client TESLAMATE_302E3133313 closed its connection.
natrlhy commented 2 years ago

db logs:

db_1         | PostgreSQL Database directory appears to contain a database; Skipping initialization
db_1         |
db_1         | 2022-07-15 14:22:52.526 UTC [1] LOG:  starting PostgreSQL 14.4 (Debian 14.4-1.pgdg110+1) on arm-unknown-linux-gnueabihf, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 32-bit
db_1         | 2022-07-15 14:22:52.530 UTC [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
db_1         | 2022-07-15 14:22:52.531 UTC [1] LOG:  listening on IPv6 address "::", port 5432
db_1         | 2022-07-15 14:22:52.555 UTC [1] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
db_1         | 2022-07-15 14:22:52.654 UTC [26] LOG:  database system shutdown was interrupted; last known up at 2022-07-15 14:22:05 UTC
db_1         | 2022-07-15 14:22:52.952 UTC [26] LOG:  database system was not properly shut down; automatic recovery in progress
db_1         | 2022-07-15 14:22:52.972 UTC [26] LOG:  redo starts at 0/37EF5D58
db_1         | 2022-07-15 14:22:52.981 UTC [26] LOG:  invalid record length at 0/37EFCBC8: wanted 24, got 0
db_1         | 2022-07-15 14:22:52.981 UTC [26] LOG:  redo done at 0/37EFCB90 system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
db_1         | 2022-07-15 14:22:53.143 UTC [1] LOG:  database system is ready to accept connections
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | 2022-07-15 23:02:28.558 UTC [30] LOG:  using stale statistics instead of current ones because stats collector is not responding
db_1         | 2022-07-16 00:23:37.675 UTC [36] ERROR:  canceling statement due to user request
db_1         | 2022-07-16 00:23:37.675 UTC [36] STATEMENT:  SELECT s0."count", s0."start_position_id", s0."end_position_id", s0."outside_temp_avg", s0."inside_temp_avg", s0."speed_max", s0."power_max", s0."power_min", s0."start_date", s0."end_date", s0."start_km", s0."end_km", s0."distance", s0."duration_min", s0."start_ideal_range_km", s0."end_ideal_range_km", s0."start_rated_range_km", s0."end_rated_range_km", s1."start_ideal_range_km", s1."end_ideal_range_km", s1."start_rated_range_km", s1."end_rated_range_km" FROM (SELECT count(*) OVER "w" AS "count", first_value(sp0."id") OVER "w" AS "start_position_id", last_value(sp0."id") OVER "w" AS "end_position_id", avg(sp0."outside_temp") OVER "w" AS "outside_temp_avg", avg(sp0."inside_temp") OVER "w" AS "inside_temp_avg", max(sp0."speed") OVER "w" AS "speed_max", max(sp0."power") OVER "w" AS "power_max", min(sp0."power") OVER "w" AS "power_min", first_value(sp0."date") OVER "w" AS "start_date", last_value(sp0."date") OVER "w" AS "end_date", first_value(sp0."odometer") OVER "w" AS "start_km", last_value(sp0."odometer") OVER "w" AS "end_km", last_value(sp0."odometer") OVER "w" - first_value(sp0."odometer") OVER "w" AS "distance", round(extract(epoch from (last_value(sp0."date") OVER "w" - first_value(sp0."date") OVER "w")) / 60)::integer AS "duration_min", -1 AS "start_ideal_range_km", -1 AS "end_ideal_range_km", -1 AS "start_rated_range_km", -1 AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (sp0."drive_id" = $1) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s0 INNER JOIN (SELECT first_value(sp0."ideal_battery_range_km") OVER "w" AS "start_ideal_range_km", last_value(sp0."ideal_battery_range_km") OVER "w" AS "end_ideal_range_km", first_value(sp0."rated_battery_range_km") OVER "w" AS "start_rated_range_km", last_value(sp0."rated_battery_range_km") OVER "w" AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (((sp0."drive_id" = $2) AND NOT (sp0."ideal_battery_range_km" IS NULL)) AND NOT (sp0."odometer" IS NULL)) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s1 ON TRUE
db_1         | 2022-07-16 00:23:37.787 UTC [36] LOG:  could not send data to client: Broken pipe
db_1         | 2022-07-16 00:23:37.788 UTC [36] FATAL:  connection to client lost
db_1         | 2022-07-16 01:05:19.890 UTC [37] ERROR:  canceling statement due to user request
db_1         | 2022-07-16 01:05:19.890 UTC [37] STATEMENT:  SELECT s0."count", s0."start_position_id", s0."end_position_id", s0."outside_temp_avg", s0."inside_temp_avg", s0."speed_max", s0."power_max", s0."power_min", s0."start_date", s0."end_date", s0."start_km", s0."end_km", s0."distance", s0."duration_min", s0."start_ideal_range_km", s0."end_ideal_range_km", s0."start_rated_range_km", s0."end_rated_range_km", s1."start_ideal_range_km", s1."end_ideal_range_km", s1."start_rated_range_km", s1."end_rated_range_km" FROM (SELECT count(*) OVER "w" AS "count", first_value(sp0."id") OVER "w" AS "start_position_id", last_value(sp0."id") OVER "w" AS "end_position_id", avg(sp0."outside_temp") OVER "w" AS "outside_temp_avg", avg(sp0."inside_temp") OVER "w" AS "inside_temp_avg", max(sp0."speed") OVER "w" AS "speed_max", max(sp0."power") OVER "w" AS "power_max", min(sp0."power") OVER "w" AS "power_min", first_value(sp0."date") OVER "w" AS "start_date", last_value(sp0."date") OVER "w" AS "end_date", first_value(sp0."odometer") OVER "w" AS "start_km", last_value(sp0."odometer") OVER "w" AS "end_km", last_value(sp0."odometer") OVER "w" - first_value(sp0."odometer") OVER "w" AS "distance", round(extract(epoch from (last_value(sp0."date") OVER "w" - first_value(sp0."date") OVER "w")) / 60)::integer AS "duration_min", -1 AS "start_ideal_range_km", -1 AS "end_ideal_range_km", -1 AS "start_rated_range_km", -1 AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (sp0."drive_id" = $1) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s0 INNER JOIN (SELECT first_value(sp0."ideal_battery_range_km") OVER "w" AS "start_ideal_range_km", last_value(sp0."ideal_battery_range_km") OVER "w" AS "end_ideal_range_km", first_value(sp0."rated_battery_range_km") OVER "w" AS "start_rated_range_km", last_value(sp0."rated_battery_range_km") OVER "w" AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (((sp0."drive_id" = $2) AND NOT (sp0."ideal_battery_range_km" IS NULL)) AND NOT (sp0."odometer" IS NULL)) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s1 ON TRUE
db_1         | 2022-07-16 01:06:59.816 UTC [665] ERROR:  canceling statement due to user request
db_1         | 2022-07-16 01:06:59.816 UTC [665] STATEMENT:  SELECT s0."count", s0."start_position_id", s0."end_position_id", s0."outside_temp_avg", s0."inside_temp_avg", s0."speed_max", s0."power_max", s0."power_min", s0."start_date", s0."end_date", s0."start_km", s0."end_km", s0."distance", s0."duration_min", s0."start_ideal_range_km", s0."end_ideal_range_km", s0."start_rated_range_km", s0."end_rated_range_km", s1."start_ideal_range_km", s1."end_ideal_range_km", s1."start_rated_range_km", s1."end_rated_range_km" FROM (SELECT count(*) OVER "w" AS "count", first_value(sp0."id") OVER "w" AS "start_position_id", last_value(sp0."id") OVER "w" AS "end_position_id", avg(sp0."outside_temp") OVER "w" AS "outside_temp_avg", avg(sp0."inside_temp") OVER "w" AS "inside_temp_avg", max(sp0."speed") OVER "w" AS "speed_max", max(sp0."power") OVER "w" AS "power_max", min(sp0."power") OVER "w" AS "power_min", first_value(sp0."date") OVER "w" AS "start_date", last_value(sp0."date") OVER "w" AS "end_date", first_value(sp0."odometer") OVER "w" AS "start_km", last_value(sp0."odometer") OVER "w" AS "end_km", last_value(sp0."odometer") OVER "w" - first_value(sp0."odometer") OVER "w" AS "distance", round(extract(epoch from (last_value(sp0."date") OVER "w" - first_value(sp0."date") OVER "w")) / 60)::integer AS "duration_min", -1 AS "start_ideal_range_km", -1 AS "end_ideal_range_km", -1 AS "start_rated_range_km", -1 AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (sp0."drive_id" = $1) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s0 INNER JOIN (SELECT first_value(sp0."ideal_battery_range_km") OVER "w" AS "start_ideal_range_km", last_value(sp0."ideal_battery_range_km") OVER "w" AS "end_ideal_range_km", first_value(sp0."rated_battery_range_km") OVER "w" AS "start_rated_range_km", last_value(sp0."rated_battery_range_km") OVER "w" AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (((sp0."drive_id" = $2) AND NOT (sp0."ideal_battery_range_km" IS NULL)) AND NOT (sp0."odometer" IS NULL)) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s1 ON TRUE
db_1         | 2022-07-16 01:08:20.156 UTC [41] ERROR:  canceling statement due to user request
db_1         | 2022-07-16 01:08:20.156 UTC [41] STATEMENT:  SELECT s0."count", s0."start_position_id", s0."end_position_id", s0."outside_temp_avg", s0."inside_temp_avg", s0."speed_max", s0."power_max", s0."power_min", s0."start_date", s0."end_date", s0."start_km", s0."end_km", s0."distance", s0."duration_min", s0."start_ideal_range_km", s0."end_ideal_range_km", s0."start_rated_range_km", s0."end_rated_range_km", s1."start_ideal_range_km", s1."end_ideal_range_km", s1."start_rated_range_km", s1."end_rated_range_km" FROM (SELECT count(*) OVER "w" AS "count", first_value(sp0."id") OVER "w" AS "start_position_id", last_value(sp0."id") OVER "w" AS "end_position_id", avg(sp0."outside_temp") OVER "w" AS "outside_temp_avg", avg(sp0."inside_temp") OVER "w" AS "inside_temp_avg", max(sp0."speed") OVER "w" AS "speed_max", max(sp0."power") OVER "w" AS "power_max", min(sp0."power") OVER "w" AS "power_min", first_value(sp0."date") OVER "w" AS "start_date", last_value(sp0."date") OVER "w" AS "end_date", first_value(sp0."odometer") OVER "w" AS "start_km", last_value(sp0."odometer") OVER "w" AS "end_km", last_value(sp0."odometer") OVER "w" - first_value(sp0."odometer") OVER "w" AS "distance", round(extract(epoch from (last_value(sp0."date") OVER "w" - first_value(sp0."date") OVER "w")) / 60)::integer AS "duration_min", -1 AS "start_ideal_range_km", -1 AS "end_ideal_range_km", -1 AS "start_rated_range_km", -1 AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (sp0."drive_id" = $1) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s0 INNER JOIN (SELECT first_value(sp0."ideal_battery_range_km") OVER "w" AS "start_ideal_range_km", last_value(sp0."ideal_battery_range_km") OVER "w" AS "end_ideal_range_km", first_value(sp0."rated_battery_range_km") OVER "w" AS "start_rated_range_km", last_value(sp0."rated_battery_range_km") OVER "w" AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (((sp0."drive_id" = $2) AND NOT (sp0."ideal_battery_range_km" IS NULL)) AND NOT (sp0."odometer" IS NULL)) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s1 ON TRUE
db_1         | 2022-07-16 01:09:50.875 UTC [715] ERROR:  canceling statement due to user request
db_1         | 2022-07-16 01:09:50.875 UTC [715] STATEMENT:  SELECT s0."count", s0."start_position_id", s0."end_position_id", s0."outside_temp_avg", s0."inside_temp_avg", s0."speed_max", s0."power_max", s0."power_min", s0."start_date", s0."end_date", s0."start_km", s0."end_km", s0."distance", s0."duration_min", s0."start_ideal_range_km", s0."end_ideal_range_km", s0."start_rated_range_km", s0."end_rated_range_km", s1."start_ideal_range_km", s1."end_ideal_range_km", s1."start_rated_range_km", s1."end_rated_range_km" FROM (SELECT count(*) OVER "w" AS "count", first_value(sp0."id") OVER "w" AS "start_position_id", last_value(sp0."id") OVER "w" AS "end_position_id", avg(sp0."outside_temp") OVER "w" AS "outside_temp_avg", avg(sp0."inside_temp") OVER "w" AS "inside_temp_avg", max(sp0."speed") OVER "w" AS "speed_max", max(sp0."power") OVER "w" AS "power_max", min(sp0."power") OVER "w" AS "power_min", first_value(sp0."date") OVER "w" AS "start_date", last_value(sp0."date") OVER "w" AS "end_date", first_value(sp0."odometer") OVER "w" AS "start_km", last_value(sp0."odometer") OVER "w" AS "end_km", last_value(sp0."odometer") OVER "w" - first_value(sp0."odometer") OVER "w" AS "distance", round(extract(epoch from (last_value(sp0."date") OVER "w" - first_value(sp0."date") OVER "w")) / 60)::integer AS "duration_min", -1 AS "start_ideal_range_km", -1 AS "end_ideal_range_km", -1 AS "start_rated_range_km", -1 AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (sp0."drive_id" = $1) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s0 INNER JOIN (SELECT first_value(sp0."ideal_battery_range_km") OVER "w" AS "start_ideal_range_km", last_value(sp0."ideal_battery_range_km") OVER "w" AS "end_ideal_range_km", first_value(sp0."rated_battery_range_km") OVER "w" AS "start_rated_range_km", last_value(sp0."rated_battery_range_km") OVER "w" AS "end_rated_range_km" FROM "positions" AS sp0 WHERE (((sp0."drive_id" = $2) AND NOT (sp0."ideal_battery_range_km" IS NULL)) AND NOT (sp0."odometer" IS NULL)) WINDOW "w" AS (ORDER BY sp0."date" RANGE BETWEEN UNBOUNDED PRECEDING AND UNBOUNDED FOLLOWING) LIMIT 1) AS s1 ON TRUE
db_1         | 2022-07-16 01:12:48.716 UTC [1] LOG:  received fast shutdown request
db_1         | 2022-07-16 01:12:49.072 UTC [1] LOG:  aborting any active transactions
db_1         | 2022-07-16 01:12:49.073 UTC [662] FATAL:  terminating connection due to administrator command
db_1         | 2022-07-16 01:12:49.073 UTC [659] FATAL:  terminating connection due to administrator command
db_1         | 2022-07-16 01:12:49.120 UTC [1] LOG:  background worker "logical replication launcher" (PID 32) exited with exit code 1
db_1         | 2022-07-16 01:12:49.124 UTC [27] LOG:  shutting down
db_1         | 2022-07-16 01:12:50.825 UTC [1] LOG:  database system is shut down
db_1         |
db_1         | PostgreSQL Database directory appears to contain a database; Skipping initialization
db_1         |
db_1         | 2022-07-16 01:14:13.794 UTC [1] LOG:  starting PostgreSQL 14.4 (Debian 14.4-1.pgdg110+1) on arm-unknown-linux-gnueabihf, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 32-bit
db_1         | 2022-07-16 01:14:13.799 UTC [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
db_1         | 2022-07-16 01:14:13.800 UTC [1] LOG:  listening on IPv6 address "::", port 5432
db_1         | 2022-07-16 01:14:13.820 UTC [1] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
db_1         | 2022-07-16 01:14:13.872 UTC [28] LOG:  database system was shut down at 2022-07-16 01:12:50 UTC
db_1         | 2022-07-16 01:14:14.035 UTC [1] LOG:  database system is ready to accept connections
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | 2022-07-16 01:16:16.806 UTC [45] ERROR:  canceling statement due to user request
db_1         | 2022-07-16 01:16:16.806 UTC [45] STATEMENT:  SELECT p0."id", p0."date", p0."latitude", p0."longitude", p0."elevation", p0."speed", p0."power", p0."odometer", p0."ideal_battery_range_km", p0."est_battery_range_km", p0."rated_battery_range_km", p0."battery_level", p0."usable_battery_level", p0."battery_heater", p0."battery_heater_on", p0."battery_heater_no_power", p0."outside_temp", p0."inside_temp", p0."fan_status", p0."driver_temp_setting", p0."passenger_temp_setting", p0."is_climate_on", p0."is_rear_defroster_on", p0."is_front_defroster_on", p0."tpms_pressure_fl", p0."tpms_pressure_fr", p0."tpms_pressure_rl", p0."tpms_pressure_rr", p0."car_id", p0."drive_id" FROM "positions" AS p0 WHERE (((p0."id" > $1) AND (p0."elevation" IS NULL)) AND p0."drive_id" = ANY($2)) ORDER BY p0."id" LIMIT $3
db_1         | 2022-07-16 01:16:16.811 UTC [51] FATAL:  terminating background worker "parallel worker" due to administrator command
db_1         | 2022-07-16 01:16:16.811 UTC [51] STATEMENT:  SELECT p0."id", p0."date", p0."latitude", p0."longitude", p0."elevation", p0."speed", p0."power", p0."odometer", p0."ideal_battery_range_km", p0."est_battery_range_km", p0."rated_battery_range_km", p0."battery_level", p0."usable_battery_level", p0."battery_heater", p0."battery_heater_on", p0."battery_heater_no_power", p0."outside_temp", p0."inside_temp", p0."fan_status", p0."driver_temp_setting", p0."passenger_temp_setting", p0."is_climate_on", p0."is_rear_defroster_on", p0."is_front_defroster_on", p0."tpms_pressure_fl", p0."tpms_pressure_fr", p0."tpms_pressure_rl", p0."tpms_pressure_rr", p0."car_id", p0."drive_id" FROM "positions" AS p0 WHERE (((p0."id" > $1) AND (p0."elevation" IS NULL)) AND p0."drive_id" = ANY($2)) ORDER BY p0."id" LIMIT $3
db_1         | 2022-07-16 01:16:16.813 UTC [52] FATAL:  terminating background worker "parallel worker" due to administrator command
db_1         | 2022-07-16 01:16:16.813 UTC [52] STATEMENT:  SELECT p0."id", p0."date", p0."latitude", p0."longitude", p0."elevation", p0."speed", p0."power", p0."odometer", p0."ideal_battery_range_km", p0."est_battery_range_km", p0."rated_battery_range_km", p0."battery_level", p0."usable_battery_level", p0."battery_heater", p0."battery_heater_on", p0."battery_heater_no_power", p0."outside_temp", p0."inside_temp", p0."fan_status", p0."driver_temp_setting", p0."passenger_temp_setting", p0."is_climate_on", p0."is_rear_defroster_on", p0."is_front_defroster_on", p0."tpms_pressure_fl", p0."tpms_pressure_fr", p0."tpms_pressure_rl", p0."tpms_pressure_rr", p0."car_id", p0."drive_id" FROM "positions" AS p0 WHERE (((p0."id" > $1) AND (p0."elevation" IS NULL)) AND p0."drive_id" = ANY($2)) ORDER BY p0."id" LIMIT $3
db_1         | 2022-07-16 01:16:26.278 UTC [1] LOG:  background worker "parallel worker" (PID 51) exited with exit code 1
db_1         | 2022-07-16 01:16:26.333 UTC [1] LOG:  background worker "parallel worker" (PID 52) exited with exit code 1
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf
db_1         | ORC error: No callback manager available for armv7-unknown-linux-gnueabihf

teslamate logs:


teslamate_1  | waiting for postgres at db:5432
teslamate_1  | 2022-07-15 18:14:24.469 [info] Migrations already up
teslamate_1  | 2022-07-15 18:14:37.636 [info] System Info: Erlang/OTP 24 (emu)
teslamate_1  | 2022-07-15 18:14:37.637 [info] Version: 1.27.0
teslamate_1  | 2022-07-15 18:14:39.530 [info] POST https://auth.tesla.com/oauth2/v3/token -> 200 (792.698 ms)
teslamate_1  | 2022-07-15 18:14:39.531 [info] Refreshed api tokens
teslamate_1  | 2022-07-15 18:14:39.571 [info] Scheduling token refresh in 6 h
teslamate_1  | 2022-07-15 18:14:39.596 [info] Running TeslaMateWeb.Endpoint with cowboy 2.9.0 at :::4000 (http)
teslamate_1  | 2022-07-15 18:14:39.610 [info] Access TeslaMateWeb.Endpoint at http://10.10.10.8
teslamate_1  | 2022-07-15 18:14:40.247 [info] Starting logger for 'SHUYE 3G'
teslamate_1  | 2022-07-15 18:14:40.311 [info] Starting logger for 'Yorkshire'
teslamate_1  | 2022-07-15 18:14:40.452 [info] MQTT connection has been established
teslamate_1  | 2022-07-15 18:14:40.759 car_id=1 [info] Start / :asleep
teslamate_1  | 2022-07-15 18:14:40.947 car_id=5 [info] Start / :online
teslamate_1  | 2022-07-15 18:14:41.218 car_id=5 [info] Connecting ...
teslamate_1  | 2022-07-15 18:16:16.674 [error] Postgrex.Protocol (#PID<0.2371.0>) disconnected: ** (DBConnection.ConnectionError) client #PID<0.2511.0> timed out because it queued and checked out the connection for longer than 60000ms
teslamate_1  |
teslamate_1  | #PID<0.2511.0> was at location:
teslamate_1  |
teslamate_1  |     :prim_inet.recv0/3
teslamate_1  |     (postgrex 0.16.3) lib/postgrex/protocol.ex:3108: Postgrex.Protocol.msg_recv/4
teslamate_1  |     (postgrex 0.16.3) lib/postgrex/protocol.ex:2138: Postgrex.Protocol.recv_bind/3
teslamate_1  |     (postgrex 0.16.3) lib/postgrex/protocol.ex:2030: Postgrex.Protocol.bind_execute/4
teslamate_1  |     (db_connection 2.4.2) lib/db_connection/holder.ex:354: DBConnection.Holder.holder_apply/4
teslamate_1  |     (db_connection 2.4.2) lib/db_connection.ex:1364: DBConnection.run_execute/5
teslamate_1  |     (db_connection 2.4.2) lib/db_connection.ex:1459: DBConnection.run/6
teslamate_1  |     (db_connection 2.4.2) lib/db_connection.ex:595: DBConnection.parsed_prepare_execute/5
teslamate_1  |
teslamate_1  | 2022-07-15 18:16:26.248 [error] GenStateMachine TeslaMate.Terrain terminating
teslamate_1  | ** (DBConnection.ConnectionError) tcp recv: closed (the connection was closed by the pool, possibly due to a timeout or because the pool has been terminated)
teslamate_1  |     (ecto_sql 3.8.3) lib/ecto/adapters/sql.ex:932: Ecto.Adapters.SQL.raise_sql_call_error/1
teslamate_1  |     (ecto_sql 3.8.3) lib/ecto/adapters/sql.ex:847: Ecto.Adapters.SQL.execute/6
teslamate_1  |     (ecto 3.8.4) lib/ecto/repo/queryable.ex:221: Ecto.Repo.Queryable.execute/4
teslamate_1  |     (ecto 3.8.4) lib/ecto/repo/queryable.ex:19: Ecto.Repo.Queryable.all/3
teslamate_1  |     (teslamate 1.27.0) lib/teslamate/log.ex:197: TeslaMate.Log.get_positions_without_elevation/2
teslamate_1  |     (teslamate 1.27.0) lib/teslamate/terrain.ex:84: TeslaMate.Terrain.handle_event/4
teslamate_1  |     (stdlib 3.17.2) gen_statem.erl:1205: :gen_statem.loop_state_callback/11
teslamate_1  |     (stdlib 3.17.2) proc_lib.erl:226: :proc_lib.init_p_do_apply/3
teslamate_1  | 2022-07-15 18:18:43.593 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/1492656798636023/vehicle_data -> 408 (123.508 ms)
teslamate_1  | 2022-07-15 18:18:53.863 car_id=5 [info] Start / :asleep
teslamate_1  | 2022-07-15 18:18:54.080 car_id=5 [info] Disconnecting ...
teslamate_1  | 2022-07-15 18:19:45.947 [info] GET /
teslamate_1  | 2022-07-15 18:19:46.218 [info] Sent 200 in 269ms
teslamate_1  | 2022-07-15 18:20:11.176 [info] GET /
teslamate_1  | 2022-07-15 18:20:11.316 [info] Sent 200 in 138ms
teslamate_1  | 2022-07-15 18:20:34.835 car_id=5 [info] Start / :online
teslamate_1  | 2022-07-15 18:20:35.632 car_id=5 [info] Connecting ...
teslamate_1  | 2022-07-15 18:21:55.976 [info] GET /
teslamate_1  | 2022-07-15 18:21:56.055 [info] Sent 200 in 78ms
teslamate_1  | 2022-07-15 18:22:02.961 car_id=1 [info] Start / :offline
teslamate_1  | 2022-07-15 18:22:43.614 car_id=1 [info] Start / :online
teslamate_1  | 2022-07-15 18:22:44.669 car_id=1 [info] Connecting ...
teslamate_1  | 2022-07-15 18:23:15.839 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/1492656798636023/vehicle_data -> 408 (9125.393 ms)
teslamate_1  | 2022-07-15 18:23:15.850 [warning] TeslaApi.Error / %{"error" => "{\"error\": \"timeout\"}", "error_description" => "", "response" => nil}
teslamate_1  | 2022-07-15 18:23:15.852 car_id=5 [error] Error / :unknown
teslamate_1  | 2022-07-15 18:23:44.994 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/1492656798636023/vehicle_data -> 408 (9133.842 ms)
teslamate_1  | 2022-07-15 18:23:44.995 [warning] TeslaApi.Error / %{"error" => "{\"error\": \"timeout\"}", "error_description" => "", "response" => nil}
teslamate_1  | 2022-07-15 18:23:44.996 car_id=5 [error] Error / :unknown
teslamate_1  | 2022-07-15 18:24:07.383 [warning] GET https://owner-api.teslamotors.com/api/1/vehicles/1492656798636023/vehicle_data -> 540 (2378.633 ms)
teslamate_1  | 2022-07-15 18:24:07.384 [warning] TeslaApi.Error / vehicle error: it is online but not responsive
teslamate_1  | 2022-07-15 18:24:07.384 car_id=5 [error] Error / :unknown
teslamate_1  | 2022-07-15 18:25:46.242 car_id=1 [info] Suspending logging
teslamate_1  | 2022-07-15 18:26:10.661 [warning] Too many disconnects from streaming API
teslamate_1  | 2022-07-15 18:26:10.661 car_id=5 [info] Creating new connection …
teslamate_1  | 2022-07-15 18:26:10.661 car_id=5 [info] Disconnecting ...
teslamate_1  | 2022-07-15 18:26:10.679 car_id=5 [info] Connecting ...
natrlhy commented 2 years ago

Was easier for me to start fresh:

https://github.com/adriankumpf/teslamate/discussions/2744#discussioncomment-3166877