Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Brute force not behave correctly #3045

Open
boonhanchng opened this issue Nov 5, 2024 · 6 comments
Open

Brute force not behave correctly #3045

boonhanchng opened this issue Nov 5, 2024 · 6 comments
Assignees
@DenisSinelnikov
Labels
bug Something isn't working local config wait for response xf:authentication

Comments

@boonhanchng
Copy link

Describe the bug
I had configured my cloudbeaver with the env variable in docker

         - name: CLOUDBEAVER_BRUTE_FORCE_PROTECTION_ENABLED
           value: "true"
        - name: CLOUDBEAVER_MAX_FAILED_LOGINS
          value: "3"
        - name: CLOUDBEAVER_MINIMUM_LOGIN_TIMEOUT
          value: "1"
        - name: CLOUDBEAVER_BLOCK_PERIOD
          value: "30"

With CLOUDBEAVER_MINIMUM_LOGIN_TIMEOUT = 1, according to the code in repo, this should be last login attempt within 1second.
Once I logout, try to login again i will hit the exception

org.jkiss.dbeaver.DBException: Too frequent authentication requests
	at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkLoginInterval(BruteForceUtils.java:65)
	at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkBruteforce(BruteForceUtils.java:40)
	at io.cloudbeaver.service.security.CBEmbeddedSecurityController.createNewAuthAttempt(CBEmbeddedSecurityController.java:1620)
	at io.cloudbeaver.service.security.CBEmbeddedSecurityController.authenticate(CBEmbeddedSecurityController.java:1541)
	at io.cloudbeaver.service.auth.impl.WebServiceAuthImpl.authLogin(WebServiceAuthImpl.java:92)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:569)

To Reproduce
Steps to reproduce the behavior:

  1. Login to CloudBeaver
  2. Logout
  3. Try to login again
  4. See error

Screenshots
If applicable, add screenshots to help explain your problem.
image
@boonhanchng boonhanchng added bug Something isn't working wait for review labels Nov 5, 2024
@EvgeniaBzzz
Copy link
Contributor

Hi @boonhanchng
Just to be clear: did you press 'Login' only once when you try to login again? (step 3)
I can't reproduce it so far

@boonhanchng
Copy link
Author

Hi @EvgeniaBzzz
Yes, i just press 'Login' once after logout.
is my config correct?

@EvgeniaBzzz
Copy link
Contributor

Your config is correct.
Could you provide us with your cloudbeaver.conf and server log on the error, please.
Does this happen every time or sometimes?

@boonhanchng
Copy link
Author

It happen every time, once logout not able to login.
I did not configure cloudbeaver.conf, i use env variables

- name: CLOUDBEAVER_SERVICE_PORT
  value: "80"
- name: CLOUDBEAVER_DB_DRIVER
  value: mysql8
- name: CLOUDBEAVER_DB_URL
  value: jdbc:mysql://mysql:3306/cloudbeaver
- name: CLOUDBEAVER_DB_USER
  value: cloudbeaver
- name: CLOUDBEAVER_DB_PASSWORD
  value: mypassword
- name: CLOUDBEAVER_DB_SCHEMA
  value: cloudbeaver
- name: CLOUDBEAVER_QM_DB_DRIVER
  value: mysql8
- name: CLOUDBEAVER_QM_DB_URL
  value: jdbc:mysql://mysql:3306/cloudbeaver
- name: CLOUDBEAVER_QM_DB_USER
  value: cloudbeaver
- name: CLOUDBEAVER_QM_DB_PASSWORD
  value: mypassword
- name: CLOUDBEAVER_QM_DB_SCHEMA
  value: cloudbeaver
- name: CLOUDBEAVER_BRUTE_FORCE_PROTECTION_ENABLED
  value: "true"
- name: CLOUDBEAVER_MAX_FAILED_LOGINS
  value: "3"
- name: CLOUDBEAVER_MINIMUM_LOGIN_TIMEOUT
  value: "1"
- name: CLOUDBEAVER_BLOCK_PERIOD
  value: "30"

Here is the .cloudbeaver.runtime.conf generated by docker

