Hi Thomas,
I’m really sorry to bother you again. The binding was ok for the last week about CPU usage so the former bug was corrected. Now I have operational issues. I could operate the gates from openhab for several days without issues. Today I wanted to open them but no response. I looked at the logs and I found these a lot repeated:
2020-03-28 13:14:46.762 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Connecting…
2020-03-28 13:14:46.762 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Connecting…
2020-03-28 13:15:13.706 [WARN ] [egateway.internal.BiSdkLoggerAdapter] - Message to be sent, but not authenticated! Ignoring message…
2020-03-28 13:15:13.706 [WARN ] [egateway.internal.BiSdkLoggerAdapter] - Message to be sent, but not authenticated! Ignoring message…
2020-03-28 13:15:13.706 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Waiting for answer with tag 56
2020-03-28 13:15:13.706 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Waiting for answer with tag 55
2020-03-28 13:15:13.706 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived
2020-03-28 13:15:13.706 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived
2020-03-28 13:15:16.753 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Received Exception Timeout waiting for PackageReceived (after waiting 3000 ms) (which probably comes from a timeout while reading). This happens after a while (about 2min), the GW just stops responding => retrying…
2020-03-28 13:15:16.753 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Received Exception Timeout waiting for PackageReceived (after waiting 3000 ms) (which probably comes from a timeout while reading). This happens after a while (about 2min), the GW just stops responding => retrying…
2020-03-28 13:15:16.753 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Reconnecting
2020-03-28 13:15:16.753 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Reconnecting
I tried to disable then enable the binding but it says:
Status: UNINITIALIZED - HANDLER_INITIALIZING_ERROR connect timed out
The logs behind:
2020-03-28 13:16:42.124 [DEBUG] [overy.BiSecureDeviceDiscoveryService] - BiSecureDeviceDiscoveryService org.openhab.binding.bisecuregateway.internal.handler.BiSecureGatewayHandler@14e2d8a0
2020-03-28 13:16:42.124 [DEBUG] [ernal.handler.BiSecureGatewayHandler] - Start initializing!
2020-03-28 13:16:42.124 [INFO ] [egateway.internal.BiSdkLoggerAdapter] - Connecting to /192.168.1.3:4000
2020-03-28 13:16:42.140 [DEBUG] [nternal.handler.BiSecureGroupHandler] - Running initializing thread
2020-03-28 13:16:42.140 [WARN ] [ernal.handler.BiSecureGatewayHandler] - GetGroups called, but bridge not ONLINE => ignoring
2020-03-28 13:16:42.140 [DEBUG] [nternal.handler.BiSecureGroupHandler] - Group 0 not found in gateway!
2020-03-28 13:16:42.140 [DEBUG] [nternal.handler.BiSecureGroupHandler] - Running initializing thread
2020-03-28 13:16:42.140 [WARN ] [ernal.handler.BiSecureGatewayHandler] - GetGroups called, but bridge not ONLINE => ignoring
2020-03-28 13:16:42.140 [DEBUG] [nternal.handler.BiSecureGroupHandler] - Group 1 not found in gateway!
2020-03-28 13:16:56.344 [WARN ] [ernal.handler.BiSecureGatewayHandler] - childHandlerDisposed not implemented!
2020-03-28 13:16:59.094 [WARN ] [ernal.handler.BiSecureGatewayHandler] - childHandlerDisposed not implemented!
2020-03-28 13:17:07.641 [DEBUG] [overy.BiSecureDeviceDiscoveryService] - BiSecureDeviceDiscoveryService org.openhab.binding.bisecuregateway.internal.handler.BiSecureGatewayHandler@5d4a1641
While it works with the Android app.
This might be relevant as well:
2020-03-25 01:06:47.676 [WARN ] [egateway.internal.BiSdkLoggerAdapter] - Message to be sent, but not authenticated! Ignoring message…
2020-03-25 01:06:47.676 [WARN ] [egateway.internal.BiSdkLoggerAdapter] - Message to be sent, but not authenticated! Ignoring message…
2020-03-25 01:06:47.676 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Waiting for answer with tag 57
2020-03-25 01:06:47.676 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Waiting for answer with tag 56
2020-03-25 01:06:47.676 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived
2020-03-25 01:06:47.676 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived
2020-03-25 01:06:50.692 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Received Exception Timeout waiting for PackageReceived (after waiting 3000 ms) (which probably comes from a timeout while reading). This happens after a while (about 2min), the GW just stops responding => retrying…
2020-03-25 01:06:50.692 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Received Exception Timeout waiting for PackageReceived (after waiting 3000 ms) (which probably comes from a timeout while reading). This happens after a while (about 2min), the GW just stops responding => retrying…
2020-03-25 01:06:50.692 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Reconnecting
2020-03-25 01:06:50.692 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Reconnecting
2020-03-25 01:06:50.692 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Connecting…
2020-03-25 01:06:50.692 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Connecting…
2020-03-25 01:06:50.723 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Stopping receiver thread, we received exception while waiting for bytes: java.io.IOException: Stream closed.
2020-03-25 01:06:51.707 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Connected
2020-03-25 01:06:51.707 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Sending package command: Command(code=16 (10), name=‘LOGIN’), tag: 58, token: 00000000, payload: 076F70656E6861626861626F70656E, isResponse=false, checksum=-23
2020-03-25 01:06:51.707 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilEmptyQueue
2020-03-25 01:06:51.707 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilEmptyQueue finished
2020-03-25 01:06:51.707 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilMessageProcessed
2020-03-25 01:06:51.707 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Reading from socket…
2020-03-25 01:06:51.770 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Sending transport container 0000000000055410EC4FEEB900183A0000000010076F70656E6861626861626F70656E230C
2020-03-25 01:06:51.770 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Sending done
2020-03-25 01:06:51.832 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilMessageProcessed finished
2020-03-25 01:06:56.709 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Stopping receiver thread, we received exception while waiting for bytes: java.io.IOException: Stream closed.
2020-03-25 01:07:17.665 [WARN ] [egateway.internal.BiSdkLoggerAdapter] - Message to be sent, but not authenticated! Ignoring message…
2020-03-25 01:07:17.665 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Waiting for answer with tag 59
2020-03-25 01:07:17.665 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived
2020-03-25 01:07:17.665 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived finished
2020-03-25 01:07:17.665 [INFO ] [egateway.internal.BiSdkLoggerAdapter] - Received Exception Stream closed. => retrying…
2020-03-25 01:07:17.696 [WARN ] [egateway.internal.BiSdkLoggerAdapter] - Message to be sent, but not authenticated! Ignoring message…
2020-03-25 01:07:17.696 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Waiting for answer with tag 60
2020-03-25 01:07:17.696 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived
2020-03-25 01:07:18.180 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Waiting for answer with tag 59
2020-03-25 01:07:18.180 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived
2020-03-25 01:07:20.759 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Received Exception Timeout waiting for PackageReceived (after waiting 3000 ms) (which probably comes from a timeout while reading). This happens after a while (about 2min), the GW just stops responding => retrying…
2020-03-25 01:07:20.759 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Reconnecting
2020-03-25 01:07:20.759 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Connecting…
2020-03-25 01:07:21.181 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Received Exception Timeout waiting for PackageReceived (after waiting 3000 ms) (which probably comes from a timeout while reading). This happens after a while (about 2min), the GW just stops responding => retrying…
2020-03-25 01:07:21.181 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Reconnecting
2020-03-25 01:07:21.181 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Connecting…
2020-03-25 01:07:47.669 [WARN ] [egateway.internal.BiSdkLoggerAdapter] - Message to be sent, but not authenticated! Ignoring message…
2020-03-25 01:07:47.669 [WARN ] [egateway.internal.BiSdkLoggerAdapter] - Message to be sent, but not authenticated! Ignoring message…
2020-03-25 01:07:47.669 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Waiting for answer with tag 62
2020-03-25 01:07:47.669 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Waiting for answer with tag 61
2020-03-25 01:07:47.669 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived
2020-03-25 01:07:47.669 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - waitUntilPackageReceived
2020-03-25 01:07:50.731 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Received Exception Timeout waiting for PackageReceived (after waiting 3000 ms) (which probably comes from a timeout while reading). This happens after a while (about 2min), the GW just stops responding => retrying…
2020-03-25 01:07:50.731 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Received Exception Timeout waiting for PackageReceived (after waiting 3000 ms) (which probably comes from a timeout while reading). This happens after a while (about 2min), the GW just stops responding => retrying…
2020-03-25 01:07:50.731 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Reconnecting
2020-03-25 01:07:50.731 [DEBUG] [egateway.internal.BiSdkLoggerAdapter] - Reconnecting
If you need more with this please contact me. Thank you. And I want to ask again if there is any possibility to support you somehow. Keep safe.
Zsolt.