MQTT integration doesn't work

Question

MQTT integration doesn't work

Opened this issue 2 years ago · 8 comments

Describe the bug
I don't get any reports into Grafana nor do I see any entities in MQTT. Secondly, the initial MQTTuser that I have defined doesn't have access anymore.

Steps to reproduce
1. config in grafana
certfile: fullchain.pem
env_vars:

name: GF_SECURITY_ADMIN_USER
value: admin
name: GF_SECURITY_ADMIN_PASSWORD
value: XXXYYY
keyfile: privkey.pem
plugins:
natel-discrete-panel
pr0ps-trackmap-panel
grafana-piechart-panel
ssl: false

In Grafana - added datasource

2. In Postgres

3. In MQTT

logins: []
require_certificate: false
certfile: fullchain.pem
keyfile: privkey.pem
customize:
active: true
folder: mosquitto

Created files /share/mosquitto/acecesscontrollist and /share/mosquitto/acl.conf

Created an user teslamate in Home assistant (settings)

4 in Teslamate

database_user: teslamate
database_pass: pw
database_name: teslamate
database_host: 29b65938-postgres
database_ssl: false
database_port: 5432
disable_mqtt: false
encryption_key: aradomencryptionkey
grafana_import_dashboards: true
grafana_folder_name: TeslaMate
grafana_host: a0d7b954-grafana
grafana_port: 3000
grafana_user: admin
grafana_pass: pw
import_dir: /share/teslamate
mqtt_host: core-mosquitto
mqtt_user: teslamate
mqtt_pass: pw
mqtt_tls: false
mqtt_tls_accept_invalid_certs: false
mqtt_namespace: ""
timezone: Asia/Jerusalem

logs
Teslamate