{
    "server": {
      "serverName": "CloudBeaver CE Server",
      "serverURL": "https://example.com",
      "expireSessionAfterPeriod": "${CLOUDBEAVER_EXPIRE_SESSION_AFTER_PERIOD:86400000}",
      "productSettings": {
        "core.localization.localization": "en",
        "core.theming.theme": "light",
        "plugin.connection-search.disabled": true,
        "plugin.log-viewer.disabled": false,
        "plugin.log-viewer.logBatchSize": 1000,
        "plugin.log-viewer.maxLogRecords": 2000,
        "plugin.sql-editor.autoSave": true,
        "plugin.sql-editor.disabled": false,
        "plugin.sql-editor.maxFileSize": 10240,
        "sql.proposals.insert.table.alias": "PLAIN"
      },
      "database": {
        "driver": "${CLOUDBEAVER_DB_DRIVER:mysql8}",
        "url": "${CLOUDBEAVER_DB_URL:jdbc:mysql://mysql:3306/cloudbeaver}",
        "user": "${CLOUDBEAVER_DB_USER:cloudbeaver}",
        "password": "${CLOUDBEAVER_DB_PASSWORD:mypassword}",
        "schema": "${CLOUDBEAVER_DB_SCHEMA:cloudbeaver}",
        "initialDataConfiguration": "${CLOUDBEAVER_DB_INITIAL_DATA:conf/initial-data.conf}",
        "backupEnabled": "${CLOUDBEAVER_DB_BACKUP_ENABLED:true}",
        "pool": {
          "minIdleConnections": "${CLOUDBEAVER_DB_MIN_IDLE_CONNECTIONS:4}",
          "validationQuery": "${CLOUDBEAVER_DB_VALIDATION_QUERY:SELECT 1}",
          "maxIdleConnections": "${CLOUDBEAVER_DB_MAX_IDLE_CONNECTIONS:10}",
          "maxConnections": "${CLOUDBEAVER_DB_MAX_CONNECTIONS:100}"
        }
      },
      "sm": {
        "passwordPolicy": {
          "minLength": "${CLOUDBEAVER_POLICY_MIN_LENGTH:8}",
          "minNumberCount": "${CLOUDBEAVER_POLICY_MIN_NUMBER_COUNT:1}",
          "minSymbolCount": "${CLOUDBEAVER_POLICY_MIN_SYMBOL_COUNT:0}",
          "requireMixedCase": "${CLOUDBEAVER_POLICY_REQUIRE_MIXED_CASE:true}"
        }
      }
    },
    "app": {
      "anonymousAccessEnabled": "${CLOUDBEAVER_APP_ANONYMOUS_ACCESS_ENABLED:false}",
      "supportsCustomConnections": "${CLOUDBEAVER_APP_SUPPORTS_CUSTOM_CONNECTIONS:false}",
      "publicCredentialsSaveEnabled": "${CLOUDBEAVER_APP_PUBLIC_CREDENTIALS_SAVE_ENABLED:true}",
      "adminCredentialsSaveEnabled": "${CLOUDBEAVER_APP_ADMIN_CREDENTIALS_SAVE_ENABLED:true}",
      "enableReverseProxyAuth": false,
      "forwardProxy": "${CLOUDBEAVER_APP_FORWARD_PROXY:false}",
      "linkExternalCredentialsWithUser": true,
      "redirectOnFederatedAuth": false,
      "resourceManagerEnabled": "${CLOUDBEAVER_APP_RESOURCE_MANAGER_ENABLED:true}",
      "showReadOnlyConnectionInfo": "${CLOUDBEAVER_APP_READ_ONLY_CONNECTION_INFO:false}",
      "grantConnectionsAccessToAnonymousTeam": "${CLOUDBEAVER_APP_GRANT_CONNECTIONS_ACCESS_TO_ANONYMOUS_TEAM:false}",
      "systemVariablesResolvingEnabled": "${CLOUDBEAVER_SYSTEM_VARIABLES_RESOLVING_ENABLED:false}",
      "resourceQuotas": {
        "dataExportFileSizeLimit": "${CLOUDBEAVER_RESOURCE_QUOTA_DATA_EXPORT_FILE_SIZE_LIMIT:10000000}",
        "resourceManagerFileSizeLimit": "${CLOUDBEAVER_RESOURCE_QUOTA_RESOURCE_MANAGER_FILE_SIZE_LIMIT:500000}",
        "sqlMaxRunningQueries": "${CLOUDBEAVER_RESOURCE_QUOTA_SQL_MAX_RUNNING_QUERIES:100}",
        "sqlResultSetRowsLimit": "${CLOUDBEAVER_RESOURCE_QUOTA_SQL_RESULT_SET_ROWS_LIMIT:100000}",
        "sqlTextPreviewMaxLength": "${CLOUDBEAVER_RESOURCE_QUOTA_SQL_TEXT_PREVIEW_MAX_LENGTH:4096}",
        "sqlBinaryPreviewMaxLength": "${CLOUDBEAVER_RESOURCE_QUOTA_SQL_BINARY_PREVIEW_MAX_LENGTH:261120}"
      },
      "defaultNavigatorSettings": {},
      "enabledFeatures": [],
      "enabledAuthProviders": [
        "local"
      ],
      "enabledDrivers": [],
      "disabledDrivers": [
        "sqlite:sqlite_jdbc",
        "h2:h2_embedded",
        "h2:h2_embedded_v2",
        "clickhouse:yandex_clickhouse",
        "generic:duckdb_jdbc"
      ]
    }
  }

Logs from my containers

06-11-2024 12:11:34.761 [qtp404994500-31] ERROR i.c.service.WebServiceBindingBase - Unexpected error during gql request
io.cloudbeaver.DBWebException: User authentication failed:
Too frequent authentication requests
        at io.cloudbeaver.service.auth.impl.WebServiceAuthImpl.authLogin(WebServiceAuthImpl.java:115)
        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:569)
        at io.cloudbeaver.service.WebServiceBindingBase$ServiceInvocationHandler.invoke(WebServiceBindingBase.java:170)
        at jdk.proxy7/jdk.proxy7.$Proxy13.authLogin(Unknown Source)
        at io.cloudbeaver.service.auth.WebServiceBindingAuth.lambda$0(WebServiceBindingAuth.java:39)
        at graphql.execution.ExecutionStrategy.invokeDataFetcher(ExecutionStrategy.java:533)
        at graphql.execution.ExecutionStrategy.fetchField(ExecutionStrategy.java:497)
        at graphql.execution.ExecutionStrategy.fetchField(ExecutionStrategy.java:438)
        at graphql.execution.ExecutionStrategy.resolveFieldWithInfo(ExecutionStrategy.java:397)
        at graphql.execution.ExecutionStrategy.getAsyncFieldValueInfo(ExecutionStrategy.java:335)
        at graphql.execution.AsyncExecutionStrategy.execute(AsyncExecutionStrategy.java:57)
        at graphql.execution.Execution.executeOperation(Execution.java:180)
        at graphql.execution.Execution.execute(Execution.java:116)
        at graphql.GraphQL.execute(GraphQL.java:546)
        at graphql.GraphQL.lambda$parseValidateAndExecute$13(GraphQL.java:476)
        at java.base/java.util.concurrent.CompletableFuture.uniComposeStage(CompletableFuture.java:1187)
        at java.base/java.util.concurrent.CompletableFuture.thenCompose(CompletableFuture.java:2309)
        at graphql.GraphQL.parseValidateAndExecute(GraphQL.java:471)
        at graphql.GraphQL.lambda$executeAsync$9(GraphQL.java:429)
        at java.base/java.util.concurrent.CompletableFuture.uniComposeStage(CompletableFuture.java:1187)
        at java.base/java.util.concurrent.CompletableFuture.thenCompose(CompletableFuture.java:2309)
        at graphql.GraphQL.executeAsync(GraphQL.java:418)
        at graphql.GraphQL.execute(GraphQL.java:359)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.executeQuery(GraphQLEndpoint.java:263)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.executeSingleQuery(GraphQLEndpoint.java:207)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.doPost(GraphQLEndpoint.java:190)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:653)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:723)
        at org.eclipse.jetty.ee10.servlet.ServletHolder.handle(ServletHolder.java:736)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1614)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$MappedServlet.handle(ServletHandler.java:1547)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.dispatch(ServletChannel.java:824)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.handle(ServletChannel.java:436)
        at org.eclipse.jetty.ee10.servlet.ServletHandler.handle(ServletHandler.java:464)
        at org.eclipse.jetty.ee10.servlet.SessionHandler.handle(SessionHandler.java:703)
        at org.eclipse.jetty.server.Handler$Wrapper.handle(Handler.java:740)
        at org.eclipse.jetty.websocket.server.WebSocketUpgradeHandler.handle(WebSocketUpgradeHandler.java:227)
        at org.eclipse.jetty.server.handler.ContextHandler.handle(ContextHandler.java:1060)
        at org.eclipse.jetty.server.Server.handle(Server.java:181)
        at org.eclipse.jetty.server.internal.HttpChannelState$HandlerInvoker.run(HttpChannelState.java:648)
        at org.eclipse.jetty.server.internal.HttpConnection.onFillable(HttpConnection.java:403)
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:322)
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:99)
        at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:478)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:441)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:293)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.run(AdaptiveExecutionStrategy.java:201)
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:311)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:979)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1209)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1164)
        at java.base/java.lang.Thread.run(Thread.java:840)
