Excodibur / ioBroker.tahoma

ioBroker adapter for Somfy Tahoma
MIT License
12 stars 9 forks source link

Error messages after some hours running (stops when restarting instance) #243

Open marlan99 opened 1 year ago

marlan99 commented 1 year ago

NodeJS: v16.19.1 NPM: v8.19.3 JS-Controller: v4.0.24 tahoma : V0.10.2

i am using the tahoma switch with the adapter for 3 days now

i regularly can see following warning messages in the log after some hours of my running instance. the messages disappears when i restart the instance:

tahoma.0 2023-07-20 09:45:03.066 info eventRegisterID = 25cb714c-eb48-4302-bb74-d190859dd31e
tahoma.0 2023-07-20 09:45:02.117 info Using stored bearer token 64b592f69863f58a72cc
tahoma.0 2023-07-20 09:45:02.113 info Adapter will connect to Tahoma Box via local API.
tahoma.0 2023-07-20 09:45:02.109 info Configured polling interval: 20000
tahoma.0 2023-07-20 09:45:02.107 info [START] Starting adapter tahoma v0.10.2.3
tahoma.0 2023-07-20 09:45:02.061 info starting. Version 0.10.2 in /opt/iobroker/node_modules/iobroker.tahoma, node: v16.19.1, js-controller: 4.0.24
tahoma.0 2023-07-20 09:44:56.357 info Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
tahoma.0 2023-07-20 09:44:56.355 info terminating
tahoma.0 2023-07-20 09:44:56.352 info Got terminate signal TERMINATE_YOURSELF
tahoma.0 2023-07-20 09:44:39.433 warn Body: {"error":"\"No registered event listener.\"","errorCode":"UNSPECIFIED_ERROR"}
tahoma.0 2023-07-20 09:44:39.433 warn Response: {"statusCode":400,"body":{"error":"\"No registered event listener.\"","errorCode":"UNSPECIFIED_ERROR"},"headers":{"content-type":"application/json","access-control-allow-origin":"*","access-control-allow-headers":"Authorization","content-length":"77","connection":"close","date":"Thu, 20 Jul 2023 07:44:39 GMT","server":"KizOs"},"request":{"uri":{"protocol":"https:","slashes":true,"auth":null,"host":"gateway-2028-5334-8283.local:8443","port":"8443","hostname":"gateway-2028-5334-8283.local","hash":null,"search":null,"query":null,"pathname":"/enduser-mobile-web/1/enduserAPI/events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch","path":"/enduser-mobile-web/1/enduserAPI/events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch","href":"https://gateway-2028-5334-8283.local:8443/enduser-mobile-web/1/enduserAPI/events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch"},"method":"POST","headers":{"User-Agent":"Mozilla/5.0 (X11; Linux x86_64; rv:79.0) Gecko/20100101 Firefox/79.0","Authorization":"Bearer 64b592f69863f58a72cc","Content-type":"application/json","accept":"application/json","content-length":2}}}
tahoma.0 2023-07-20 09:44:39.432 warn error during request: null, request path: events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch with payload:{}
tahoma.0 2023-07-20 09:44:19.731 warn Body: {"error":"\"No registered event listener.\"","errorCode":"UNSPECIFIED_ERROR"}
tahoma.0 2023-07-20 09:44:19.730 warn Response: {"statusCode":400,"body":{"error":"\"No registered event listener.\"","errorCode":"UNSPECIFIED_ERROR"},"headers":{"content-type":"application/json","access-control-allow-origin":"*","access-control-allow-headers":"Authorization","content-length":"77","connection":"close","date":"Thu, 20 Jul 2023 07:44:20 GMT","server":"KizOs"},"request":{"uri":{"protocol":"https:","slashes":true,"auth":null,"host":"gateway-2028-5334-8283.local:8443","port":"8443","hostname":"gateway-2028-5334-8283.local","hash":null,"search":null,"query":null,"pathname":"/enduser-mobile-web/1/enduserAPI/events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch","path":"/enduser-mobile-web/1/enduserAPI/events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch","href":"https://gateway-2028-5334-8283.local:8443/enduser-mobile-web/1/enduserAPI/events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch"},"method":"POST","headers":{"User-Agent":"Mozilla/5.0 (X11; Linux x86_64; rv:79.0) Gecko/20100101 Firefox/79.0","Authorization":"Bearer 64b592f69863f58a72cc","Content-type":"application/json","accept":"application/json","content-length":2}}}
tahoma.0 2023-07-20 09:44:19.729 warn error during request: null, request path: events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch with payload:{}
tahoma.0 2023-07-20 09:43:59.763 warn Body: {"error":"\"No registered event listener.\"","errorCode":"UNSPECIFIED_ERROR"}
tahoma.0 2023-07-20 09:43:59.763 warn Response: {"statusCode":400,"body":{"error":"\"No registered event listener.\"","errorCode":"UNSPECIFIED_ERROR"},"headers":{"content-type":"application/json","access-control-allow-origin":"*","access-control-allow-headers":"Authorization","content-length":"77","connection":"close","date":"Thu, 20 Jul 2023 07:44:00 GMT","server":"KizOs"},"request":{"uri":{"protocol":"https:","slashes":true,"auth":null,"host":"gateway-2028-5334-8283.local:8443","port":"8443","hostname":"gateway-2028-5334-8283.local","hash":null,"search":null,"query":null,"pathname":"/enduser-mobile-web/1/enduserAPI/events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch","path":"/enduser-mobile-web/1/enduserAPI/events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch","href":"https://gateway-2028-5334-8283.local:8443/enduser-mobile-web/1/enduserAPI/events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch"},"method":"POST","headers":{"User-Agent":"Mozilla/5.0 (X11; Linux x86_64; rv:79.0) Gecko/20100101 Firefox/79.0","Authorization":"Bearer 64b592f69863f58a72cc","Content-type":"application/json","accept":"application/json","content-length":2}}}
tahoma.0 2023-07-20 09:43:59.762 warn error during request: null, request path: events/b90f1a4b-c69e-4580-88d8-826405a47f96/fetch with payload:{}
tahoma.0 2023-07-20 09:43:39.413 warn Body: {"error":"\"No registered event listener.\"","errorCode":"UNSPECIFIED_ERROR"}
Basti189 commented 1 year ago