2023-02-09 11:15:28.298 [info] Start of drive initiated by: %TeslaApi.Stream.Data{elevation: 265, est_heading: 352, est_lat: 31.887999, est_lng: 35.01521, est_range: 94, heading: 357, odometer: 20004.9, power: 2, range: 125, shift_state: "R", soc: 39, speed: 0, time: ~U[2023-02-09 09:15:28.195Z]}
2023-02-09 11:15:28.298 car_id=1 [info] Driving / Start
2023-02-09 11:28:26.992 [info] GET https://nominatim.openstreetmap.org/reverse -> 200 (603.217 ms)
2023-02-09 11:28:27.509 [info] GET https://nominatim.openstreetmap.org/reverse -> 200 (516.479 ms)
2023-02-09 11:28:27.518 [info] End of drive initiated by: %TeslaApi.Vehicle.State.Drive{gps_as_of: 1675934905, heading: 342, latitude: 31.906416, longitude: 35.017888, native_latitude: 31.906416, native_location_supported: 1, native_longitude: 35.017888, native_type: "wgs", power: 1, shift_state: "P", speed: 0, timestamp: 1675934906256}
2023-02-09 11:28:27.518 car_id=1 [info] Driving / Ended / 5 km – 13 min
2023-02-09 11:28:27.518 car_id=1 [info] Start / :online
2023-02-09 11:29:55.811 car_id=1 [info] Received stream data: :inactive
2023-02-09 11:39:24.107 [warning] Too many disconnects from streaming API
2023-02-09 11:39:24.107 car_id=1 [info] Creating new connection … 
2023-02-09 11:39:24.107 car_id=1 [info] Disconnecting ...
2023-02-09 11:39:24.109 car_id=1 [info] Connecting ...
2023-02-09 11:42:36.353 [info] Start of drive initiated by: %TeslaApi.Vehicle.State.Drive{gps_as_of: 1675935755, heading: 342, latitude: 31.906416, longitude: 35.017888, native_latitude: 31.906416, native_location_supported: 1, native_longitude: 35.017888, native_type: "wgs", power: 1, shift_state: "R", speed: 0, timestamp: 1675935756175}
2023-02-09 11:42:36.353 car_id=1 [info] Driving / Start
2023-02-09 11:49:37.749 [info] GET https://nominatim.openstreetmap.org/reverse -> 200 (345.439 ms)
2023-02-09 11:49:38.241 [info] GET https://nominatim.openstreetmap.org/reverse -> 200 (491.345 ms)
2023-02-09 11:49:38.246 [info] End of drive initiated by: %TeslaApi.Vehicle.State.Drive{gps_as_of: 1675936175, heading: 204, latitude: 31.900113, longitude: 35.008391, native_latitude: 31.900113, native_location_supported: 1, native_longitude: 35.008391, native_type: "wgs", power: 1, shift_state: "P", speed: 0, timestamp: 1675936177148}
2023-02-09 11:49:38.246 car_id=1 [info] Driving / Ended / 1 km – 7 min
2023-02-09 11:49:38.246 car_id=1 [info] Start / :online
2023-02-09 11:49:57.966 [info] Start of drive initiated by: %TeslaApi.Stream.Data{elevation: 232, est_heading: 204, est_lat: 31.900116, est_lng: 35.008392, est_range: 95, heading: 204, odometer: 20009.0, power: 1, range: 120, shift_state: "D", soc: 38, speed: 0, time: ~U[2023-02-09 09:49:57.863Z]}
2023-02-09 11:49:57.966 car_id=1 [info] Driving / Start
2023-02-09 11:51:15.946 [info] GET https://nominatim.openstreetmap.org/reverse -> 200 (290.800 ms)
2023-02-09 11:51:16.940 [info] GET https://nominatim.openstreetmap.org/reverse -> 200 (993.101 ms)
2023-02-09 11:51:16.950 [info] End of drive initiated by: %TeslaApi.Vehicle.State.Drive{gps_as_of: 1675936274, heading: 52, latitude: 31.899524, longitude: 35.008917, native_latitude: 31.899524, native_location_supported: 1, native_longitude: 35.008917, native_type: "wgs", power: 1, shift_state: "P", speed: 0, timestamp: 1675936275372}
2023-02-09 11:51:16.950 car_id=1 [info] Driving / Ended / 0 km – 1 min
2023-02-09 11:51:16.950 car_id=1 [info] Start / :online
2023-02-09 11:53:07.433 car_id=1 [info] Received stream data: :inactive
2023-02-09 12:01:34.531 [warning] Too many disconnects from streaming API
2023-02-09 12:01:34.531 car_id=1 [info] Creating new connection … 
2023-02-09 12:01:34.531 car_id=1 [info] Disconnecting ...
2023-02-09 12:01:34.533 car_id=1 [info] Connecting ...
2023-02-09 12:10:04.678 car_id=1 [info] Received stream data: :inactive
2023-02-09 12:10:48.631 [info] Start of drive initiated by: %TeslaApi.Vehicle.State.Drive{gps_as_of: 1675937447, heading: 46, latitude: 31.899514, longitude: 35.008895, native_latitude: 31.899514, native_location_supported: 1, native_longitude: 35.008895, native_type: "wgs", power: 1, shift_state: "R", speed: 1, timestamp: 1675937448370}
2023-02-09 12:10:48.631 car_id=1 [info] Driving / Start
2023-02-09 12:16:51.411 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/number/vehicle_data -> 408 (134.447 ms)
2023-02-09 12:16:51.543 car_id=1 [warning] Vehicle went offline while driving
2023-02-09 12:16:52.410 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/numbervehicle_data -> 408 (133.107 ms)
2023-02-09 12:16:52.911 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/number/vehicle_data -> 408 (127.359 ms)
2023-02-09 12:16:53.418 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/number/vehicle_data -> 408 (128.390 ms)
2023-02-09 12:16:55.247 car_id=1 [info] Vehicle is back online
2023-02-09 12:16:55.538 [info] GET https://nominatim.openstreetmap.org/reverse -> 200 (283.640 ms)
2023-02-09 12:16:56.046 [info] GET https://nominatim.openstreetmap.org/reverse -> 200 (507.982 ms)
2023-02-09 12:16:56.055 [info] End of drive initiated by: %TeslaApi.Vehicle.State.Drive{gps_as_of: number1, heading: 360, latitude: 31.887778, longitude: 35.002349, native_latitude: 31.887778, native_location_supported: 1, native_longitude: 35.002349, native_type: "wgs", power: 0, shift_state: "P", speed: 0, timestamp: 1675937814457}
2023-02-09 12:16:56.055 car_id=1 [info] Driving / Ended / 3 km – 6 min
2023-02-09 12:16:56.055 car_id=1 [info] Start / :online
2023-02-09 12:19:42.585 car_id=1 [info] Received stream data: :inactive
2023-02-09 12:21:15.383 car_id=1 [info] Suspending logging
2023-02-09 12:28:10.973 [warning] Too many disconnects from streaming API
2023-02-09 12:28:10.973 car_id=1 [info] Creating new connection … 
2023-02-09 12:28:10.973 car_id=1 [info] Disconnecting ...
2023-02-09 12:28:10.974 car_id=1 [info] Connecting ...
2023-02-09 12:51:16.793 car_id=1 [info] Start / :asleep
2023-02-09 12:51:16.795 car_id=1 [info] Disconnecting ...
2023-02-09 13:30:40.229 car_id=1 [info] Start / :online
2023-02-09 13:30:40.237 car_id=1 [info] Connecting ...
2023-02-09 13:34:14.777 car_id=1 [info] Suspending logging
2023-02-09 13:41:27.970 [info] GET /
2023-02-09 13:41:27.972 [info] Sent 200 in 1ms
2023-02-09 13:41:34.741 car_id=1 [info] Resuming logging
2023-02-09 13:42:21.675 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/number/vehicle_data -> 408 (9125.905 ms)
2023-02-09 13:42:21.675 [warning] TeslaApi.Error / %{"error" => "{\"error\": \"timeout\"}", "error_description" => "", "response" => nil}
2023-02-09 13:42:21.675 car_id=1 [error] Error / :unknown
2023-02-09 13:42:50.820 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/number/vehicle_data -> 408 (9143.671 ms)
2023-02-09 13:42:50.820 [warning] TeslaApi.Error / %{"error" => "{\"error\": \"timeout\"}", "error_description" => "", "response" => nil}
2023-02-09 13:42:50.820 car_id=1 [error] Error / :unknown
2023-02-09 13:43:44.216 [info] GET https://owner-api.teslamotors.com/api/1/vehicles/1493132975230022/vehicle_data -> 408 (131.968 ms)
2023-02-09 13:43:54.479 car_id=1 [info] Start / :asleep
2023-02-09 13:43:54.486 car_id=1 [info] Disconnecting ...

