guacamole-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Philip Herbert <m...@pherbert.de>
Subject AW: ldap groups in 1.0.0 RC1
Date Mon, 14 Jan 2019 20:37:32 GMT

Hello,

I have now created a testuser who is also member in one group.

The user himself has a connection assigned, and one connection is assigned to the TestGroup

with the modification oft the function getGroupSearchFilter, Authentication succeeds, but
opening the connection assigned tot he group fails (it is visible to the user)
(see attached log)


without the modification, the testuser can no longer sign in, the ldap-extension crashes.


Thanks, Philip



Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat7/common/classes], exists: [false], isDirectory:
[false], canRead: [false]
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat7/common], exists: [false], isDirectory:
[false], canRead: [false]
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat7/server/classes], exists: [false], isDirectory:
[false], canRead: [false]
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat7/server], exists: [false], isDirectory:
[false], canRead: [false]
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat7/shared/classes], exists: [false], isDirectory:
[false], canRead: [false]
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat7/shared], exists: [false], isDirectory:
[false], canRead: [false]
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server version:        Apache Tomcat/7.0.68 (Ubuntu)
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server built:          Oct 30 2018 12:54:52 UTC
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server number:         7.0.68.0
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: OS Name:               Linux
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: OS Version:            4.4.0-141-generic
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Architecture:          amd64
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Java Home:             /usr/lib/jvm/java-8-openjdk-amd64/jre
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: JVM Version:           1.8.0_191-8u191-b12-0ubuntu0.16.04.1-b12
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: JVM Vendor:            Oracle Corporation
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: CATALINA_BASE:         /var/lib/tomcat7
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: CATALINA_HOME:         /usr/share/tomcat7
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.util.logging.config.file=/var/lib/tomcat7/conf/logging.properties
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.awt.headless=true
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Xmx128m
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -XX:+UseConcMarkSweepGC
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.endorsed.dirs=/usr/share/tomcat7/endorsed
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcatalina.base=/var/lib/tomcat7
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcatalina.home=/usr/share/tomcat7
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.io.tmpdir=/tmp/tomcat7-tomcat7-tmp
Jan 14, 2019 9:20:34 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8080"]
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 318 ms
Jan 14, 2019 9:20:34 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Jan 14, 2019 9:20:34 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.68 (Ubuntu)
Jan 14, 2019 9:20:34 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /var/lib/tomcat7/webapps/guacamole.war
Jan 14, 2019 9:20:35 PM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for
this logger for a complete list of JARs that were scanned but no TLDs were found in them.
Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
21:20:35.831 [localhost-startStop-1] INFO  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME
is "/usr/share/tomcat7/.guacamole".
21:20:35.886 [localhost-startStop-1] INFO  o.a.g.rest.auth.HashTokenSessionMap - Sessions
will expire after 60 minutes of inactivity.
21:20:35.939 [localhost-startStop-1] INFO  org.apache.guacamole.log.LogModule - Loading logback
configuration from "/usr/share/tomcat7/.guacamole/logback.xml".
21:20:35.973 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Added language:
"nl"
21:20:35.973 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Added language:
"en"
21:20:35.973 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Added language:
"zh"
21:20:35.973 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Added language:
"fr"
21:20:35.973 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Added language:
"ru"
21:20:35.973 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Added language:
"de"
21:20:35.973 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Added language:
"es"
21:20:35.973 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Added language:
"no"
21:20:35.973 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Added language:
"it"
21:20:35.974 [localhost-startStop-1] DEBUG o.a.g.extension.ExtensionModule - Loading extension:
"guacamole-auth-header-1.0.0.jar"
21:20:36.011 [localhost-startStop-1] DEBUG o.a.g.extension.ExtensionModule - [0] Binding AuthenticationProvider
"org.apache.guacamole.auth.header.HTTPHeaderAuthenticationProvider".
21:20:36.016 [localhost-startStop-1] INFO  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME
is "/usr/share/tomcat7/.guacamole".
21:20:36.116 [localhost-startStop-1] INFO  o.a.g.extension.ExtensionModule - Extension "HTTP
Header Authentication Extension" loaded.
21:20:36.116 [localhost-startStop-1] DEBUG o.a.g.extension.ExtensionModule - Loading extension:
"guacamole-auth-jdbc-mysql-1.0.0.jar"
21:20:36.118 [localhost-startStop-1] DEBUG o.a.g.extension.ExtensionModule - [1] Binding AuthenticationProvider
"org.apache.guacamole.auth.mysql.MySQLAuthenticationProvider".
21:20:36.120 [localhost-startStop-1] INFO  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME
is "/usr/share/tomcat7/.guacamole".
21:20:36.226 [localhost-startStop-1] DEBUG org.apache.ibatis.logging.LogFactory - Logging
initialized using 'class org.apache.ibatis.logging.slf4j.Slf4jImpl' adapter.
21:20:36.526 [localhost-startStop-1] DEBUG o.a.i.d.pooled.PooledDataSource - PooledDataSource
forcefully closed/removed all connections.
21:20:36.526 [localhost-startStop-1] DEBUG o.a.i.d.pooled.PooledDataSource - PooledDataSource
forcefully closed/removed all connections.
21:20:36.526 [localhost-startStop-1] DEBUG o.a.i.d.pooled.PooledDataSource - PooledDataSource
forcefully closed/removed all connections.
21:20:36.526 [localhost-startStop-1] DEBUG o.a.i.d.pooled.PooledDataSource - PooledDataSource
forcefully closed/removed all connections.
21:20:36.526 [localhost-startStop-1] DEBUG o.a.i.d.pooled.PooledDataSource - PooledDataSource
forcefully closed/removed all connections.
21:20:36.526 [localhost-startStop-1] DEBUG o.a.i.d.pooled.PooledDataSource - PooledDataSource
forcefully closed/removed all connections.
21:20:36.847 [localhost-startStop-1] DEBUG o.a.g.extension.ExtensionModule - [2] Binding AuthenticationProvider
"org.apache.guacamole.auth.mysql.MySQLSharedAuthenticationProvider".
21:20:36.876 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Merged strings
with existing language: "es"
21:20:36.879 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Merged strings
with existing language: "ru"
21:20:36.880 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Merged strings
with existing language: "fr"
21:20:36.882 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Merged strings
with existing language: "en"
21:20:36.882 [localhost-startStop-1] INFO  o.a.g.extension.ExtensionModule - Extension "MySQL
Authentication" loaded.
21:20:36.882 [localhost-startStop-1] DEBUG o.a.g.extension.ExtensionModule - Loading extension:
"guacamole-auth-ldap-1.0.0.jar"
21:20:36.883 [localhost-startStop-1] DEBUG o.a.g.extension.ExtensionModule - [3] Binding AuthenticationProvider
"org.apache.guacamole.auth.ldap.LDAPAuthenticationProvider".
21:20:36.883 [localhost-startStop-1] INFO  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME
is "/usr/share/tomcat7/.guacamole".
21:20:37.018 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Merged strings
with existing language: "en"
21:20:37.018 [localhost-startStop-1] INFO  o.a.g.extension.ExtensionModule - Extension "LDAP
Authentication" loaded.
21:20:37.018 [localhost-startStop-1] DEBUG o.a.g.extension.ExtensionModule - Loading extension:
"knauber-branding-extension.jar"
21:20:37.020 [localhost-startStop-1] DEBUG o.a.g.e.LanguageResourceService - Merged strings
with existing language: "en"
21:20:37.026 [localhost-startStop-1] INFO  o.a.g.extension.ExtensionModule - Extension "Login
Page Customize Extension" loaded.
21:20:37.026 [localhost-startStop-1] DEBUG o.a.g.extension.ExtensionModule - [4] Binding AuthenticationProvider
"org.apache.guacamole.auth.file.FileAuthenticationProvider".
21:20:37.027 [localhost-startStop-1] INFO  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME
is "/usr/share/tomcat7/.guacamole".
21:20:37.095 [localhost-startStop-1] INFO  o.a.g.t.w.WebSocketTunnelModule - Loading JSR-356
WebSocket support...
21:20:37.107 [localhost-startStop-1] DEBUG o.a.guacamole.tunnel.TunnelModule - WebSocket module
loaded: org.apache.guacamole.tunnel.websocket.WebSocketTunnelModule
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.guacamole.rest.RESTExceptionMapper as a provider class
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.guacamole.rest.extension.ExtensionRESTService as a root resource
class
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.guacamole.rest.language.LanguageRESTService as a root resource
class
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.guacamole.rest.patch.PatchRESTService as a root resource class
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.guacamole.rest.auth.TokenRESTService as a root resource class
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.guacamole.rest.session.SessionRESTService as a root resource
class
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.codehaus.jackson.jaxrs.JacksonJsonProvider as a provider class
Jan 14, 2019 9:20:37 PM com.sun.jersey.server.impl.application.WebApplicationImpl _initiate
INFO: Initiating Jersey application, version 'Jersey: 1.17.1 02/28/2013 12:47 PM'
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider
INFO: Binding org.apache.guacamole.rest.RESTExceptionMapper to GuiceManagedComponentProvider
with the scope "Singleton"
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider
INFO: Binding org.codehaus.jackson.jaxrs.JacksonJsonProvider to GuiceManagedComponentProvider
with the scope "Singleton"
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider
INFO: Binding org.apache.guacamole.rest.extension.ExtensionRESTService to GuiceManagedComponentProvider
with the scope "PerRequest"
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider
INFO: Binding org.apache.guacamole.rest.language.LanguageRESTService to GuiceManagedComponentProvider
with the scope "PerRequest"
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider
INFO: Binding org.apache.guacamole.rest.patch.PatchRESTService to GuiceManagedComponentProvider
with the scope "PerRequest"
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider
INFO: Binding org.apache.guacamole.rest.auth.TokenRESTService to GuiceManagedComponentProvider
with the scope "PerRequest"
Jan 14, 2019 9:20:37 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider
INFO: Binding org.apache.guacamole.rest.session.SessionRESTService to GuiceManagedComponentProvider
with the scope "PerRequest"
Jan 14, 2019 9:20:37 PM org.webjars.servlet.WebjarsServlet init
INFO: WebjarsServlet initialization completed
Jan 14, 2019 9:20:37 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /var/lib/tomcat7/webapps/guacamole.war has finished
in 3,104 ms
Jan 14, 2019 9:20:37 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/lib/tomcat7/webapps/ROOT
Jan 14, 2019 9:20:37 PM org.apache.catalina.core.StandardContext setPath
WARNING: A context path must either be an empty string or start with a '/' and do not end
with a '/'. The path [/] does not meet these criteria and has been changed to []
Jan 14, 2019 9:20:37 PM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for
this logger for a complete list of JARs that were scanned but no TLDs were found in them.
Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Jan 14, 2019 9:20:37 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/lib/tomcat7/webapps/ROOT has finished in
188 ms
Jan 14, 2019 9:20:37 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Jan 14, 2019 9:20:37 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 3329 ms
21:20:50.469 [http-bio-8080-exec-2] DEBUG o.a.i.t.jdbc.JdbcTransaction - Opening JDBC Connection
21:20:50.685 [http-bio-8080-exec-2] DEBUG o.a.i.d.pooled.PooledDataSource - Created connection
927782874.
21:20:50.686 [http-bio-8080-exec-2] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection
927782874 ...
21:20:50.688 [http-bio-8080-exec-2] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 927782874
is GOOD!
21:20:50.689 [http-bio-8080-exec-2] DEBUG o.a.g.a.j.user.UserMapper.selectOne - ==>  Preparing:
SELECT guacamole_user.user_id, guacamole_entity.entity_id, guacamole_entity.name, password_hash,
password_salt, password_date, disabled, expired, access_window_start, access_window_end, valid_from,
valid_until, timezone, full_name, email_address, organization, organizational_role, MAX(start_date)
AS last_active FROM guacamole_user JOIN guacamole_entity ON guacamole_user.entity_id = guacamole_entity.entity_id
LEFT JOIN guacamole_user_history ON guacamole_user_history.user_id = guacamole_user.user_id
WHERE guacamole_entity.name = ? AND guacamole_entity.type = 'USER' GROUP BY guacamole_user.user_id,
guacamole_entity.entity_id; SELECT guacamole_user_attribute.user_id, guacamole_user_attribute.attribute_name,
guacamole_user_attribute.attribute_value FROM guacamole_user_attribute JOIN guacamole_user
ON guacamole_user.user_id = guacamole_user_attribute.user_id JOIN guacamole_entity ON guacamole_user.entity_id
= guacamole_entity.entity_id WHERE guacamole_entity.name = ? AND guacamole_entity.type = 'USER'