Same issue, but only this message

tahoma.0 (11209) error during request: Error: connect ETIMEDOUT fd00:::87ff::****:8443, request path: setup/gateways with payload:{}

The message is displayed 70 times. After the restart, the messages continue to appear. I can no longer control the roller shutters

marlan99 commented 1 year ago

i dont know if or what has changed. but currently no such messages again in the last 3 days

Basti189 commented 1 year ago

Since my comment, all is fine. Today it started all over again Screenshot 2023-07-26 231851

Basti189 commented 1 year ago

Restarting doesn't fix the problem. Stopping and starting fixes the problem for now

Excodibur commented 1 year ago

It sounds a bit like a network issue t.b.h. Or perhaps the cpu load on the server ioBroker is running on is at times so high that it has issues sending data fast over its network interface.

Basti189 commented 1 year ago

That's out of the question. Since I stopped the adapter every night and started it again after 15 seconds, the problem no longer occurs.

marlan99 commented 1 year ago

yesterday i had a power outage on the plug where my tahoma switch is plugged in. I now just checked the logs in ioBroker an had all these warning messages since the device is running again. Now restarted the instance on ioBroker and everything is fine again.

All scenes were working during the time with the warning messages. So i have no clue whats the root cause here. Maybe a token error for reading? i am just guessing

marlan99 commented 12 months ago

again after restarting the Tahoma Switch device the instance did not reconnect correctly. i had to restart the instance and it is running fine since (until the next restart of the device :-) )

github-actions[bot] commented 10 months ago

This issue is stale because it has been open 60 days with no activity. Remove stale label or comment or this will be closed in 7 days.

marlan99 commented 10 months ago

problem still persists and waiting for a possible update. see also following error messages which appears regularly until a restart of the instance was made. Then no error messages anymore for a few hours (or maybe one day):

` tahoma.0 2023-11-19 15:46:42.801 warn error during request: Error: getaddrinfo ENOTFOUND gateway-2028-5334-8283.local, request path: events/089201ec-180a-42de-8aad-21583977f5fa/fetch with payload:{}
tahoma.0 2023-11-19 14:43:10.394 warn error during request: Error: getaddrinfo ENOTFOUND gateway-2028-5334-8283.local, request path: setup/gateways with payload:{}
tahoma.0 2023-11-19 13:04:21.865 warn error during request: Error: getaddrinfo ENOTFOUND gateway-2028-5334-8283.local, request path: events/089201ec-180a-42de-8aad-21583977f5fa/fetch with payload:{}
tahoma.0 2023-11-19 11:49:21.407 warn error during request: Error: getaddrinfo ENOTFOUND gateway-2028-5334-8283.local, request path: events/089201ec-180a-42de-8aad-21583977f5fa/fetch with payload:{}

`

tecfreakz commented 8 months ago

Same problem here, Bild_2023-12-24_171835609 Need a fix

Kaiman77 commented 1 month ago

I have some more details about the issue. for me it occures always, when the tahome/connexxoon box wasn't available for some reason. I had some power failures the last couple of days with the box being offline (iobroker kept running). After the reboot, the box obviously wanted a new handler and the tahoma adapter kept trying with the old handler.

A restart of the adapter solved the issue of course, as the adapter then requests a new handler.

So, from my perspective, the adapter needs to detect when a new handler request is necessary.