MQTT

2023-02-07 11:00:55: New connection from 172.30.33.8:42808 on port 1883.
2023-02-07 11:00:55: New client connected from 172.30.33.8:42808 as TESLAMATE_302E3135393 (p2, c0, k60, u'teslamate').

Postgres

PostgreSQL Database directory appears to contain a database; Skipping initialization
2023-02-07 09:45:50.203 IST [7] LOG:  starting PostgreSQL 12.5 on x86_64-pc-linux-musl, compiled by gcc (Alpine 9.3.0) 9.3.0, 64-bit
2023-02-07 09:45:50.203 IST [7] LOG:  listening on IPv4 address "0.0.0.0", port 5432
2023-02-07 09:45:50.203 IST [7] LOG:  listening on IPv6 address "::", port 5432
2023-02-07 09:45:50.236 IST [7] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
2023-02-07 09:45:50.250 IST [34] LOG:  database system was shut down at 2023-02-07 09:45:49 IST
2023-02-07 09:45:50.254 IST [7] LOG:  database system is ready to accept connections

Grafana

Grafana server is running with elevated privileges. This is not recommended
logger=settings t=2023-02-09T14:00:25.414425747+02:00 level=info msg="Starting Grafana" version=9.2.4 commit=64017e8ca6 branch=HEAD compiled=2022-11-08T12:38:42+02:00
logger=settings t=2023-02-09T14:00:25.414543213+02:00 level=info msg="Config loaded from" file=/usr/share/grafana/conf/defaults.ini
logger=settings t=2023-02-09T14:00:25.41456+02:00 level=info msg="Config loaded from" file=/etc/grafana/grafana.ini
logger=settings t=2023-02-09T14:00:25.414564135+02:00 level=info msg="Config overridden from Environment variable" var="GF_SECURITY_ADMIN_USER=admin"
logger=settings t=2023-02-09T14:00:25.41456779+02:00 level=info msg="Config overridden from Environment variable" var="GF_SECURITY_ADMIN_PASSWORD=*********"
logger=settings t=2023-02-09T14:00:25.414571239+02:00 level=info msg="Path Home" path=/usr/share/grafana
logger=settings t=2023-02-09T14:00:25.414574396+02:00 level=info msg="Path Data" path=/data
logger=settings t=2023-02-09T14:00:25.414577133+02:00 level=info msg="Path Logs" path=/var/logs/grafana
logger=settings t=2023-02-09T14:00:25.414579906+02:00 level=info msg="Path Plugins" path=/var/lib/grafana/plugins
logger=settings t=2023-02-09T14:00:25.414582999+02:00 level=info msg="Path Provisioning" path=/usr/share/grafana/conf/provisioning
logger=settings t=2023-02-09T14:00:25.414586463+02:00 level=info msg="App mode production"
logger=sqlstore t=2023-02-09T14:00:25.414627727+02:00 level=info msg="Connecting to DB" dbtype=sqlite3
logger=migrator t=2023-02-09T14:00:25.423498462+02:00 level=info msg="Starting DB migrations"
logger=migrator t=2023-02-09T14:00:25.427134676+02:00 level=info msg="migrations completed" performed=0 skipped=452 duration=616.503µs
logger=plugin.loader t=2023-02-09T14:00:25.469209948+02:00 level=info msg="Plugin registered" pluginID=input
logger=plugin.loader t=2023-02-09T14:00:27.41710066+02:00 level=info msg="Plugin registered" pluginID=pr0ps-trackmap-panel
logger=plugin.loader t=2023-02-09T14:00:27.417117678+02:00 level=info msg="Plugin registered" pluginID=grafana-image-renderer
logger=plugin.loader t=2023-02-09T14:00:27.417122673+02:00 level=info msg="Plugin registered" pluginID=grafana-piechart-panel
logger=plugin.loader t=2023-02-09T14:00:27.417126895+02:00 level=info msg="Plugin registered" pluginID=natel-discrete-panel
logger=secrets t=2023-02-09T14:00:27.417277436+02:00 level=info msg="Envelope encryption state" enabled=true currentprovider=secretKey.v1
logger=query_data t=2023-02-09T14:00:27.418284371+02:00 level=info msg="Query Service initialization"
logger=live.push_http t=2023-02-09T14:00:27.421953761+02:00 level=info msg="Live Push Gateway initialization"
logger=infra.usagestats.collector t=2023-02-09T14:00:27.480787862+02:00 level=info msg="registering usage stat providers" usageStatsProvidersLen=2
logger=provisioning.alerting t=2023-02-09T14:00:27.481225884+02:00 level=info msg="starting to provision alerting"
logger=provisioning.alerting t=2023-02-09T14:00:27.481237907+02:00 level=info msg="finished to provision alerting"
logger=http.server t=2023-02-09T14:00:27.482547433+02:00 level=info msg="HTTP Server Listen" address=[::]:3000 protocol=http subUrl=/api/hassio_ingress/dWyHGMaIDKSFwBC0jCemQhGHr1oMlxI65YtUKsQ59mM socket=
logger=ngalert t=2023-02-09T14:00:27.482621435+02:00 level=info msg="warming cache for startup"
logger=grafanaStorageLogger t=2023-02-09T14:00:27.491947621+02:00 level=info msg="storage starting"
logger=ticker t=2023-02-09T14:00:27.501516488+02:00 level=info msg=starting first_tick=2023-02-09T14:00:30+02:00
logger=ngalert.multiorg.alertmanager t=2023-02-09T14:00:27.501528083+02:00 level=info msg="starting MultiOrg Alertmanager"
[14:00:27] INFO: Starting NGINX...
logger=plugin.grafana-image-renderer t=2023-02-09T14:00:28.001754487+02:00 level=warn msg="Plugin process is running with elevated privileges. This is not recommended"