21:20:50.704 [http-bio-8080-exec-2] DEBUG o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters:
null, null
21:20:50.710 [http-bio-8080-exec-2] DEBUG o.a.g.a.j.user.UserMapper.selectOne - <==   
  Total: 0
21:20:50.711 [http-bio-8080-exec-2] DEBUG o.a.i.t.jdbc.JdbcTransaction - Resetting autocommit
to true on JDBC Connection [com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:50.711 [http-bio-8080-exec-2] DEBUG o.a.i.t.jdbc.JdbcTransaction - Closing JDBC Connection
[com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:50.712 [http-bio-8080-exec-2] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection
927782874 ...
21:20:50.712 [http-bio-8080-exec-2] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 927782874
is GOOD!
21:20:50.712 [http-bio-8080-exec-2] DEBUG o.a.i.d.pooled.PooledDataSource - Returned connection
927782874 to pool.
21:20:50.757 [http-bio-8080-exec-2] DEBUG o.a.g.a.l.AuthenticationProviderService - Anonymous
bind is not currently allowed by the LDAP authentication provider.
21:20:50.757 [http-bio-8080-exec-2] DEBUG o.a.g.a.f.FileAuthenticationProvider - User mapping
file "/usr/share/tomcat7/.guacamole/user-mapping.xml" does not exist and will not be read.
21:20:50.758 [http-bio-8080-exec-2] DEBUG o.a.g.r.auth.AuthenticationService - Anonymous authentication
attempt from [192.168.121.212, 127.0.0.1] failed.
21:20:55.813 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Opening JDBC Connection
21:20:55.813 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Checked out connection
927782874 from pool.
21:20:55.813 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection
927782874 ...
21:20:55.816 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 927782874
is GOOD!
21:20:55.816 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Setting autocommit
to false on JDBC Connection [com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:55.817 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.user.UserMapper.selectOne - ==>  Preparing:
SELECT guacamole_user.user_id, guacamole_entity.entity_id, guacamole_entity.name, password_hash,
password_salt, password_date, disabled, expired, access_window_start, access_window_end, valid_from,
valid_until, timezone, full_name, email_address, organization, organizational_role, MAX(start_date)
AS last_active FROM guacamole_user JOIN guacamole_entity ON guacamole_user.entity_id = guacamole_entity.entity_id
LEFT JOIN guacamole_user_history ON guacamole_user_history.user_id = guacamole_user.user_id
WHERE guacamole_entity.name = ? AND guacamole_entity.type = 'USER' GROUP BY guacamole_user.user_id,
guacamole_entity.entity_id; SELECT guacamole_user_attribute.user_id, guacamole_user_attribute.attribute_name,
guacamole_user_attribute.attribute_value FROM guacamole_user_attribute JOIN guacamole_user
ON guacamole_user.user_id = guacamole_user_attribute.user_id JOIN guacamole_entity ON guacamole_user.entity_id
= guacamole_entity.entity_id WHERE guacamole_entity.name = ? AND guacamole_entity.type = 'USER'

21:20:55.818 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters:
testdv(String), testdv(String)
21:20:55.836 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.user.UserMapper.selectOne - <==  
   Total: 1
21:20:55.837 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.user.UserMapper.selectOne - <==  
   Total: 0
21:20:55.837 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Resetting autocommit
to true on JDBC Connection [com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:55.837 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Closing JDBC Connection
[com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:55.837 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection
927782874 ...
21:20:55.838 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 927782874
is GOOD!
21:20:55.838 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Returned connection
927782874 to pool.
21:20:55.843 [http-bio-8080-exec-10] DEBUG o.a.g.a.ldap.LDAPConnectionService - Connecting
to LDAP server using SSL/TLS.
21:20:56.237 [http-bio-8080-exec-10] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching
"dc=knauber,dc=de" for objects matching "(&(objectClass=user)(!(objectCategory=computer))(sAMAccountName=testdv))".
21:20:56.271 [http-bio-8080-exec-10] DEBUG o.a.g.a.ldap.LDAPConnectionService - Connecting
to LDAP server using SSL/TLS.
21:20:56.489 [http-bio-8080-exec-10] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching
"dc=knauber,dc=de" for objects matching "(&(objectClass=*)(member=CN=testdv,OU=EDV,DC=knauber,DC=de))".
21:20:56.504 [http-bio-8080-exec-10] INFO  o.a.g.r.auth.AuthenticationService - User "testdv"
successfully authenticated from [192.168.121.212, 0:0:0:0:0:0:0:1].
21:20:56.505 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Opening JDBC Connection
21:20:56.505 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Checked out connection
927782874 from pool.
21:20:56.505 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection
927782874 ...
21:20:56.506 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 927782874
is GOOD!
21:20:56.506 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Setting autocommit
to false on JDBC Connection [com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:56.507 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.user.UserMapper.selectOne - ==>  Preparing:
SELECT guacamole_user.user_id, guacamole_entity.entity_id, guacamole_entity.name, password_hash,
password_salt, password_date, disabled, expired, access_window_start, access_window_end, valid_from,
valid_until, timezone, full_name, email_address, organization, organizational_role, MAX(start_date)
AS last_active FROM guacamole_user JOIN guacamole_entity ON guacamole_user.entity_id = guacamole_entity.entity_id
LEFT JOIN guacamole_user_history ON guacamole_user_history.user_id = guacamole_user.user_id
WHERE guacamole_entity.name = ? AND guacamole_entity.type = 'USER' GROUP BY guacamole_user.user_id,
guacamole_entity.entity_id; SELECT guacamole_user_attribute.user_id, guacamole_user_attribute.attribute_name,
guacamole_user_attribute.attribute_value FROM guacamole_user_attribute JOIN guacamole_user
ON guacamole_user.user_id = guacamole_user_attribute.user_id JOIN guacamole_entity ON guacamole_user.entity_id
= guacamole_entity.entity_id WHERE guacamole_entity.name = ? AND guacamole_entity.type = 'USER'

21:20:56.508 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters:
testdv(String), testdv(String)
21:20:56.511 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.user.UserMapper.selectOne - <==  
   Total: 1
21:20:56.511 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.user.UserMapper.selectOne - <==  
   Total: 0
21:20:56.511 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Resetting autocommit
to true on JDBC Connection [com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:56.512 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Closing JDBC Connection
[com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:56.512 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection
927782874 ...
21:20:56.513 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 927782874
is GOOD!
21:20:56.513 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Returned connection
927782874 to pool.
21:20:56.525 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Opening JDBC Connection
21:20:56.525 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Checked out connection
927782874 from pool.
21:20:56.525 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection
927782874 ...
21:20:56.526 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 927782874
is GOOD!
21:20:56.526 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Setting autocommit
to false on JDBC Connection [com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:56.526 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.u.UserRecordMapper.insert - ==>  Preparing:
INSERT INTO guacamole_user_history ( remote_host, user_id, username, start_date, end_date
) VALUES ( ?, (SELECT user_id FROM guacamole_user JOIN guacamole_entity ON guacamole_user.entity_id
= guacamole_entity.entity_id WHERE guacamole_entity.name = ? AND guacamole_entity.type = 'USER'),
?, ?, ? ) 
21:20:56.529 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.u.UserRecordMapper.insert - ==> Parameters:
0:0:0:0:0:0:0:1(String), testdv(String), testdv(String), 2019-01-14 21:20:56.525(Timestamp),
null
21:20:56.530 [http-bio-8080-exec-10] DEBUG o.a.g.a.j.u.UserRecordMapper.insert - <==  
 Updates: 1
21:20:56.531 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Committing JDBC
Connection [com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:56.531 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Resetting autocommit
to true on JDBC Connection [com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:56.531 [http-bio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - Closing JDBC Connection
[com.mysql.jdbc.JDBC4Connection@374cd7da]
21:20:56.531 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection
927782874 ...
21:20:56.532 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 927782874
is GOOD!
21:20:56.532 [http-bio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - Returned connection
927782874 to pool.
21:20:56.533 [http-bio-8080-exec-10] DEBUG o.a.g.a.ldap.LDAPConnectionService - Connecting
to LDAP server using SSL/TLS.
21:20:56.749 [http-bio-8080-exec-10] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching
"dc=knauber,dc=de" for objects matching "(&(objectClass=user)(!(objectCategory=computer))(sAMAccountName=testdv))".
21:20:56.756 [http-bio-8080-exec-10] DEBUG o.a.g.a.ldap.LDAPConnectionService - Connecting
to LDAP server using SSL/TLS.
21:20:56.982 [http-bio-8080-exec-10] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching
"dc=knauber,dc=de" for objects matching "(&(objectClass=user)(!(objectCategory=computer))(sAMAccountName=*))".
21:20:57.283 [http-bio-8080-exec-10] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching
"dc=knauber,dc=de" for objects matching "(&(objectClass=*)(cn=*))".
21:20:57.386 [http-bio-8080-exec-10] WARN  o.a.g.e.AuthenticationProviderFacade - The "ldap"
authentication provider has encountered an internal error which will halt the authentication
process. If this is unexpected or you are the developer of this authentication provider, you
may wish to enable debug-level logging. If this is expected and you wish to ignore such failures
in the future, please set "skip-if-unavailable: ldap" within your guacamole.properties.






Mime
View raw message