Caused by: org.jkiss.dbeaver.DBException: Too frequent authentication requests
        at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkLoginInterval(BruteForceUtils.java:65)
        at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkBruteforce(BruteForceUtils.java:40)
        at io.cloudbeaver.service.security.CBEmbeddedSecurityController.createNewAuthAttempt(CBEmbeddedSecurityController.java:1620)
        at io.cloudbeaver.service.security.CBEmbeddedSecurityController.authenticate(CBEmbeddedSecurityController.java:1541)
        at io.cloudbeaver.service.auth.impl.WebServiceAuthImpl.authLogin(WebServiceAuthImpl.java:92)
        ... 55 common frames omitted
06-11-2024 12:11:34.763 [qtp404994500-31] DEBUG i.c.server.graphql.GraphQLEndpoint - GraphQL call failed at '/authInfo'
io.cloudbeaver.DBWebException: User authentication failed:
Too frequent authentication requests
        at io.cloudbeaver.service.auth.impl.WebServiceAuthImpl.authLogin(WebServiceAuthImpl.java:115)
        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:569)
        at io.cloudbeaver.service.WebServiceBindingBase$ServiceInvocationHandler.invoke(WebServiceBindingBase.java:170)
        at jdk.proxy7/jdk.proxy7.$Proxy13.authLogin(Unknown Source)
        at io.cloudbeaver.service.auth.WebServiceBindingAuth.lambda$0(WebServiceBindingAuth.java:39)
        at graphql.execution.ExecutionStrategy.invokeDataFetcher(ExecutionStrategy.java:533)
        at graphql.execution.ExecutionStrategy.fetchField(ExecutionStrategy.java:497)
        at graphql.execution.ExecutionStrategy.fetchField(ExecutionStrategy.java:438)
        at graphql.execution.ExecutionStrategy.resolveFieldWithInfo(ExecutionStrategy.java:397)
        at graphql.execution.ExecutionStrategy.getAsyncFieldValueInfo(ExecutionStrategy.java:335)
        at graphql.execution.AsyncExecutionStrategy.execute(AsyncExecutionStrategy.java:57)
        at graphql.execution.Execution.executeOperation(Execution.java:180)
        at graphql.execution.Execution.execute(Execution.java:116)
        at graphql.GraphQL.execute(GraphQL.java:546)
        at graphql.GraphQL.lambda$parseValidateAndExecute$13(GraphQL.java:476)
        at java.base/java.util.concurrent.CompletableFuture.uniComposeStage(CompletableFuture.java:1187)
        at java.base/java.util.concurrent.CompletableFuture.thenCompose(CompletableFuture.java:2309)
        at graphql.GraphQL.parseValidateAndExecute(GraphQL.java:471)
        at graphql.GraphQL.lambda$executeAsync$9(GraphQL.java:429)
        at java.base/java.util.concurrent.CompletableFuture.uniComposeStage(CompletableFuture.java:1187)
        at java.base/java.util.concurrent.CompletableFuture.thenCompose(CompletableFuture.java:2309)
        at graphql.GraphQL.executeAsync(GraphQL.java:418)
        at graphql.GraphQL.execute(GraphQL.java:359)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.executeQuery(GraphQLEndpoint.java:263)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.executeSingleQuery(GraphQLEndpoint.java:207)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.doPost(GraphQLEndpoint.java:190)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:653)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:723)
        at org.eclipse.jetty.ee10.servlet.ServletHolder.handle(ServletHolder.java:736)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1614)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$MappedServlet.handle(ServletHandler.java:1547)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.dispatch(ServletChannel.java:824)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.handle(ServletChannel.java:436)
        at org.eclipse.jetty.ee10.servlet.ServletHandler.handle(ServletHandler.java:464)
        at org.eclipse.jetty.ee10.servlet.SessionHandler.handle(SessionHandler.java:703)
        at org.eclipse.jetty.server.Handler$Wrapper.handle(Handler.java:740)
        at org.eclipse.jetty.websocket.server.WebSocketUpgradeHandler.handle(WebSocketUpgradeHandler.java:227)
        at org.eclipse.jetty.server.handler.ContextHandler.handle(ContextHandler.java:1060)
        at org.eclipse.jetty.server.Server.handle(Server.java:181)
        at org.eclipse.jetty.server.internal.HttpChannelState$HandlerInvoker.run(HttpChannelState.java:648)
        at org.eclipse.jetty.server.internal.HttpConnection.onFillable(HttpConnection.java:403)
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:322)
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:99)
        at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:478)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:441)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:293)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.run(AdaptiveExecutionStrategy.java:201)
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:311)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:979)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1209)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1164)
        at java.base/java.lang.Thread.run(Thread.java:840)
Caused by: org.jkiss.dbeaver.DBException: Too frequent authentication requests
        at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkLoginInterval(BruteForceUtils.java:65)
        at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkBruteforce(BruteForceUtils.java:40)
        at io.cloudbeaver.service.security.CBEmbeddedSecurityController.createNewAuthAttempt(CBEmbeddedSecurityController.java:1620)
        at io.cloudbeaver.service.security.CBEmbeddedSecurityController.authenticate(CBEmbeddedSecurityController.java:1541)
        at io.cloudbeaver.service.auth.impl.WebServiceAuthImpl.authLogin(WebServiceAuthImpl.java:92)
        ... 55 common frames omitted
