Hello again, I figured out how to enable debugging and the following is the log from trying to login to getting the error message in the web interface.
What makes me most wonder are the two last searchs: server: 18:52:36.222 [http-bio-8080-exec-9] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching "ou=theusersearchbase" for objects matching "(&(ou=SPECIFIC-OU)(uid=nico))". server: 18:52:36.288 [http-bio-8080-exec-9] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching "ou=theusersearchbase" for objects matching "(&(ou=SPECIFIC-OU)(uid=*))". The first one should have returned exactly 1 object and the second one should have returned at maximum 143 objects, so both relatively small. The user does not need to exist in the database prior to the login additionally to LDAP, or does it? Best, Nico server: 18:52:35.613 [http-bio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - Opening JDBC Connection server: 18:52:35.614 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Checked out connection 126593496 from pool. server: 18:52:35.614 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection 126593496 ... server: 18:52:35.621 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 126593496 is GOOD! server: 18:52:35.621 [http-bio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - Setting autocommit to false on JDBC Connection [com.mysql.jdbc.JDBC4Connection@78ba9d8] server: 18:52:35.623 [http-bio-8080-exec-9] 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' server: 18:52:35.625 [http-bio-8080-exec-9] DEBUG o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: nico(String), nico(String) server: 18:52:35.627 [http-bio-8080-exec-9] DEBUG o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0 server: 18:52:35.628 [http-bio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - Resetting autocommit to true on JDBC Connection [com.mysql.jdbc.JDBC4Connection@78ba9d8] server: 18:52:35.630 [http-bio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - Closing JDBC Connection [com.mysql.jdbc.JDBC4Connection@78ba9d8] server: 18:52:35.630 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection 126593496 ... server: 18:52:35.630 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 126593496 is GOOD! server: 18:52:35.630 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Returned connection 126593496 to pool. server: 18:52:35.640 [http-bio-8080-exec-9] DEBUG o.a.g.a.ldap.LDAPConnectionService - Connecting to LDAP server using SSL/TLS. server: 18:52:36.034 [http-bio-8080-exec-9] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching "ou=theusersearchbase" for objects matching "(&(ou=SPECIFIC-OU)(uid=nico))". server: 18:52:36.055 [http-bio-8080-exec-9] DEBUG o.a.g.a.ldap.LDAPConnectionService - Connecting to LDAP server using SSL/TLS. server: 18:52:36.128 [http-bio-8080-exec-9] INFO o.a.g.r.auth.AuthenticationService - User "nico" successfully authenticated from 10.204.X.X. server: 18:52:36.129 [http-bio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - Opening JDBC Connection server: 18:52:36.129 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Checked out connection 126593496 from pool. server: 18:52:36.129 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection 126593496 ... server: 18:52:36.131 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 126593496 is GOOD! server: 18:52:36.131 [http-bio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - Setting autocommit to false on JDBC Connection [com.mysql.jdbc.JDBC4Connection@78ba9d8] server: 18:52:36.133 [http-bio-8080-exec-9] 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' server: 18:52:36.134 [http-bio-8080-exec-9] DEBUG o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: nico(String), nico(String) server: 18:52:36.138 [http-bio-8080-exec-9] DEBUG o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0 server: 18:52:36.139 [http-bio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - Resetting autocommit to true on JDBC Connection [com.mysql.jdbc.JDBC4Connection@78ba9d8] server: 18:52:36.140 [http-bio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - Closing JDBC Connection [com.mysql.jdbc.JDBC4Connection@78ba9d8] server: 18:52:36.141 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Testing connection 126593496 ... server: 18:52:36.142 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Connection 126593496 is GOOD! server: 18:52:36.142 [http-bio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - Returned connection 126593496 to pool. server: 18:52:36.157 [http-bio-8080-exec-9] DEBUG o.a.g.a.ldap.LDAPConnectionService - Connecting to LDAP server using SSL/TLS. server: 18:52:36.222 [http-bio-8080-exec-9] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching "ou=theusersearchbase" for objects matching "(&(ou=SPECIFIC-OU)(uid=nico))". server: 18:52:36.234 [http-bio-8080-exec-9] DEBUG o.a.g.a.ldap.LDAPConnectionService - Connecting to LDAP server using SSL/TLS. server: 18:52:36.288 [http-bio-8080-exec-9] DEBUG o.a.g.auth.ldap.ObjectQueryService - Searching "ou=theusersearchbase" for objects matching "(&(ou=SPECIFIC-OU)(uid=*))". -- Sent from: http://apache-guacamole-general-user-mailing-list.2363388.n4.nabble.com/
