tillsteinbach/WeConnect-mqtt

Error -6 : Retrieval error during update. Will try again after configured interval of 300s

softservicegit opened this issue · 3 comments

Sorry to come back with an new issue:
tgat started a couple of hours ago after the docker container stops suddenly.

2023-02-27T16:10:25.804305203Z | stdout | KeyError: 'location'
2023-02-27T16:10:25.804283282Z | stdout | ~~~~~~~~~~~^^^^^^^^^^^^^
2023-02-27T16:10:25.804243811Z | stdout | return self._store[key.lower()][1]
2023-02-27T16:10:25.804179163Z | stdout | File "/usr/local/lib/python3.11/site-packages/requests/structures.py", line 52, in __getitem__
2023-02-27T16:10:25.804158245Z | stdout | ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^
2023-02-27T16:10:25.804110347Z | stdout | redirect = tryLoginResponse.headers['Location']
2023-02-27T16:10:25.804034983Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/we_connect_session.py", line 96, in authorizationUrl
2023-02-27T16:10:25.804013382Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.803941999Z | stdout | authorizationUrl = self.authorizationUrl(url='https://identity.vwgroup.io/oidc/v1/authorize')
2023-02-27T16:10:25.803864941Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/we_connect_session.py", line 75, in login
2023-02-27T16:10:25.803829871Z | stdout | self.login()
2023-02-27T16:10:25.803735742Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/openid_session.py", line 229, in addToken
2023-02-27T16:10:25.803707713Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.803610774Z | stdout | url, headers, data = self.addToken(url, body=data, headers=headers, access_type=access_type, token=token)
2023-02-27T16:10:25.803531664Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/openid_session.py", line 192, in request
2023-02-27T16:10:25.803515853Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.803494609Z | stdout | return super(WeConnectSession, self).request(
2023-02-27T16:10:25.803460371Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/we_connect_session.py", line 69, in request
2023-02-27T16:10:25.803436190Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.803391792Z | stdout | return self.request("GET", url, **kwargs)
2023-02-27T16:10:25.803068689Z | stdout | File "/usr/local/lib/python3.11/site-packages/requests/sessions.py", line 600, in get
2023-02-27T16:10:25.803047983Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.802967548Z | stdout | statusResponse: requests.Response = self.session.get(url, allow_redirects=False)
2023-02-27T16:10:25.802793243Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/weconnect.py", line 364, in fetchData
2023-02-27T16:10:25.802772538Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.802725594Z | stdout | data = self.fetchData(url, force)
2023-02-27T16:10:25.802588978Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/weconnect.py", line 199, in updateVehicles
2023-02-27T16:10:25.802548522Z | stdout | self.updateVehicles(updateCapabilities=updateCapabilities, updatePictures=updatePictures, force=force, selective=selective)
2023-02-27T16:10:25.802410941Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/weconnect.py", line 191, in update
2023-02-27T16:10:25.802349912Z | stdout | self.weConnect.update(updateCapabilities=self.updateCapabilities, updatePictures=self.updatePictures, selective=self.selective)
2023-02-27T16:10:25.802136876Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect_mqtt/weconnect_mqtt_base.py", line 446, in updateWeConnect
2023-02-27T16:10:25.802093038Z | stdout | self.updateWeConnect()
2023-02-27T16:10:25.801813779Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect_mqtt/weconnect_mqtt_base.py", line 585, in on_connect_callback
2023-02-27T16:10:25.801784074Z | stdout | on_connect(
2023-02-27T16:10:25.800710342Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 3138, in _handle_connack
2023-02-27T16:10:25.800692648Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.800649151Z | stdout | return self._handle_connack()
2023-02-27T16:10:25.799525590Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 3039, in _packet_handle
2023-02-27T16:10:25.799507644Z | stdout | ^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.799469112Z | stdout | rc = self._packet_handle()
2023-02-27T16:10:25.798636152Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 2439, in _packet_read
2023-02-27T16:10:25.798622005Z | stdout | ^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.798595555Z | stdout | rc = self._packet_read()
2023-02-27T16:10:25.798030226Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 1556, in loop_read
2023-02-27T16:10:25.798012706Z | stdout | ^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.797971757Z | stdout | rc = self.loop_read()
2023-02-27T16:10:25.797544668Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 1164, in _loop
2023-02-27T16:10:25.797512501Z | stdout | ^^^^^^^^^^^^^^^^^^^
2023-02-27T16:10:25.797461889Z | stdout | rc = self._loop(timeout)
2023-02-27T16:10:25.796754820Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 1756, in loop_forever
2023-02-27T16:10:25.796708367Z | stdout | self.loop_forever(retry_first_connection=True)
2023-02-27T16:10:25.795405739Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 3591, in _thread_main
2023-02-27T16:10:25.795362319Z | stdout | self._target(*self._args, **self._kwargs)
2023-02-27T16:10:25.794949389Z | stdout | File "/usr/local/lib/python3.11/threading.py", line 975, in run
2023-02-27T16:10:25.794905960Z | stdout | self.run()
2023-02-27T16:10:25.794664827Z | stdout | File "/usr/local/lib/python3.11/threading.py", line 1038, in _bootstrap_inner
2023-02-27T16:10:25.794644431Z | stdout | Traceback (most recent call last):
2023-02-27T16:10:25.794608908Z | stdout | Exception in thread Thread-1 (_thread_main):
2023-02-27T16:10:25.794554160Z | stdout | 2023-02-27T17:10:25+0100:ERROR:client:Caught exception in on_connect: 'location'
2023-02-27T16:10:25.793975035Z | stdout | 2023-02-27T17:10:25+0100:DEBUG:connectionpool:https://login.apps.emea.vwapps.io:443 "GET /authorize?redirect_uri=weconnect%3A%2F%2Fauthenticated&nonce=127944254321865767701677514225 HTTP/1.1" 404 77
2023-02-27T16:10:25.650773224Z | stdout | 2023-02-27T17:10:25+0100:DEBUG:connectionpool:Starting new HTTPS connection (1): login.apps.emea.vwapps.io:443
2023-02-27T16:10:25.649303939Z | stdout | 2023-02-27T17:10:25+0100:INFO:weconnect_mqtt_base:Update data from WeConnect
2023-02-27T16:10:25.649225768Z | stdout | 2023-02-27T17:10:25+0100:DEBUG:client:Sending SUBSCRIBE (d0, m6) [(b'weconnect/0/mqtt/weconnectUpdateInterval_s_writetopic', 1)]
2023-02-27T16:10:25.649117948Z | stdout | 2023-02-27T17:10:25+0100:DEBUG:client:Sending PUBLISH (d0, q1, r1, m5), 'b'weconnect/0/mqtt/weconnectUpdateInterval_s'', ... (3 bytes)
2023-02-27T16:10:25.649036479Z | stdout | 2023-02-27T17:10:25+0100:DEBUG:client:Sending SUBSCRIBE (d0, m4) [(b'weconnect/0/mqtt/weconnectForceUpdate_writetopic', 2)]
2023-02-27T16:10:25.649010856Z | stdout | 2023-02-27T17:10:25+0100:INFO:weconnect_mqtt_base:Connected to MQTT broker
2023-02-27T16:10:25.648928628Z | stdout | 2023-02-27T17:10:25+0100:DEBUG:client:Received CONNACK (0, 0)
2023-02-27T16:10:25.561520008Z | stdout | 2023-02-27T17:10:25+0100:DEBUG:client:Sending CONNECT (u1, p1, wr1, wq1, wf1, c1, k60) client_id=b''
2023-02-27T16:10:25.499430539Z | stdout | 2023-02-27T17:10:25+0100:DEBUG:addressable:: Observer added with flags: ObserverEvent.ENABLED\|DISABLED\|VALUE_CHANGED
2023-02-27T16:10:25.499375713Z | stdout | 2023-02-27T17:10:25+0100:INFO:weconnect_mqtt_base:Connect to WeConnect
2023-02-27T16:10:25.498673273Z | stdout | 2023-02-27T17:10:25+0100:INFO:weconnect_mqtt_base:WeConnect-mqtt 0.41.1 (using WeConnect-python 0.50.1)
2023-02-27T16:08:15.606451478Z | stdout | 2023-02-27T17:08:15+0100:DEBUG:client:Sending PUBLISH (d0, q1, r1, m7), 'b'weconnect/0/mqtt/weconnectConnected'', ... (5 bytes)
2023-02-27T16:08:15.606122221Z | stdout | 2023-02-27T17:08:15+0100:DEBUG:connectionpool:https://login.apps.emea.vwapps.io:443 "GET /authorize?redirect_uri=weconnect%3A%2F%2Fauthenticated&nonce=162284535719547465111677514095 HTTP/1.1" 404 77
2023-02-27T16:08:15.461679251Z | stdout | 2023-02-27T17:08:15+0100:DEBUG:connectionpool:Resetting dropped connection: login.apps.emea.vwapps.io
2023-02-27T16:08:15.460438646Z | stdout | 2023-02-27T17:08:15+0100:INFO:weconnect_mqtt_base:Update data from WeConnect
2023-02-27T16:03:15.777188433Z | stdout | KeyError: 'location'
2023-02-27T16:03:15.777175957Z | stdout | ~~~~~~~~~~~^^^^^^^^^^^^^
2023-02-27T16:03:15.777159612Z | stdout | return self._store[key.lower()][1]
2023-02-27T16:03:15.777141783Z | stdout | File "/usr/local/lib/python3.11/site-packages/requests/structures.py", line 52, in __getitem__
2023-02-27T16:03:15.777128968Z | stdout | ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^
2023-02-27T16:03:15.777109214Z | stdout | redirect = tryLoginResponse.headers['Location']
2023-02-27T16:03:15.777085013Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/we_connect_session.py", line 96, in authorizationUrl
2023-02-27T16:03:15.777066637Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.777045076Z | stdout | authorizationUrl = self.authorizationUrl(url='https://identity.vwgroup.io/oidc/v1/authorize')
2023-02-27T16:03:15.777022644Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/we_connect_session.py", line 75, in login
2023-02-27T16:03:15.777008968Z | stdout | self.login()
2023-02-27T16:03:15.776992034Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/openid_session.py", line 229, in addToken
2023-02-27T16:03:15.776978773Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776959352Z | stdout | url, headers, data = self.addToken(url, body=data, headers=headers, access_type=access_type, token=token)
2023-02-27T16:03:15.776939804Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/openid_session.py", line 192, in request
2023-02-27T16:03:15.776926597Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776906969Z | stdout | return super(WeConnectSession, self).request(
2023-02-27T16:03:15.776888169Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/auth/we_connect_session.py", line 69, in request
2023-02-27T16:03:15.776875429Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776859712Z | stdout | return self.request("GET", url, **kwargs)
2023-02-27T16:03:15.776840518Z | stdout | File "/usr/local/lib/python3.11/site-packages/requests/sessions.py", line 600, in get
2023-02-27T16:03:15.776825530Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776805620Z | stdout | statusResponse: requests.Response = self.session.get(url, allow_redirects=False)
2023-02-27T16:03:15.776788768Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/weconnect.py", line 364, in fetchData
2023-02-27T16:03:15.776776207Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776760294Z | stdout | data = self.fetchData(url, force)
2023-02-27T16:03:15.776743014Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/weconnect.py", line 199, in updateVehicles
2023-02-27T16:03:15.776724380Z | stdout | self.updateVehicles(updateCapabilities=updateCapabilities, updatePictures=updatePictures, force=force, selective=selective)
2023-02-27T16:03:15.776707075Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect/weconnect.py", line 191, in update
2023-02-27T16:03:15.776683835Z | stdout | self.weConnect.update(updateCapabilities=self.updateCapabilities, updatePictures=self.updatePictures, selective=self.selective)
2023-02-27T16:03:15.776666191Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect_mqtt/weconnect_mqtt_base.py", line 446, in updateWeConnect
2023-02-27T16:03:15.776651770Z | stdout | self.updateWeConnect()
2023-02-27T16:03:15.776630339Z | stdout | File "/usr/local/lib/python3.11/site-packages/weconnect_mqtt/weconnect_mqtt_base.py", line 585, in on_connect_callback
2023-02-27T16:03:15.776614881Z | stdout | on_connect(
2023-02-27T16:03:15.776597561Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 3138, in _handle_connack
2023-02-27T16:03:15.776584977Z | stdout | ^^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776567326Z | stdout | return self._handle_connack()
2023-02-27T16:03:15.776531201Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 3039, in _packet_handle
2023-02-27T16:03:15.776518565Z | stdout | ^^^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776503400Z | stdout | rc = self._packet_handle()
2023-02-27T16:03:15.776478707Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 2439, in _packet_read
2023-02-27T16:03:15.776454236Z | stdout | ^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776440322Z | stdout | rc = self._packet_read()
2023-02-27T16:03:15.776423407Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 1556, in loop_read
2023-02-27T16:03:15.776411235Z | stdout | ^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776397084Z | stdout | rc = self.loop_read()
2023-02-27T16:03:15.776377549Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 1164, in _loop
2023-02-27T16:03:15.776363809Z | stdout | ^^^^^^^^^^^^^^^^^^^
2023-02-27T16:03:15.776343728Z | stdout | rc = self._loop(timeout)
2023-02-27T16:03:15.776277070Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 1756, in loop_forever
2023-02-27T16:03:15.739268149Z | stdout | self.loop_forever(retry_first_connection=True)
2023-02-27T16:03:15.721145369Z | stdout | File "/usr/local/lib/python3.11/site-packages/paho/mqtt/client.py", line 3591, in _thread_main
2023-02-27T16:03:15.721094391Z | stdout | self._target(*self._args, **self._kwargs)
2023-02-27T16:03:15.720754999Z | stdout | File "/usr/local/lib/python3.11/threading.py", line 975, in run
2023-02-27T16:03:15.720698266Z | stdout | self.run()
2023-02-27T16:03:15.711623023Z | stdout | File "/usr/local/lib/python3.11/threading.py", line 1038, in _bootstrap_inner
2023-02-27T16:03:15.711603738Z | stdout | Traceback (most recent call last):
2023-02-27T16:03:15.711580111Z | stdout | Exception in thread Thread-1 (_thread_main):
2023-02-27T16:03:15.711525238Z | stdout | 2023-02-27T17:03:15+0100:ERROR:client:Caught exception in on_connect: 'location'
2023-02-27T16:03:15.710970234Z | stdout | 2023-02-27T17:03:15+0100:DEBUG:connectionpool:https://login.apps.emea.vwapps.io:443 "GET /authorize?redirect_uri=weconnect%3A%2F%2Fauthenticated&nonce=12114415840091920591677513795 HTTP/1.1" 404 77
2023-02-27T16:03:15.539832602Z | stdout | 2023-02-27T17:03:15+0100:DEBUG:connectionpool:Starting new HTTPS connection (1): login.apps.emea.vwapps.io:443
2023-02-27T16:03:15.538345478Z | stdout | 2023-02-27T17:03:15+0100:INFO:weconnect_mqtt_base:Update data from WeConnect
2023-02-27T16:03:15.538285073Z | stdout | 2023-02-27T17:03:15+0100:DEBUG:client:Sending SUBSCRIBE (d0, m6) [(b'weconnect/0/mqtt/weconnectUpdateInterval_s_writetopic', 1)]
2023-02-27T16:03:15.538179564Z | stdout | 2023-02-27T17:03:15+0100:DEBUG:client:Sending PUBLISH (d0, q1, r1, m5), 'b'weconnect/0/mqtt/weconnectUpdateInterval_s'', ... (3 bytes)
2023-02-27T16:03:15.538067494Z | stdout | 2023-02-27T17:03:15+0100:DEBUG:client:Sending SUBSCRIBE (d0, m4) [(b'weconnect/0/mqtt/weconnectForceUpdate_writetopic', 2)]
2023-02-27T16:03:15.538038355Z | stdout | 2023-02-27T17:03:15+0100:INFO:weconnect_mqtt_base:Connected to MQTT broker
2023-02-27T16:03:15.537946266Z | stdout | 2023-02-27T17:03:15+0100:DEBUG:client:Received CONNACK (0, 0)
2023-02-27T16:03:15.459820243Z | stdout | 2023-02-27T17:03:15+0100:DEBUG:client:Sending CONNECT (u1, p1, wr1, wq1, wf1, c1, k60) client_id=b''
2023-02-27T16:03:15.440783280Z | stdout | 2023-02-27T17:03:15+0100:DEBUG:addressable:: Observer added with flags: ObserverEvent.ENABLED\|DISABLED\|VALUE_CHANGED
2023-02-27T16:03:15.440709832Z | stdout | 2023-02-27T17:03:15+0100:INFO:weconnect_mqtt_base:Connect to WeConnect
2023-02-27T16:03:15.439947913Z | stdout | 2023-02-27T17:03:15+0100:INFO:weconnect_mqtt_base:WeConnect-mqtt 0.41.1 (using WeConnect-python 0.50.1)

URLs in the API changed. Will fix it tomorrow.

Please update to the latest release 0.42.2