06-11-2024 12:12:58.406 [qtp404994500-29] ERROR i.c.s.websockets.CBEventsWebSocket - null
java.nio.channels.ClosedChannelException: null
        at org.eclipse.jetty.websocket.core.internal.WebSocketSessionState.onEof(WebSocketSessionState.java:168)
        at org.eclipse.jetty.websocket.core.WebSocketCoreSession.onEof(WebSocketCoreSession.java:229)
        at org.eclipse.jetty.websocket.core.WebSocketConnection.fillAndParse(WebSocketConnection.java:474)
        at org.eclipse.jetty.websocket.core.WebSocketConnection.onFillable(WebSocketConnection.java:332)
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:322)
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:99)
        at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:478)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:441)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:293)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.run(AdaptiveExecutionStrategy.java:201)
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:311)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:979)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1209)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1164)
        at java.base/java.lang.Thread.run(Thread.java:840)
06-11-2024 12:12:58.407 [qtp404994500-29] DEBUG i.c.s.websockets.CBEventsWebSocket - Socket Closed: [1006] Session Closed
06-11-2024 12:13:29.374 [qtp404994500-31] INFO  i.c.s.w.CBJettyWebSocketManager - Websocket created for session: aakdegmmpfrs1hayzat3robbk0
06-11-2024 12:13:29.375 [qtp404994500-31] DEBUG i.c.s.websockets.CBEventsWebSocket - EventWebSocket connected to the aakdegmmpfrs1hayzat3robbk0 session

@DenisSinelnikov
Copy link
Contributor

Hi @boonhanchng
I want to clarify:

  1. Does this behavior work with any user or the one that was created recently, and this is his first/second login attempt? (Maybe the time in the database is different with the server.).
  2. Сould you provide a log from the start of the application until the error is reproduced?

@boonhanchng
Copy link
Author

Hi @DenisSinelnikov
Sorry for late reply.

  1. any user created recently or created previously having same behavior.
