[
https://issues.apache.org/jira/browse/GUACAMOLE-2031?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
gihan updated GUACAMOLE-2031:
-----------------------------
Description:
Hi,
I have installed guacamole 1.6.0 on a linux server 24.04, and I'm getting the
error below while launching a remote app published on the server using "Remote
App Tool". Below is the error message:
!image-2025-02-12-14-45-20-916.png!
I have included the debug logs as well. I have another server running 1.5.5
connecting to the same session host and it works fine. Issue only happens with
version 1.6.0.
I’ve also attached the debug logs for your reference.
21:19:22.656 [http-nio-8080-exec-2] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2129705300 to pool.
SLF4J(E): Failed toString() invocation on an object of type
[org.apache.guacamole.event.RemoteAddress]
SLF4J(E): Reported exception:
java.lang.IllegalStateException: The request object has been recycled and is no
longer associated with this facade
at
org.apache.catalina.connector.RequestFacade.checkFacade(RequestFacade.java:856)
at
org.apache.catalina.connector.RequestFacade.getHeader(RequestFacade.java:516)
at
javax.servlet.http.HttpServletRequestWrapper.getHeader(HttpServletRequestWrapper.java:82)
at
javax.servlet.http.HttpServletRequestWrapper.getHeader(HttpServletRequestWrapper.java:82)
at
javax.servlet.http.HttpServletRequestWrapper.getHeader(HttpServletRequestWrapper.java:82)
at
org.apache.guacamole.event.RemoteAddress.toString(RemoteAddress.java:86)
at
org.slf4j.helpers.MessageFormatter.safeObjectAppend(MessageFormatter.java:291)
at
org.slf4j.helpers.MessageFormatter.deeplyAppendParameter(MessageFormatter.java:263)
at
org.slf4j.helpers.MessageFormatter.arrayFormat(MessageFormatter.java:225)
at
org.slf4j.helpers.MessageFormatter.arrayFormat(MessageFormatter.java:160)
at
ch.qos.logback.classic.spi.LoggingEvent.getFormattedMessage(LoggingEvent.java:396)
at
ch.qos.logback.classic.spi.LoggingEvent.prepareForDeferredProcessing(LoggingEvent.java:252)
at
ch.qos.logback.core.OutputStreamAppender.subAppend(OutputStreamAppender.java:235)
at
ch.qos.logback.core.OutputStreamAppender.append(OutputStreamAppender.java:102)
at
ch.qos.logback.core.UnsynchronizedAppenderBase.doAppend(UnsynchronizedAppenderBase.java:85)
at
ch.qos.logback.core.spi.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:51)
at ch.qos.logback.classic.Logger.appendLoopOnAppenders(Logger.java:272)
at ch.qos.logback.classic.Logger.callAppenders(Logger.java:259)
at
ch.qos.logback.classic.Logger.buildLoggingEventAndAppend(Logger.java:426)
at ch.qos.logback.classic.Logger.filterAndLog_2(Logger.java:419)
at ch.qos.logback.classic.Logger.debug(Logger.java:495)
at
org.apache.guacamole.event.EventLoggingListener.logSuccess(EventLoggingListener.java:165)
at
org.apache.guacamole.event.EventLoggingListener.handleEvent(EventLoggingListener.java:225)
at
org.apache.guacamole.rest.event.ListenerService.handleEvent(ListenerService.java:53)
at
org.apache.guacamole.rest.auth.AuthenticationService.authenticate(AuthenticationService.java:409)
at
org.apache.guacamole.rest.auth.TokenRESTService.createToken(TokenRESTService.java:174)
at
java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:103)
at java.base/java.lang.reflect.Method.invoke(Method.java:580)
at
org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory.lambda$static$0(ResourceMethodInvocationHandlerFactory.java:52)
at
org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:146)
at
org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:189)
at
org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$TypeOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:219)
at
org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:93)
at
org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:478)
at
org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:400)
at
org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:81)
at
org.glassfish.jersey.server.ServerRuntime$1.run(ServerRuntime.java:256)
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248)
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244)
at org.glassfish.jersey.internal.Errors.process(Errors.java:292)
at org.glassfish.jersey.internal.Errors.process(Errors.java:274)
at org.glassfish.jersey.internal.Errors.process(Errors.java:244)
at
org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:265)
at
org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:235)
at
org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:684)
at
org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:397)
at
org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:349)
at
org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:379)
at
org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:312)
at
org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:205)
at
com.google.inject.servlet.ServletDefinition.doServiceImpl(ServletDefinition.java:290)
at
com.google.inject.servlet.ServletDefinition.doService(ServletDefinition.java:280)
at
com.google.inject.servlet.ServletDefinition.service(ServletDefinition.java:184)
at
com.google.inject.servlet.ManagedServletPipeline.service(ManagedServletPipeline.java:89)
at
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:85)
at
com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:121)
at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:133)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:168)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:144)
at
org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:168)
at
org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:90)
at
org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:482)
at
org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:130)
at
org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:93)
at
org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:660)
at
org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74)
at
org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:346)
at
org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:396)
at
org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:63)
at
org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937)
at
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1791)
at
org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63)
at java.base/java.lang.Thread.run(Thread.java:1583)
21:19:22.656 [http-nio-8080-exec-2] DEBUG o.a.g.event.EventLoggingListener -
User "guacadmin" (authenticated by "mysql") successfully re-authenticated their
existing session from [FAILED toString()]
21:19:22.724 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession not set for thread: 30, creating a new one
21:19:22.724 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.724 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2071790073 from pool.
21:19:22.724 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.725 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.726 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.727 [http-nio-8080-exec-5] DEBUG o.a.g.a.mysql.conf.MySQLEnvironment -
Database recognized as MySQL 8.0.41.
21:19:22.728 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Preparing: WITH RECURSIVE
related_entity(entity_id) AS ( SELECT guacamole_user_group.entity_id FROM
guacamole_user_group JOIN guacamole_user_group_member ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group_member.member_entity_id = ? AND
guacamole_user_group.disabled = false UNION SELECT
guacamole_user_group.entity_id FROM related_entity JOIN
guacamole_user_group_member ON related_entity.entity_id =
guacamole_user_group_member.member_entity_id JOIN guacamole_user_group ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group.disabled = false ) SELECT name FROM related_entity
JOIN guacamole_entity ON related_entity.entity_id = guacamole_entity.entity_id
WHERE guacamole_entity.type = 'USER_GROUP';
21:19:22.729 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Parameters: 1(Integer)
21:19:22.731 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - <== Total: 0
21:19:22.732 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 committing
21:19:22.732 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 terminated its life-cycle, closing it
21:19:22.733 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Resetting autocommit to true on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.735 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.736 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2071790073 to pool.
21:19:22.739 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession not set for thread: 30, creating a new one
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2129705300 from pool.
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2129705300 ...
21:19:22.741 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2129705300 is GOOD!
21:19:22.741 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.742 [http-nio-8080-exec-5] DEBUG o.a.g.a.mysql.conf.MySQLEnvironment -
Database recognized as MySQL 8.0.41.
21:19:22.742 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Preparing: WITH RECURSIVE
related_entity(entity_id) AS ( SELECT guacamole_user_group.entity_id FROM
guacamole_user_group JOIN guacamole_user_group_member ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group_member.member_entity_id = ? AND
guacamole_user_group.disabled = false UNION SELECT
guacamole_user_group.entity_id FROM related_entity JOIN
guacamole_user_group_member ON related_entity.entity_id =
guacamole_user_group_member.member_entity_id JOIN guacamole_user_group ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group.disabled = false ) SELECT name FROM related_entity
JOIN guacamole_entity ON related_entity.entity_id = guacamole_entity.entity_id
WHERE guacamole_entity.type = 'USER_GROUP';
21:19:22.743 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Parameters: 1(Integer)
21:19:22.745 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - <== Total: 0
21:19:22.746 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 committing
21:19:22.746 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 terminated its life-cycle, closing it
21:19:22.746 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Resetting autocommit to true on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.747 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.747 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2129705300 ...
21:19:22.749 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2129705300 is GOOD!
21:19:22.749 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2129705300 to pool.
21:19:22.750 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.751 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2071790073 from pool.
21:19:22.751 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.753 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.753 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.755 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.p.S.selectOne - ==>
Preparing: SELECT DISTINCT ? AS entity_id, permission FROM
guacamole_system_permission WHERE ( entity_id = ? ) AND permission = ?
21:19:22.756 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.p.S.selectOne - ==>
Parameters: 1(Integer), 1(Integer), ADMINISTER(String)
21:19:22.758 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.p.S.selectOne - <==
Total: 1
21:19:22.759 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Resetting autocommit to true on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.760 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.760 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.761 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.761 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2071790073 to pool.
21:19:22.762 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.762 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2129705300 from pool.
21:19:22.762 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2129705300 ...
21:19:22.764 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2129705300 is GOOD!
21:19:22.764 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.766 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
==> Preparing: SELECT guacamole_connection.connection_id,
guacamole_connection.connection_name, parent_id, protocol, max_connections,
max_connections_per_user, proxy_hostname, proxy_port, proxy_encryption_method,
connection_weight, failover_only, MAX(start_date) AS last_active FROM
guacamole_connection LEFT JOIN guacamole_connection_history ON
guacamole_connection_history.connection_id = guacamole_connection.connection_id
WHERE guacamole_connection.connection_id IN ( ? ) GROUP BY
guacamole_connection.connection_id; SELECT primary_connection_id,
sharing_profile_id FROM guacamole_sharing_profile WHERE primary_connection_id
IN ( ? ) ; SELECT connection_id, attribute_name, attribute_value FROM
guacamole_connection_attribute WHERE connection_id IN ( ? ) ;
21:19:22.766 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
==> Parameters: 1(String), 1(String), 1(String)
21:19:22.771 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
<== Total: 1
21:19:22.773 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
<== Total: 0
21:19:22.773 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
<== Total: 0
21:19:22.774 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Resetting autocommit to true on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.775 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.775 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2129705300 ...
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2129705300 is GOOD!
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2129705300 to pool.
21:19:22.776 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession not set for thread: 30, creating a new one
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2071790073 from pool.
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.777 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.777 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.779 [http-nio-8080-exec-5] DEBUG o.a.g.a.mysql.conf.MySQLEnvironment -
Database recognized as MySQL 8.0.41.
21:19:22.781 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Preparing: WITH RECURSIVE
related_entity(entity_id) AS ( SELECT guacamole_user_group.entity_id FROM
guacamole_user_group JOIN guacamole_user_group_member ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group_member.member_entity_id = ? AND
guacamole_user_group.disabled = false UNION SELECT
guacamole_user_group.entity_id FROM related_entity JOIN
guacamole_user_group_member ON related_entity.entity_id =
guacamole_user_group_member.member_entity_id JOIN guacamole_user_group ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group.disabled = false ) SELECT name FROM related_entity
JOIN guacamole_entity ON related_entity.entity_id = guacamole_entity.entity_id
WHERE guacamole_entity.type = 'USER_GROUP';
21:19:22.783 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Parameters: 1(Integer)
21:19:22.788 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - <== Total: 0
21:19:22.788 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 committing
was:
Hi,
I have installed guacamole 1.6.0 on a linux server 24.04, and I'm getting the
error below while launching a remote app published on the server using "Remote
App Tool". Below is the error message:
!image-2025-02-12-14-45-20-916.png!
21:19:22.656 [http-nio-8080-exec-2] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2129705300 to pool.
SLF4J(E): Failed toString() invocation on an object of type
[org.apache.guacamole.event.RemoteAddress]
SLF4J(E): Reported exception:
java.lang.IllegalStateException: The request object has been recycled and is no
longer associated with this facade
at
org.apache.catalina.connector.RequestFacade.checkFacade(RequestFacade.java:856)
at
org.apache.catalina.connector.RequestFacade.getHeader(RequestFacade.java:516)
at
javax.servlet.http.HttpServletRequestWrapper.getHeader(HttpServletRequestWrapper.java:82)
at
javax.servlet.http.HttpServletRequestWrapper.getHeader(HttpServletRequestWrapper.java:82)
at
javax.servlet.http.HttpServletRequestWrapper.getHeader(HttpServletRequestWrapper.java:82)
at
org.apache.guacamole.event.RemoteAddress.toString(RemoteAddress.java:86)
at
org.slf4j.helpers.MessageFormatter.safeObjectAppend(MessageFormatter.java:291)
at
org.slf4j.helpers.MessageFormatter.deeplyAppendParameter(MessageFormatter.java:263)
at
org.slf4j.helpers.MessageFormatter.arrayFormat(MessageFormatter.java:225)
at
org.slf4j.helpers.MessageFormatter.arrayFormat(MessageFormatter.java:160)
at
ch.qos.logback.classic.spi.LoggingEvent.getFormattedMessage(LoggingEvent.java:396)
at
ch.qos.logback.classic.spi.LoggingEvent.prepareForDeferredProcessing(LoggingEvent.java:252)
at
ch.qos.logback.core.OutputStreamAppender.subAppend(OutputStreamAppender.java:235)
at
ch.qos.logback.core.OutputStreamAppender.append(OutputStreamAppender.java:102)
at
ch.qos.logback.core.UnsynchronizedAppenderBase.doAppend(UnsynchronizedAppenderBase.java:85)
at
ch.qos.logback.core.spi.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:51)
at ch.qos.logback.classic.Logger.appendLoopOnAppenders(Logger.java:272)
at ch.qos.logback.classic.Logger.callAppenders(Logger.java:259)
at
ch.qos.logback.classic.Logger.buildLoggingEventAndAppend(Logger.java:426)
at ch.qos.logback.classic.Logger.filterAndLog_2(Logger.java:419)
at ch.qos.logback.classic.Logger.debug(Logger.java:495)
at
org.apache.guacamole.event.EventLoggingListener.logSuccess(EventLoggingListener.java:165)
at
org.apache.guacamole.event.EventLoggingListener.handleEvent(EventLoggingListener.java:225)
at
org.apache.guacamole.rest.event.ListenerService.handleEvent(ListenerService.java:53)
at
org.apache.guacamole.rest.auth.AuthenticationService.authenticate(AuthenticationService.java:409)
at
org.apache.guacamole.rest.auth.TokenRESTService.createToken(TokenRESTService.java:174)
at
java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:103)
at java.base/java.lang.reflect.Method.invoke(Method.java:580)
at
org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory.lambda$static$0(ResourceMethodInvocationHandlerFactory.java:52)
at
org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:146)
at
org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:189)
at
org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$TypeOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:219)
at
org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:93)
at
org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:478)
at
org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:400)
at
org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:81)
at
org.glassfish.jersey.server.ServerRuntime$1.run(ServerRuntime.java:256)
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248)
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244)
at org.glassfish.jersey.internal.Errors.process(Errors.java:292)
at org.glassfish.jersey.internal.Errors.process(Errors.java:274)
at org.glassfish.jersey.internal.Errors.process(Errors.java:244)
at
org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:265)
at
org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:235)
at
org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:684)
at
org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:397)
at
org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:349)
at
org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:379)
at
org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:312)
at
org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:205)
at
com.google.inject.servlet.ServletDefinition.doServiceImpl(ServletDefinition.java:290)
at
com.google.inject.servlet.ServletDefinition.doService(ServletDefinition.java:280)
at
com.google.inject.servlet.ServletDefinition.service(ServletDefinition.java:184)
at
com.google.inject.servlet.ManagedServletPipeline.service(ManagedServletPipeline.java:89)
at
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:85)
at
com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:121)
at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:133)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:168)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:144)
at
org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:168)
at
org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:90)
at
org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:482)
at
org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:130)
at
org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:93)
at
org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:660)
at
org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74)
at
org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:346)
at
org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:396)
at
org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:63)
at
org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937)
at
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1791)
at
org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190)
at
org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63)
at java.base/java.lang.Thread.run(Thread.java:1583)
21:19:22.656 [http-nio-8080-exec-2] DEBUG o.a.g.event.EventLoggingListener -
User "guacadmin" (authenticated by "mysql") successfully re-authenticated their
existing session from [FAILED toString()]
21:19:22.724 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession not set for thread: 30, creating a new one
21:19:22.724 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.724 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2071790073 from pool.
21:19:22.724 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.725 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.726 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.727 [http-nio-8080-exec-5] DEBUG o.a.g.a.mysql.conf.MySQLEnvironment -
Database recognized as MySQL 8.0.41.
21:19:22.728 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Preparing: WITH RECURSIVE
related_entity(entity_id) AS ( SELECT guacamole_user_group.entity_id FROM
guacamole_user_group JOIN guacamole_user_group_member ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group_member.member_entity_id = ? AND
guacamole_user_group.disabled = false UNION SELECT
guacamole_user_group.entity_id FROM related_entity JOIN
guacamole_user_group_member ON related_entity.entity_id =
guacamole_user_group_member.member_entity_id JOIN guacamole_user_group ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group.disabled = false ) SELECT name FROM related_entity
JOIN guacamole_entity ON related_entity.entity_id = guacamole_entity.entity_id
WHERE guacamole_entity.type = 'USER_GROUP';
21:19:22.729 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Parameters: 1(Integer)
21:19:22.731 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - <== Total: 0
21:19:22.732 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 committing
21:19:22.732 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 terminated its life-cycle, closing it
21:19:22.733 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Resetting autocommit to true on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.735 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.736 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2071790073 to pool.
21:19:22.739 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession not set for thread: 30, creating a new one
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2129705300 from pool.
21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2129705300 ...
21:19:22.741 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2129705300 is GOOD!
21:19:22.741 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.742 [http-nio-8080-exec-5] DEBUG o.a.g.a.mysql.conf.MySQLEnvironment -
Database recognized as MySQL 8.0.41.
21:19:22.742 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Preparing: WITH RECURSIVE
related_entity(entity_id) AS ( SELECT guacamole_user_group.entity_id FROM
guacamole_user_group JOIN guacamole_user_group_member ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group_member.member_entity_id = ? AND
guacamole_user_group.disabled = false UNION SELECT
guacamole_user_group.entity_id FROM related_entity JOIN
guacamole_user_group_member ON related_entity.entity_id =
guacamole_user_group_member.member_entity_id JOIN guacamole_user_group ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group.disabled = false ) SELECT name FROM related_entity
JOIN guacamole_entity ON related_entity.entity_id = guacamole_entity.entity_id
WHERE guacamole_entity.type = 'USER_GROUP';
21:19:22.743 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Parameters: 1(Integer)
21:19:22.745 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - <== Total: 0
21:19:22.746 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 committing
21:19:22.746 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 terminated its life-cycle, closing it
21:19:22.746 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Resetting autocommit to true on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.747 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.747 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2129705300 ...
21:19:22.749 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2129705300 is GOOD!
21:19:22.749 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2129705300 to pool.
21:19:22.750 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.751 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2071790073 from pool.
21:19:22.751 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.753 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.753 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.755 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.p.S.selectOne - ==>
Preparing: SELECT DISTINCT ? AS entity_id, permission FROM
guacamole_system_permission WHERE ( entity_id = ? ) AND permission = ?
21:19:22.756 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.p.S.selectOne - ==>
Parameters: 1(Integer), 1(Integer), ADMINISTER(String)
21:19:22.758 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.p.S.selectOne - <==
Total: 1
21:19:22.759 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Resetting autocommit to true on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.760 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.760 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.761 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.761 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2071790073 to pool.
21:19:22.762 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.762 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2129705300 from pool.
21:19:22.762 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2129705300 ...
21:19:22.764 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2129705300 is GOOD!
21:19:22.764 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.766 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
==> Preparing: SELECT guacamole_connection.connection_id,
guacamole_connection.connection_name, parent_id, protocol, max_connections,
max_connections_per_user, proxy_hostname, proxy_port, proxy_encryption_method,
connection_weight, failover_only, MAX(start_date) AS last_active FROM
guacamole_connection LEFT JOIN guacamole_connection_history ON
guacamole_connection_history.connection_id = guacamole_connection.connection_id
WHERE guacamole_connection.connection_id IN ( ? ) GROUP BY
guacamole_connection.connection_id; SELECT primary_connection_id,
sharing_profile_id FROM guacamole_sharing_profile WHERE primary_connection_id
IN ( ? ) ; SELECT connection_id, attribute_name, attribute_value FROM
guacamole_connection_attribute WHERE connection_id IN ( ? ) ;
21:19:22.766 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
==> Parameters: 1(String), 1(String), 1(String)
21:19:22.771 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
<== Total: 1
21:19:22.773 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
<== Total: 0
21:19:22.773 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select -
<== Total: 0
21:19:22.774 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Resetting autocommit to true on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.775 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
21:19:22.775 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2129705300 ...
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2129705300 is GOOD!
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Returned connection 2129705300 to pool.
21:19:22.776 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession not set for thread: 30, creating a new one
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Opening JDBC Connection
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Checked out connection 2071790073 from pool.
21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Testing connection 2071790073 ...
21:19:22.777 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
Connection 2071790073 is GOOD!
21:19:22.777 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
Setting autocommit to false on JDBC Connection
[com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
21:19:22.779 [http-nio-8080-exec-5] DEBUG o.a.g.a.mysql.conf.MySQLEnvironment -
Database recognized as MySQL 8.0.41.
21:19:22.781 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Preparing: WITH RECURSIVE
related_entity(entity_id) AS ( SELECT guacamole_user_group.entity_id FROM
guacamole_user_group JOIN guacamole_user_group_member ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group_member.member_entity_id = ? AND
guacamole_user_group.disabled = false UNION SELECT
guacamole_user_group.entity_id FROM related_entity JOIN
guacamole_user_group_member ON related_entity.entity_id =
guacamole_user_group_member.member_entity_id JOIN guacamole_user_group ON
guacamole_user_group.user_group_id = guacamole_user_group_member.user_group_id
WHERE guacamole_user_group.disabled = false ) SELECT name FROM related_entity
JOIN guacamole_entity ON related_entity.entity_id = guacamole_entity.entity_id
WHERE guacamole_entity.type = 'USER_GROUP';
21:19:22.783 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Parameters: 1(Integer)
21:19:22.788 [http-nio-8080-exec-5] DEBUG
o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - <== Total: 0
21:19:22.788 [http-nio-8080-exec-5] DEBUG
o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
java.util.Set<java.lang.String>
org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
extends
org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
- SqlSession of thread: 30 committing
> 1.6.0: RDP server closed/refused connection: Manually logged off
> ----------------------------------------------------------------
>
> Key: GUACAMOLE-2031
> URL: https://issues.apache.org/jira/browse/GUACAMOLE-2031
> Project: Guacamole
> Issue Type: Bug
> Components: guacamole
> Affects Versions: 1.6.0
> Reporter: gihan
> Priority: Minor
> Attachments: image-2025-02-12-14-45-20-916.png
>
>
> Hi,
> I have installed guacamole 1.6.0 on a linux server 24.04, and I'm getting the
> error below while launching a remote app published on the server using
> "Remote App Tool". Below is the error message:
> !image-2025-02-12-14-45-20-916.png!
>
> I have included the debug logs as well. I have another server running 1.5.5
> connecting to the same session host and it works fine. Issue only happens
> with version 1.6.0.
> I’ve also attached the debug logs for your reference.
> 21:19:22.656 [http-nio-8080-exec-2] DEBUG o.a.i.d.pooled.PooledDataSource -
> Returned connection 2129705300 to pool.
> SLF4J(E): Failed toString() invocation on an object of type
> [org.apache.guacamole.event.RemoteAddress]
> SLF4J(E): Reported exception:
> java.lang.IllegalStateException: The request object has been recycled and is
> no longer associated with this facade
> at
> org.apache.catalina.connector.RequestFacade.checkFacade(RequestFacade.java:856)
> at
> org.apache.catalina.connector.RequestFacade.getHeader(RequestFacade.java:516)
> at
> javax.servlet.http.HttpServletRequestWrapper.getHeader(HttpServletRequestWrapper.java:82)
> at
> javax.servlet.http.HttpServletRequestWrapper.getHeader(HttpServletRequestWrapper.java:82)
> at
> javax.servlet.http.HttpServletRequestWrapper.getHeader(HttpServletRequestWrapper.java:82)
> at
> org.apache.guacamole.event.RemoteAddress.toString(RemoteAddress.java:86)
> at
> org.slf4j.helpers.MessageFormatter.safeObjectAppend(MessageFormatter.java:291)
> at
> org.slf4j.helpers.MessageFormatter.deeplyAppendParameter(MessageFormatter.java:263)
> at
> org.slf4j.helpers.MessageFormatter.arrayFormat(MessageFormatter.java:225)
> at
> org.slf4j.helpers.MessageFormatter.arrayFormat(MessageFormatter.java:160)
> at
> ch.qos.logback.classic.spi.LoggingEvent.getFormattedMessage(LoggingEvent.java:396)
> at
> ch.qos.logback.classic.spi.LoggingEvent.prepareForDeferredProcessing(LoggingEvent.java:252)
> at
> ch.qos.logback.core.OutputStreamAppender.subAppend(OutputStreamAppender.java:235)
> at
> ch.qos.logback.core.OutputStreamAppender.append(OutputStreamAppender.java:102)
> at
> ch.qos.logback.core.UnsynchronizedAppenderBase.doAppend(UnsynchronizedAppenderBase.java:85)
> at
> ch.qos.logback.core.spi.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:51)
> at
> ch.qos.logback.classic.Logger.appendLoopOnAppenders(Logger.java:272)
> at ch.qos.logback.classic.Logger.callAppenders(Logger.java:259)
> at
> ch.qos.logback.classic.Logger.buildLoggingEventAndAppend(Logger.java:426)
> at ch.qos.logback.classic.Logger.filterAndLog_2(Logger.java:419)
> at ch.qos.logback.classic.Logger.debug(Logger.java:495)
> at
> org.apache.guacamole.event.EventLoggingListener.logSuccess(EventLoggingListener.java:165)
> at
> org.apache.guacamole.event.EventLoggingListener.handleEvent(EventLoggingListener.java:225)
> at
> org.apache.guacamole.rest.event.ListenerService.handleEvent(ListenerService.java:53)
> at
> org.apache.guacamole.rest.auth.AuthenticationService.authenticate(AuthenticationService.java:409)
> at
> org.apache.guacamole.rest.auth.TokenRESTService.createToken(TokenRESTService.java:174)
> at
> java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:103)
> at java.base/java.lang.reflect.Method.invoke(Method.java:580)
> at
> org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory.lambda$static$0(ResourceMethodInvocationHandlerFactory.java:52)
> at
> org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:146)
> at
> org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:189)
> at
> org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$TypeOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:219)
> at
> org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:93)
> at
> org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:478)
> at
> org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:400)
> at
> org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:81)
> at
> org.glassfish.jersey.server.ServerRuntime$1.run(ServerRuntime.java:256)
> at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248)
> at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244)
> at org.glassfish.jersey.internal.Errors.process(Errors.java:292)
> at org.glassfish.jersey.internal.Errors.process(Errors.java:274)
> at org.glassfish.jersey.internal.Errors.process(Errors.java:244)
> at
> org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:265)
> at
> org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:235)
> at
> org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:684)
> at
> org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:397)
> at
> org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:349)
> at
> org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:379)
> at
> org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:312)
> at
> org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:205)
> at
> com.google.inject.servlet.ServletDefinition.doServiceImpl(ServletDefinition.java:290)
> at
> com.google.inject.servlet.ServletDefinition.doService(ServletDefinition.java:280)
> at
> com.google.inject.servlet.ServletDefinition.service(ServletDefinition.java:184)
> at
> com.google.inject.servlet.ManagedServletPipeline.service(ManagedServletPipeline.java:89)
> at
> com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:85)
> at
> com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:121)
> at
> com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:133)
> at
> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:168)
> at
> org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:144)
> at
> org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:168)
> at
> org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:90)
> at
> org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:482)
> at
> org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:130)
> at
> org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:93)
> at
> org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:660)
> at
> org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74)
> at
> org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:346)
> at
> org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:396)
> at
> org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:63)
> at
> org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:937)
> at
> org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1791)
> at
> org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52)
> at
> org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190)
> at
> org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
> at
> org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63)
> at java.base/java.lang.Thread.run(Thread.java:1583)
> 21:19:22.656 [http-nio-8080-exec-2] DEBUG o.a.g.event.EventLoggingListener -
> User "guacadmin" (authenticated by "mysql") successfully re-authenticated
> their existing session from [FAILED toString()]
> 21:19:22.724 [http-nio-8080-exec-5] DEBUG
> o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
> java.util.Set<java.lang.String>
> org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
> extends
> org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
> - SqlSession not set for thread: 30, creating a new one
> 21:19:22.724 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Opening JDBC Connection
> 21:19:22.724 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Checked out connection 2071790073 from pool.
> 21:19:22.724 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Testing connection 2071790073 ...
> 21:19:22.725 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Connection 2071790073 is GOOD!
> 21:19:22.726 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Setting autocommit to false on JDBC Connection
> [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
> 21:19:22.727 [http-nio-8080-exec-5] DEBUG o.a.g.a.mysql.conf.MySQLEnvironment
> - Database recognized as MySQL 8.0.41.
> 21:19:22.728 [http-nio-8080-exec-5] DEBUG
> o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Preparing: WITH
> RECURSIVE related_entity(entity_id) AS ( SELECT
> guacamole_user_group.entity_id FROM guacamole_user_group JOIN
> guacamole_user_group_member ON guacamole_user_group.user_group_id =
> guacamole_user_group_member.user_group_id WHERE
> guacamole_user_group_member.member_entity_id = ? AND
> guacamole_user_group.disabled = false UNION SELECT
> guacamole_user_group.entity_id FROM related_entity JOIN
> guacamole_user_group_member ON related_entity.entity_id =
> guacamole_user_group_member.member_entity_id JOIN guacamole_user_group ON
> guacamole_user_group.user_group_id =
> guacamole_user_group_member.user_group_id WHERE guacamole_user_group.disabled
> = false ) SELECT name FROM related_entity JOIN guacamole_entity ON
> related_entity.entity_id = guacamole_entity.entity_id WHERE
> guacamole_entity.type = 'USER_GROUP';
> 21:19:22.729 [http-nio-8080-exec-5] DEBUG
> o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Parameters: 1(Integer)
> 21:19:22.731 [http-nio-8080-exec-5] DEBUG
> o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - <== Total: 0
> 21:19:22.732 [http-nio-8080-exec-5] DEBUG
> o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
> java.util.Set<java.lang.String>
> org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
> extends
> org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
> - SqlSession of thread: 30 committing
> 21:19:22.732 [http-nio-8080-exec-5] DEBUG
> o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
> java.util.Set<java.lang.String>
> org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
> extends
> org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
> - SqlSession of thread: 30 terminated its life-cycle, closing it
> 21:19:22.733 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Resetting autocommit to true on JDBC Connection
> [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
> 21:19:22.735 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
> 21:19:22.736 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Testing connection 2071790073 ...
> 21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Connection 2071790073 is GOOD!
> 21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Returned connection 2071790073 to pool.
> 21:19:22.739 [http-nio-8080-exec-5] DEBUG
> o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
> java.util.Set<java.lang.String>
> org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
> extends
> org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
> - SqlSession not set for thread: 30, creating a new one
> 21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Opening JDBC Connection
> 21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Checked out connection 2129705300 from pool.
> 21:19:22.739 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Testing connection 2129705300 ...
> 21:19:22.741 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Connection 2129705300 is GOOD!
> 21:19:22.741 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Setting autocommit to false on JDBC Connection
> [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
> 21:19:22.742 [http-nio-8080-exec-5] DEBUG o.a.g.a.mysql.conf.MySQLEnvironment
> - Database recognized as MySQL 8.0.41.
> 21:19:22.742 [http-nio-8080-exec-5] DEBUG
> o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Preparing: WITH
> RECURSIVE related_entity(entity_id) AS ( SELECT
> guacamole_user_group.entity_id FROM guacamole_user_group JOIN
> guacamole_user_group_member ON guacamole_user_group.user_group_id =
> guacamole_user_group_member.user_group_id WHERE
> guacamole_user_group_member.member_entity_id = ? AND
> guacamole_user_group.disabled = false UNION SELECT
> guacamole_user_group.entity_id FROM related_entity JOIN
> guacamole_user_group_member ON related_entity.entity_id =
> guacamole_user_group_member.member_entity_id JOIN guacamole_user_group ON
> guacamole_user_group.user_group_id =
> guacamole_user_group_member.user_group_id WHERE guacamole_user_group.disabled
> = false ) SELECT name FROM related_entity JOIN guacamole_entity ON
> related_entity.entity_id = guacamole_entity.entity_id WHERE
> guacamole_entity.type = 'USER_GROUP';
> 21:19:22.743 [http-nio-8080-exec-5] DEBUG
> o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Parameters: 1(Integer)
> 21:19:22.745 [http-nio-8080-exec-5] DEBUG
> o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - <== Total: 0
> 21:19:22.746 [http-nio-8080-exec-5] DEBUG
> o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
> java.util.Set<java.lang.String>
> org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
> extends
> org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
> - SqlSession of thread: 30 committing
> 21:19:22.746 [http-nio-8080-exec-5] DEBUG
> o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
> java.util.Set<java.lang.String>
> org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
> extends
> org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
> - SqlSession of thread: 30 terminated its life-cycle, closing it
> 21:19:22.746 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Resetting autocommit to true on JDBC Connection
> [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
> 21:19:22.747 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
> 21:19:22.747 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Testing connection 2129705300 ...
> 21:19:22.749 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Connection 2129705300 is GOOD!
> 21:19:22.749 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Returned connection 2129705300 to pool.
> 21:19:22.750 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Opening JDBC Connection
> 21:19:22.751 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Checked out connection 2071790073 from pool.
> 21:19:22.751 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Testing connection 2071790073 ...
> 21:19:22.753 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Connection 2071790073 is GOOD!
> 21:19:22.753 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Setting autocommit to false on JDBC Connection
> [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
> 21:19:22.755 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.p.S.selectOne - ==>
> Preparing: SELECT DISTINCT ? AS entity_id, permission FROM
> guacamole_system_permission WHERE ( entity_id = ? ) AND permission = ?
> 21:19:22.756 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.p.S.selectOne - ==>
> Parameters: 1(Integer), 1(Integer), ADMINISTER(String)
> 21:19:22.758 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.p.S.selectOne - <==
> Total: 1
> 21:19:22.759 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Resetting autocommit to true on JDBC Connection
> [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
> 21:19:22.760 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
> 21:19:22.760 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Testing connection 2071790073 ...
> 21:19:22.761 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Connection 2071790073 is GOOD!
> 21:19:22.761 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Returned connection 2071790073 to pool.
> 21:19:22.762 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Opening JDBC Connection
> 21:19:22.762 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Checked out connection 2129705300 from pool.
> 21:19:22.762 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Testing connection 2129705300 ...
> 21:19:22.764 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Connection 2129705300 is GOOD!
> 21:19:22.764 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Setting autocommit to false on JDBC Connection
> [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
> 21:19:22.766 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select
> - ==> Preparing: SELECT guacamole_connection.connection_id,
> guacamole_connection.connection_name, parent_id, protocol, max_connections,
> max_connections_per_user, proxy_hostname, proxy_port,
> proxy_encryption_method, connection_weight, failover_only, MAX(start_date) AS
> last_active FROM guacamole_connection LEFT JOIN guacamole_connection_history
> ON guacamole_connection_history.connection_id =
> guacamole_connection.connection_id WHERE guacamole_connection.connection_id
> IN ( ? ) GROUP BY guacamole_connection.connection_id; SELECT
> primary_connection_id, sharing_profile_id FROM guacamole_sharing_profile
> WHERE primary_connection_id IN ( ? ) ; SELECT connection_id, attribute_name,
> attribute_value FROM guacamole_connection_attribute WHERE connection_id IN (
> ? ) ;
> 21:19:22.766 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select
> - ==> Parameters: 1(String), 1(String), 1(String)
> 21:19:22.771 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select
> - <== Total: 1
> 21:19:22.773 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select
> - <== Total: 0
> 21:19:22.773 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.c.ConnectionMapper.select
> - <== Total: 0
> 21:19:22.774 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Resetting autocommit to true on JDBC Connection
> [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
> 21:19:22.775 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Closing JDBC Connection [com.mysql.cj.jdbc.ConnectionImpl@7ef0b954]
> 21:19:22.775 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Testing connection 2129705300 ...
> 21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Connection 2129705300 is GOOD!
> 21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Returned connection 2129705300 to pool.
> 21:19:22.776 [http-nio-8080-exec-5] DEBUG
> o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
> java.util.Set<java.lang.String>
> org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
> extends
> org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
> - SqlSession not set for thread: 30, creating a new one
> 21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Opening JDBC Connection
> 21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Checked out connection 2071790073 from pool.
> 21:19:22.776 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Testing connection 2071790073 ...
> 21:19:22.777 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource -
> Connection 2071790073 is GOOD!
> 21:19:22.777 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction -
> Setting autocommit to false on JDBC Connection
> [com.mysql.cj.jdbc.ConnectionImpl@7b7d01f9]
> 21:19:22.779 [http-nio-8080-exec-5] DEBUG o.a.g.a.mysql.conf.MySQLEnvironment
> - Database recognized as MySQL 8.0.41.
> 21:19:22.781 [http-nio-8080-exec-5] DEBUG
> o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Preparing: WITH
> RECURSIVE related_entity(entity_id) AS ( SELECT
> guacamole_user_group.entity_id FROM guacamole_user_group JOIN
> guacamole_user_group_member ON guacamole_user_group.user_group_id =
> guacamole_user_group_member.user_group_id WHERE
> guacamole_user_group_member.member_entity_id = ? AND
> guacamole_user_group.disabled = false UNION SELECT
> guacamole_user_group.entity_id FROM related_entity JOIN
> guacamole_user_group_member ON related_entity.entity_id =
> guacamole_user_group_member.member_entity_id JOIN guacamole_user_group ON
> guacamole_user_group.user_group_id =
> guacamole_user_group_member.user_group_id WHERE guacamole_user_group.disabled
> = false ) SELECT name FROM related_entity JOIN guacamole_entity ON
> related_entity.entity_id = guacamole_entity.entity_id WHERE
> guacamole_entity.type = 'USER_GROUP';
> 21:19:22.783 [http-nio-8080-exec-5] DEBUG
> o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - ==> Parameters: 1(Integer)
> 21:19:22.788 [http-nio-8080-exec-5] DEBUG
> o.a.g.a.j.b.E.selectEffectiveGroupIdentifiers - <== Total: 0
> 21:19:22.788 [http-nio-8080-exec-5] DEBUG
> o.m.g.t.TransactionalMethodInterceptor - [Intercepted method: public
> java.util.Set<java.lang.String>
> org.apache.guacamole.auth.jdbc.base.EntityService.retrieveEffectiveGroups(org.apache.guacamole.auth.jdbc.base.ModeledPermissions<?
> extends
> org.apache.guacamole.auth.jdbc.base.EntityModel>,java.util.Collection<java.lang.String>)]
> - SqlSession of thread: 30 committing
>
>
>
--
This message was sent by Atlassian Jira
(v8.20.10#820010)