Answer 1 · 2023-04-12T12:34:27.000Z

I have partly the same issue as you. I can't get the MQTT integration to work. There are no errors in the logs. I have installed MQTT Explorer as well but can't see any topics.

Answer 2 · 2023-04-12T13:04:45.000Z

It looks like login is working. Have you set up any ACLs?

Answer 3 · 2023-05-20T19:59:39.000Z

To add to this, I keep getting an error message - pq: password authentication failed for user when I add the username and password to the data source. I am sure it's correct as I am copy pasting it from the config.

The DB user and password is only set in the initial Postgres container initialisation. If you change it afterwards it won't work sadly.

I'd suggest removing the Postgres add on, then re-adding and setting the configuration before 1st run.

Answer 4 · 2023-05-22T11:13:33.000Z

Had the same problem make sure the datasource is "TeslaMate" with a capital M

Answer 5 · 2023-05-22T15:15:20.000Z

If you are not seeing data in Grafana then the issue is the Postgres connection, not MQTT.

I think we have multiple issues being discussed here.

Answer 6 · 2023-05-23T10:35:32.000Z

@matt-FFFFFF Got it working. I had the MQTT set to true all this time :/
Do you know how to integrate TeslaMate with HA so I can see the entities within HA? (https://docs.teslamate.org/docs/integrations/home_assistant)
TIA.

Answer 7 · 2023-05-24T00:23:55.000Z

Hi,

If you have MQTT working you can follow the instructions on the Teslamate website. It's the same from here on in.

Answer 8 · 2023-09-12T07:16:08.000Z

For me, I gave the write access to my mqtt user for all the topics to make it work.

user mymqttuser
topic readwrite #