Starting Cloudbeaver Server
WARNING: Using incubator modules: jdk.incubator.vector, jdk.incubator.foreign
SLF4J(I): Logback configuration is used: conf/logback.xml
20-11-2024 02:02:22.674 [main] DEBUG i.c.model.app.BaseWebApplication - Loading configuration from /opt/cloudbeaver/conf/cloudbeaver.conf
20-11-2024 02:02:22.675 [main] DEBUG i.c.s.CBServerConfigurationController - Using configuration [/opt/cloudbeaver/conf/cloudbeaver.conf]
20-11-2024 02:02:22.675 [main] DEBUG i.c.s.CBServerConfigurationController - Read configuration [/opt/cloudbeaver/conf/cloudbeaver.conf]
20-11-2024 02:02:22.702 [main] INFO  io.cloudbeaver.server.CBPlatform - Initialize web platform...:
20-11-2024 02:02:22.760 [main] DEBUG o.j.d.runtime.SecurityProviderUtils - BounceCastle bundle found. Use JCE provider BC
20-11-2024 02:02:22.779 [main] DEBUG o.j.d.registry.BasePlatformImpl - Initialize base platform...
20-11-2024 02:02:22.932 [main] DEBUG o.j.d.r.DataSourceProviderRegistry - Total database drivers: 119 (119)
20-11-2024 02:02:22.935 [main] ERROR io.cloudbeaver.server.CBPlatform -         Driver 'yandex_clickhouse' is missing library 'ru.yandex.clickhouse:clickhouse-jdbc:RELEASE'
20-11-2024 02:02:22.937 [main] INFO  io.cloudbeaver.server.CBPlatform - Available drivers: ClickHouse,Db2 for LUW,Db2 for IBM i,Firebird,DuckDB,Hadoop / Apache Kyuubi,Trino,H2 Embedded,H2 Embedded V.2,MS SQL Server / SQL Server,MySQL,MariaDB,Oracle,PostgreSQL,SQLite,LibSQL
20-11-2024 02:02:22.939 [main] INFO  io.cloudbeaver.server.CBPlatform - Web platform initialized (237ms)
20-11-2024 02:02:22.955 [main] INFO  i.c.m.a.BaseServerConfigurationController - Workspace path initialized: /opt/cloudbeaver/workspace
20-11-2024 02:02:22.955 [main] DEBUG i.c.s.CBServerConfigurationController - Runtime configuration [/opt/cloudbeaver/workspace/.data/.cloudbeaver.runtime.conf]
20-11-2024 02:02:22.955 [main] DEBUG i.c.s.CBServerConfigurationController - Read configuration [/opt/cloudbeaver/workspace/.data/.cloudbeaver.runtime.conf]
20-11-2024 02:02:22.960 [main] DEBUG io.cloudbeaver.server.CBApplication - CloudBeaver CE Server 24.2.5.202411181000 is starting
20-11-2024 02:02:22.960 [main] DEBUG io.cloudbeaver.server.CBApplication -      OS: Linux 6.8.0-45-generic (amd64)
20-11-2024 02:02:22.960 [main] DEBUG io.cloudbeaver.server.CBApplication -      Java version: 17.0.12 by Eclipse Adoptium (64bit)
20-11-2024 02:02:22.961 [main] DEBUG io.cloudbeaver.server.CBApplication -      Install path: '/opt/cloudbeaver/server'
20-11-2024 02:02:22.961 [main] DEBUG io.cloudbeaver.server.CBApplication -      Global workspace: 'file:/opt/cloudbeaver/workspace/'
20-11-2024 02:02:22.962 [main] DEBUG io.cloudbeaver.server.CBApplication -      Memory available 84Mb/1024Mb
20-11-2024 02:02:22.962 [main] DEBUG io.cloudbeaver.server.CBApplication -      Content root: /opt/cloudbeaver/web
20-11-2024 02:02:22.962 [main] DEBUG io.cloudbeaver.server.CBApplication -      Drivers storage: /opt/cloudbeaver/drivers
20-11-2024 02:02:22.963 [main] DEBUG io.cloudbeaver.server.CBApplication -      Listen port: 8978 on all interfaces
20-11-2024 02:02:22.963 [main] DEBUG io.cloudbeaver.server.CBApplication -      Base URI: /api/
20-11-2024 02:02:22.964 [main] DEBUG io.cloudbeaver.server.CBApplication -      Production mode
20-11-2024 02:02:22.974 [main] DEBUG io.cloudbeaver.server.CBApplication -      Local host addresses:
20-11-2024 02:02:22.974 [main] DEBUG io.cloudbeaver.server.CBApplication -              10.1.136.180 (cloudbeaver-0.cloudbeaver.elkenvn-environment.svc.cluster.local)
20-11-2024 02:02:22.974 [main] DEBUG io.cloudbeaver.server.CBApplication -              0:0:0:0:0:0:0:1%lo (localhost)
20-11-2024 02:02:23.030 [main] DEBUG i.c.s.auth.ReverseProxyConfigurator - Reverse proxy provider disabled, migration not needed
20-11-2024 02:02:23.040 [main] DEBUG i.c.service.security.db.CBDatabase - Initiate management database
20-11-2024 02:02:23.048 [main] DEBUG i.c.service.security.db.CBDatabase -       Initiate connection pool with management database (MySQL; jdbc:mysql://mysql:3306/cloudbeaver)
20-11-2024 02:02:23.336 [main] DEBUG i.c.service.security.db.CBDatabase -       Connected to MySQL 8.0.39
20-11-2024 02:02:23.455 [main] DEBUG i.c.service.security.db.CBDatabase -       Management database connection established
20-11-2024 02:02:23.470 [main] DEBUG io.cloudbeaver.server.CBApplication - Starting Jetty server (8978 on all interfaces)
20-11-2024 02:02:23.735 [main] DEBUG i.c.server.graphql.GraphQLEndpoint - Schema extensions loaded: WebServiceBindingCore,WebServiceBindingNavigator,WebServiceBindingSQL,WebServiceBindingAdmin,WebServiceBindingAuth,WebServiceBindingDataTransfer,WebServiceBindingFS,WebServiceBindingMetadata,WebServiceBindingRM
20-11-2024 02:02:24.021 [main] DEBUG i.c.server.jetty.CBJettyServer - Max http session idle time: 86400s
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer - Active servlets:
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   static: [/]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   images: [/api/images/*]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   status: [/status]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   graphql: [/api/gql/*]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   sqlResultValueViewer: [/api/sql-result-value/*]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   sqlUploadFile: [/api/resultset/blob/*]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   adminLogs: [/api/logs/*]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   dataTransfer: [/api/data/*]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   dataTransferImport: [/api/data/import/*]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer -   fileSystems: [/api/fs-data/*]
20-11-2024 02:02:24.024 [main] DEBUG i.c.server.jetty.CBJettyServer - Active websocket mappings:
20-11-2024 02:02:24.024 [main] INFO  i.c.server.jetty.CBJettyServer - Refreshing Jetty configuration
20-11-2024 02:02:24.026 [main] INFO  org.eclipse.jetty.server.Server - jetty-12.0.12; built: 2024-07-25T21:58:37.668Z; git: cc6f1b74db755fed228b50701ad967aeaa68e83f; jvm 17.0.12+7
20-11-2024 02:02:24.032 [main] INFO  o.e.j.s.DefaultSessionIdManager - Session workerName=
20-11-2024 02:02:24.040 [main] INFO  o.e.j.server.handler.ContextHandler - Started oeje10s.ServletContextHandler@4ec427{ROOT,/,b=file:///opt/cloudbeaver/web/,a=AVAILABLE,h=oejws.WebSocketUpgradeHandler@4c8d45cf{STARTED}}
20-11-2024 02:02:24.047 [main] INFO  o.e.j.e.s.ServletContextHandler - Started oeje10s.ServletContextHandler@4ec427{ROOT,/,b=file:///opt/cloudbeaver/web/,a=AVAILABLE,h=oejws.WebSocketUpgradeHandler@4c8d45cf{STARTED}}
20-11-2024 02:02:24.052 [main] INFO  o.e.jetty.server.AbstractConnector - Started ServerConnector@11015ca0{HTTP/1.1, (http/1.1)}{0.0.0.0:8978}
20-11-2024 02:02:24.053 [main] INFO  org.eclipse.jetty.server.Server - Started oejs.Server@6630dd28{STARTING}[12.0.12,sto=0] @2730ms
20-11-2024 02:06:39.092 [qtp1005928818-31] DEBUG i.c.s.session.WebSessionManager - New web session '1iwphnlcvnuhiu0b85jaccztt0'
20-11-2024 02:06:41.236 [qtp1005928818-33] INFO  i.c.s.w.CBJettyWebSocketManager - Websocket created for session: 1iwphnlcvnuhiu0b85jaccztt0
20-11-2024 02:06:41.249 [qtp1005928818-31] DEBUG i.c.server.graphql.GraphQLEndpoint - API > serverConfig
20-11-2024 02:06:41.259 [qtp1005928818-33] DEBUG i.c.s.websockets.CBEventsWebSocket - EventWebSocket connected to the 1iwphnlcvnuhiu0b85jaccztt0 session
20-11-2024 02:06:41.337 [qtp1005928818-32] DEBUG i.c.server.graphql.GraphQLEndpoint - API > openSession
20-11-2024 02:06:41.365 [qtp1005928818-36] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getActiveUser
20-11-2024 02:06:41.432 [qtp1005928818-33] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getProductInfo
20-11-2024 02:06:41.482 [qtp1005928818-41] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getAuthProviders
20-11-2024 02:06:41.565 [qtp1005928818-33] DEBUG i.c.server.graphql.GraphQLEndpoint - API > sessionPermissions
20-11-2024 02:06:41.586 [qtp1005928818-29] DEBUG i.c.model.session.WebSession - Update session lifetime 1iwphnlcvnuhiu0b85jaccztt0 for user null
20-11-2024 02:06:52.288 [qtp1005928818-29] DEBUG i.c.s.session.WebSessionManager - Couldn't restore previous user session 'lmvtgcgbapxye31m09l0n5dt169'
20-11-2024 02:06:52.288 [qtp1005928818-29] DEBUG i.c.s.w.CBJettyWebSocketManager - CloudBeaver web session not exist, try to create headless session
20-11-2024 02:06:52.288 [qtp1005928818-29] DEBUG i.c.s.w.CBJettyWebSocketManager - Couldn't create headless session
20-11-2024 02:06:52.514 [qtp1005928818-31] DEBUG i.c.server.graphql.GraphQLEndpoint - API > authLogin
20-11-2024 02:06:52.591 [qtp1005928818-31] DEBUG i.c.model.session.WebUserContext - refresh permissions bhchng f108e9e6-5b86-4154-a3d1-aa53753a28ad
20-11-2024 02:06:52.594 [qtp1005928818-31] DEBUG i.c.model.session.WebUserContext - refresh permissions bhchng f108e9e6-5b86-4154-a3d1-aa53753a28ad
20-11-2024 02:06:52.624 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getActiveUser
20-11-2024 02:06:52.686 [qtp1005928818-33] DEBUG i.c.server.graphql.GraphQLEndpoint - API > openSession
20-11-2024 02:06:52.729 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getActiveUser
20-11-2024 02:06:52.737 [qtp1005928818-35] DEBUG i.c.server.graphql.GraphQLEndpoint - API > sessionPermissions
20-11-2024 02:06:52.774 [qtp1005928818-36] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getServerLicenseStatus
20-11-2024 02:06:52.795 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getProjectList
20-11-2024 02:06:52.833 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getTemplateConnections
20-11-2024 02:06:52.833 [qtp1005928818-35] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getUserConnections
20-11-2024 02:06:52.892 [qtp1005928818-36] DEBUG i.c.server.graphql.GraphQLEndpoint - API > executionContextList
20-11-2024 02:06:52.895 [qtp1005928818-35] DEBUG i.c.server.graphql.GraphQLEndpoint - API > navNodeChildren
20-11-2024 02:06:52.898 [qtp1005928818-42] DEBUG i.c.server.graphql.GraphQLEndpoint - API > driverList
20-11-2024 02:06:53.006 [qtp1005928818-36] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getNodeParents
20-11-2024 02:06:53.010 [qtp1005928818-43] DEBUG i.c.server.graphql.GraphQLEndpoint - API > navNodeChildren
20-11-2024 02:06:54.838 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > executionContextList
20-11-2024 02:06:54.863 [qtp1005928818-41] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getUserConnections
20-11-2024 02:06:54.909 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > navNodeChildren
20-11-2024 02:06:54.909 [qtp1005928818-41] DEBUG i.c.server.graphql.GraphQLEndpoint - API > initConnection
20-11-2024 02:06:54.915 [qtp1005928818-41] DEBUG o.j.d.registry.DataSourceDescriptor - Connect with 'elkenvn' (mysql8-192dbee4138-3babcde487179433)
20-11-2024 02:06:54.933 [qtp1005928818-41] DEBUG o.j.d.m.i.AbstractExecutionContext - Execution context opened (elkenvn; Main; 0)
20-11-2024 02:06:54.937 [qtp1005928818-41] DEBUG o.j.d.e.mysql.model.MySQLDataSource - Set autoDeserialize:false
20-11-2024 02:06:54.937 [qtp1005928818-41] DEBUG o.j.d.e.mysql.model.MySQLDataSource - Set allowUrlInLocalInfile:false
20-11-2024 02:06:54.937 [qtp1005928818-41] DEBUG o.j.d.e.mysql.model.MySQLDataSource - Set allowLocalInfile:false
20-11-2024 02:06:54.937 [qtp1005928818-41] DEBUG o.j.d.e.mysql.model.MySQLDataSource - Set allowLoadLocalInfile:false
20-11-2024 02:06:54.952 [qtp1005928818-41] DEBUG o.j.d.m.i.AbstractExecutionContext - Execution context opened (elkenvn; Metadata; 1)
20-11-2024 02:06:54.952 [qtp1005928818-41] DEBUG o.j.d.e.mysql.model.MySQLDataSource - Set autoDeserialize:false
20-11-2024 02:06:54.952 [qtp1005928818-41] DEBUG o.j.d.e.mysql.model.MySQLDataSource - Set allowUrlInLocalInfile:false
20-11-2024 02:06:54.952 [qtp1005928818-41] DEBUG o.j.d.e.mysql.model.MySQLDataSource - Set allowLocalInfile:false
20-11-2024 02:06:54.952 [qtp1005928818-41] DEBUG o.j.d.e.mysql.model.MySQLDataSource - Set allowLoadLocalInfile:false
20-11-2024 02:06:54.985 [qtp1005928818-41] DEBUG o.j.d.registry.DataSourceDescriptor - Connected (mysql8-192dbee4138-3babcde487179433, MySQL Connector/J [mysql-connector-j-8.2.0 (Revision: 06a1f724497fd81c6a659131fda822c9e5085b6c)])
20-11-2024 02:06:55.024 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > executionContextCreate
20-11-2024 02:06:55.024 [qtp1005928818-41] DEBUG i.c.server.graphql.GraphQLEndpoint - API > navNodeChildren
20-11-2024 02:06:55.055 [qtp1005928818-32] DEBUG i.c.server.graphql.GraphQLEndpoint - API > navNodeChildren
20-11-2024 02:06:55.083 [qtp1005928818-41] DEBUG i.c.server.graphql.GraphQLEndpoint - API > querySqlDialectInfo
20-11-2024 02:06:55.086 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > executionContextList
20-11-2024 02:06:55.113 [qtp1005928818-34] DEBUG i.c.server.graphql.GraphQLEndpoint - API > navGetStructContainers
20-11-2024 02:06:55.173 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > navGetStructContainers
20-11-2024 02:06:56.895 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getUserProfileProperties
20-11-2024 02:06:56.951 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getPasswordPolicy
20-11-2024 02:06:56.951 [qtp1005928818-41] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getActiveUserMetaParameters
20-11-2024 02:06:57.147 [qtp1005928818-34] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:06:57.150 [qtp1005928818-33] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:06:57.150 [qtp1005928818-41] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:06:57.157 [qtp1005928818-29] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:06:57.160 [qtp1005928818-35] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:06:57.160 [qtp1005928818-45] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:06:57.161 [qtp1005928818-42] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:06:57.161 [qtp1005928818-44] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:06:57.161 [qtp1005928818-46] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:06:57.163 [qtp1005928818-36] DEBUG i.c.server.graphql.GraphQLEndpoint - API > parseSQLScript
20-11-2024 02:07:00.071 [qtp1005928818-43] DEBUG i.c.server.graphql.GraphQLEndpoint - API > authLogout
20-11-2024 02:07:00.094 [DBeaver: Disconnect from data sources] DEBUG o.j.d.m.i.AbstractExecutionContext - Execution context closed (elkenvn, 0)
20-11-2024 02:07:00.105 [DBeaver: Disconnect from data sources] DEBUG o.j.d.m.i.AbstractExecutionContext - Execution context closed (elkenvn, 1)
20-11-2024 02:07:00.105 [DBeaver: Disconnect from data sources] DEBUG o.j.d.registry.DataSourceDescriptor - Disconnected (mysql8-192dbee4138-3babcde487179433)
20-11-2024 02:07:00.178 [qtp1005928818-44] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getActiveUser
20-11-2024 02:07:00.225 [qtp1005928818-32] DEBUG i.c.server.graphql.GraphQLEndpoint - API > openSession
20-11-2024 02:07:00.248 [qtp1005928818-44] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getActiveUser
20-11-2024 02:07:00.258 [qtp1005928818-32] DEBUG i.c.server.graphql.GraphQLEndpoint - API > sessionPermissions
20-11-2024 02:07:02.096 [qtp1005928818-32] DEBUG i.c.s.websockets.CBEventsWebSocket - Socket Closed: [1001] null
20-11-2024 02:07:02.236 [qtp1005928818-32] INFO  i.c.s.w.CBJettyWebSocketManager - Websocket created for session: 1iwphnlcvnuhiu0b85jaccztt0
20-11-2024 02:07:02.237 [qtp1005928818-32] DEBUG i.c.s.websockets.CBEventsWebSocket - EventWebSocket connected to the 1iwphnlcvnuhiu0b85jaccztt0 session
20-11-2024 02:07:02.266 [qtp1005928818-32] DEBUG i.c.server.graphql.GraphQLEndpoint - API > serverConfig
20-11-2024 02:07:02.290 [qtp1005928818-44] DEBUG i.c.server.graphql.GraphQLEndpoint - API > openSession
20-11-2024 02:07:02.315 [qtp1005928818-32] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getActiveUser
20-11-2024 02:07:02.361 [qtp1005928818-44] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getProductInfo
20-11-2024 02:07:02.370 [qtp1005928818-32] DEBUG i.c.server.graphql.GraphQLEndpoint - API > getAuthProviders
20-11-2024 02:07:02.431 [qtp1005928818-32] DEBUG i.c.server.graphql.GraphQLEndpoint - API > sessionPermissions
20-11-2024 02:07:02.431 [qtp1005928818-44] DEBUG i.c.model.session.WebSession - Update session lifetime 1iwphnlcvnuhiu0b85jaccztt0 for user null
20-11-2024 02:07:23.306 [qtp1005928818-44] DEBUG i.c.s.session.WebSessionManager - Couldn't restore previous user session 'lmvtgcgbapxye31m09l0n5dt169'
20-11-2024 02:07:23.306 [qtp1005928818-44] DEBUG i.c.s.w.CBJettyWebSocketManager - CloudBeaver web session not exist, try to create headless session
20-11-2024 02:07:23.306 [qtp1005928818-44] DEBUG i.c.s.w.CBJettyWebSocketManager - Couldn't create headless session
20-11-2024 02:07:25.464 [qtp1005928818-44] DEBUG i.c.server.graphql.GraphQLEndpoint - API > authLogin
20-11-2024 02:07:25.470 [qtp1005928818-44] ERROR i.c.service.WebServiceBindingBase - Unexpected error during gql request
io.cloudbeaver.DBWebException: User authentication failed:
Too frequent authentication requests
        at io.cloudbeaver.service.auth.impl.WebServiceAuthImpl.authLogin(WebServiceAuthImpl.java:115)
        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:569)
        at io.cloudbeaver.service.WebServiceBindingBase$ServiceInvocationHandler.invoke(WebServiceBindingBase.java:170)
        at jdk.proxy8/jdk.proxy8.$Proxy14.authLogin(Unknown Source)
        at io.cloudbeaver.service.auth.WebServiceBindingAuth.lambda$0(WebServiceBindingAuth.java:39)
        at graphql.execution.ExecutionStrategy.invokeDataFetcher(ExecutionStrategy.java:533)
        at graphql.execution.ExecutionStrategy.fetchField(ExecutionStrategy.java:497)
        at graphql.execution.ExecutionStrategy.fetchField(ExecutionStrategy.java:438)
        at graphql.execution.ExecutionStrategy.resolveFieldWithInfo(ExecutionStrategy.java:397)
        at graphql.execution.ExecutionStrategy.getAsyncFieldValueInfo(ExecutionStrategy.java:335)
        at graphql.execution.AsyncExecutionStrategy.execute(AsyncExecutionStrategy.java:57)
        at graphql.execution.Execution.executeOperation(Execution.java:180)
        at graphql.execution.Execution.execute(Execution.java:116)
        at graphql.GraphQL.execute(GraphQL.java:546)
        at graphql.GraphQL.lambda$parseValidateAndExecute$13(GraphQL.java:476)
        at java.base/java.util.concurrent.CompletableFuture.uniComposeStage(CompletableFuture.java:1187)
        at java.base/java.util.concurrent.CompletableFuture.thenCompose(CompletableFuture.java:2309)
        at graphql.GraphQL.parseValidateAndExecute(GraphQL.java:471)
        at graphql.GraphQL.lambda$executeAsync$9(GraphQL.java:429)
        at java.base/java.util.concurrent.CompletableFuture.uniComposeStage(CompletableFuture.java:1187)
        at java.base/java.util.concurrent.CompletableFuture.thenCompose(CompletableFuture.java:2309)
        at graphql.GraphQL.executeAsync(GraphQL.java:418)
        at graphql.GraphQL.execute(GraphQL.java:359)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.executeQuery(GraphQLEndpoint.java:263)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.executeSingleQuery(GraphQLEndpoint.java:207)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.doPost(GraphQLEndpoint.java:190)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:653)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:723)
        at org.eclipse.jetty.ee10.servlet.ServletHolder.handle(ServletHolder.java:736)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1614)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$MappedServlet.handle(ServletHandler.java:1547)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.dispatch(ServletChannel.java:824)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.handle(ServletChannel.java:436)
        at org.eclipse.jetty.ee10.servlet.ServletHandler.handle(ServletHandler.java:464)
        at org.eclipse.jetty.ee10.servlet.SessionHandler.handle(SessionHandler.java:703)
        at org.eclipse.jetty.server.Handler$Wrapper.handle(Handler.java:740)
        at org.eclipse.jetty.websocket.server.WebSocketUpgradeHandler.handle(WebSocketUpgradeHandler.java:227)
        at org.eclipse.jetty.server.handler.ContextHandler.handle(ContextHandler.java:1060)
        at org.eclipse.jetty.server.Server.handle(Server.java:181)
        at org.eclipse.jetty.server.internal.HttpChannelState$HandlerInvoker.run(HttpChannelState.java:648)
        at org.eclipse.jetty.server.internal.HttpConnection.onFillable(HttpConnection.java:403)
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:322)
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:99)
        at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:478)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:441)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:293)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.run(AdaptiveExecutionStrategy.java:201)
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:311)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:979)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1209)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1164)
        at java.base/java.lang.Thread.run(Thread.java:840)
Caused by: org.jkiss.dbeaver.DBException: Too frequent authentication requests
        at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkLoginInterval(BruteForceUtils.java:65)
        at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkBruteforce(BruteForceUtils.java:40)
        at io.cloudbeaver.service.security.CBEmbeddedSecurityController.createNewAuthAttempt(CBEmbeddedSecurityController.java:1659)
        at io.cloudbeaver.service.security.CBEmbeddedSecurityController.authenticate(CBEmbeddedSecurityController.java:1580)
        at io.cloudbeaver.service.auth.impl.WebServiceAuthImpl.authLogin(WebServiceAuthImpl.java:92)
        ... 55 common frames omitted
20-11-2024 02:07:25.472 [qtp1005928818-44] DEBUG i.c.server.graphql.GraphQLEndpoint - GraphQL call failed at '/authInfo'
io.cloudbeaver.DBWebException: User authentication failed:
Too frequent authentication requests
        at io.cloudbeaver.service.auth.impl.WebServiceAuthImpl.authLogin(WebServiceAuthImpl.java:115)
        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:569)
        at io.cloudbeaver.service.WebServiceBindingBase$ServiceInvocationHandler.invoke(WebServiceBindingBase.java:170)
        at jdk.proxy8/jdk.proxy8.$Proxy14.authLogin(Unknown Source)
        at io.cloudbeaver.service.auth.WebServiceBindingAuth.lambda$0(WebServiceBindingAuth.java:39)
        at graphql.execution.ExecutionStrategy.invokeDataFetcher(ExecutionStrategy.java:533)
        at graphql.execution.ExecutionStrategy.fetchField(ExecutionStrategy.java:497)
        at graphql.execution.ExecutionStrategy.fetchField(ExecutionStrategy.java:438)
        at graphql.execution.ExecutionStrategy.resolveFieldWithInfo(ExecutionStrategy.java:397)
        at graphql.execution.ExecutionStrategy.getAsyncFieldValueInfo(ExecutionStrategy.java:335)
        at graphql.execution.AsyncExecutionStrategy.execute(AsyncExecutionStrategy.java:57)
        at graphql.execution.Execution.executeOperation(Execution.java:180)
        at graphql.execution.Execution.execute(Execution.java:116)
        at graphql.GraphQL.execute(GraphQL.java:546)
        at graphql.GraphQL.lambda$parseValidateAndExecute$13(GraphQL.java:476)
        at java.base/java.util.concurrent.CompletableFuture.uniComposeStage(CompletableFuture.java:1187)
        at java.base/java.util.concurrent.CompletableFuture.thenCompose(CompletableFuture.java:2309)
        at graphql.GraphQL.parseValidateAndExecute(GraphQL.java:471)
        at graphql.GraphQL.lambda$executeAsync$9(GraphQL.java:429)
        at java.base/java.util.concurrent.CompletableFuture.uniComposeStage(CompletableFuture.java:1187)
        at java.base/java.util.concurrent.CompletableFuture.thenCompose(CompletableFuture.java:2309)
        at graphql.GraphQL.executeAsync(GraphQL.java:418)
        at graphql.GraphQL.execute(GraphQL.java:359)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.executeQuery(GraphQLEndpoint.java:263)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.executeSingleQuery(GraphQLEndpoint.java:207)
        at io.cloudbeaver.server.graphql.GraphQLEndpoint.doPost(GraphQLEndpoint.java:190)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:653)
        at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:723)
        at org.eclipse.jetty.ee10.servlet.ServletHolder.handle(ServletHolder.java:736)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1614)
        at org.eclipse.jetty.ee10.servlet.ServletHandler$MappedServlet.handle(ServletHandler.java:1547)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.dispatch(ServletChannel.java:824)
        at org.eclipse.jetty.ee10.servlet.ServletChannel.handle(ServletChannel.java:436)
        at org.eclipse.jetty.ee10.servlet.ServletHandler.handle(ServletHandler.java:464)
        at org.eclipse.jetty.ee10.servlet.SessionHandler.handle(SessionHandler.java:703)
        at org.eclipse.jetty.server.Handler$Wrapper.handle(Handler.java:740)
        at org.eclipse.jetty.websocket.server.WebSocketUpgradeHandler.handle(WebSocketUpgradeHandler.java:227)
        at org.eclipse.jetty.server.handler.ContextHandler.handle(ContextHandler.java:1060)
        at org.eclipse.jetty.server.Server.handle(Server.java:181)
        at org.eclipse.jetty.server.internal.HttpChannelState$HandlerInvoker.run(HttpChannelState.java:648)
        at org.eclipse.jetty.server.internal.HttpConnection.onFillable(HttpConnection.java:403)
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:322)
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:99)
        at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:478)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:441)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:293)
        at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.run(AdaptiveExecutionStrategy.java:201)
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:311)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:979)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1209)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1164)
        at java.base/java.lang.Thread.run(Thread.java:840)
Caused by: org.jkiss.dbeaver.DBException: Too frequent authentication requests
        at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkLoginInterval(BruteForceUtils.java:65)
        at io.cloudbeaver.service.security.bruteforce.BruteForceUtils.checkBruteforce(BruteForceUtils.java:40)
        at io.cloudbeaver.service.security.CBEmbeddedSecurityController.createNewAuthAttempt(CBEmbeddedSecurityController.java:1659)
        at io.cloudbeaver.service.security.CBEmbeddedSecurityController.authenticate(CBEmbeddedSecurityController.java:1580)
        at io.cloudbeaver.service.auth.impl.WebServiceAuthImpl.authLogin(WebServiceAuthImpl.java:92)
        ... 55 common frames omitted

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@DenisSinelnikov DenisSinelnikov

Labels
bug Something isn't working local config wait for response xf:authentication
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@boonhanchng @EvgeniaBzzz @DenisSinelnikov