[ 
https://issues.apache.org/jira/browse/GUACAMOLE-919?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17011779#comment-17011779
 ] 

Mechanix edited comment on GUACAMOLE-919 at 1/9/20 1:33 PM:
------------------------------------------------------------

[~mjumper] thanks for your reply. I've enabled debug logs on the guacamole 
deployment:

{{ 12:16:16.156 [http-nio-8080-exec-8] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.css"}}
{{12:16:16.167 [http-nio-8080-exec-7] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.js"}}
{{12:16:16.361 [http-nio-8080-exec-6] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:16:16.361 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 52832043 from pool.}}
{{12:16:16.361 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 52832043 ...}}
{{12:16:16.471 [http-nio-8080-exec-9] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/translations/en.json" }}
{{12:16:18.334 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection *(blank page - browser refresh page)*}}
{{12:16:19.199 [http-nio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection (blank page - browser refresh page)}}
{{12:16:19.376 [http-nio-8080-exec-7] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection (blank page - browser refresh page)}}
{{12:16:19.609 [http-nio-8080-exec-1] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection (blank page - browser refresh page)}}
{{12:16:19.811 [http-nio-8080-exec-3] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection (blank page - browser refresh page)}}
{{12:17:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:17:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:17:12.809 [http-nio-8080-exec-8] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:18:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:18:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:19:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:19:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:20:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:20:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:21:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:21:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:22:12.065 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:22:12.065 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:23:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:23:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:24:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:24:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:25:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:25:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:26:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:26:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:27:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:27:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:28:12.065 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:28:12.065 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:29:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:29:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:30:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:30:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:31:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:31:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:31:52.793 [http-nio-8080-exec-6] WARN o.a.i.d.pooled.PooledDataSource - 
Execution of ping query 'SELECT 1' failed: An I/O error occurred while sending 
to the backend.}}
{{12:31:52.794 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 52832043 is BAD: An I/O error occurred while sending to the 
backend.}}
{{12:31:52.794 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - A 
bad connection (52832043) was returned from the pool, getting another 
connection.}}
{{12:31:52.794 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 699492272 from pool.}}
{{12:31:52.794 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 699492272 ...}}
{{12:32:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:32:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:33:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:33:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:34:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:34:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:34:36.385 [http-nio-8080-exec-4] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.css"}}
{{12:34:36.393 [http-nio-8080-exec-2] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.js"}}
{{12:34:36.489 [http-nio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:34:36.563 [http-nio-8080-exec-2] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/translations/en.json"}}
{{12:35:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:35:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:36:12.068 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:36:12.068 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:37:12.065 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:37:12.065 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:38:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:38:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:39:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:39:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:39:32.977 [http-nio-8080-exec-14] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:39:33.950 [http-nio-8080-exec-17] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:40:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:40:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:40:49.395 [http-nio-8080-exec-19] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:40:52.191 [http-nio-8080-exec-16] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:41:00.884 [http-nio-8080-exec-4] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:41:01.230 [http-nio-8080-exec-13] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:41:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:41:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:42:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:42:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:43:09.100 [http-nio-8080-exec-20] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:43:09.895 [http-nio-8080-exec-23] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{12:43:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:43:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:44:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:44:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:45:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:45:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:46:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:46:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:47:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:47:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:47:29.226 [http-nio-8080-exec-6] WARN o.a.i.d.pooled.PooledDataSource - 
Execution of ping query 'SELECT 1' failed: An I/O error occurred while sending 
to the backend.}}
{{12:47:29.226 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 699492272 is BAD: An I/O error occurred while sending to the 
backend.}}
{{12:47:29.226 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - A 
bad connection (699492272) was returned from the pool, getting another 
connection.}}
{{12:47:29.374 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 2082103122.}}
{{12:47:29.374 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 2082103122 ...}}
{{12:47:29.384 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 2082103122 is GOOD! *<login works again>*}}
{{12:47:29.384 [http-nio-8080-exec-6] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.385 [http-nio-8080-exec-6] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- ==> Parameters: null, null}}
{{12:47:29.397 [http-nio-8080-exec-6] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- <== Total: 0}}
{{12:47:29.397 [http-nio-8080-exec-6] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@7c1a5f52]}}
{{12:47:29.404 [http-nio-8080-exec-6] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@7c1a5f52]}}
{{12:47:29.561 [http-nio-8080-exec-8] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 204366919.}}
{{12:47:29.561 [http-nio-8080-exec-8] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.561 [http-nio-8080-exec-8] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- ==> Parameters: null, null}}
{{12:47:29.571 [http-nio-8080-exec-8] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- <== Total: 0}}
{{12:47:29.571 [http-nio-8080-exec-8] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@c2e6447]}}
{{12:47:29.574 [http-nio-8080-exec-8] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@c2e6447]}}
{{12:47:29.616 [http-nio-8080-exec-3] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 2132244257.}}
{{12:47:29.616 [http-nio-8080-exec-3] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.616 [http-nio-8080-exec-3] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- ==> Parameters: null, null}}
{{12:47:29.629 [http-nio-8080-exec-3] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- <== Total: 0}}
{{12:47:29.629 [http-nio-8080-exec-3] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@7f177721]}}
{{12:47:29.641 [http-nio-8080-exec-3] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@7f177721]}}
{{12:47:29.683 [http-nio-8080-exec-1] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 1911010863.}}
{{12:47:29.683 [http-nio-8080-exec-1] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.684 [http-nio-8080-exec-1] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- ==> Parameters: null, null}}
{{12:47:29.697 [http-nio-8080-exec-1] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- <== Total: 0}}
{{12:47:29.697 [http-nio-8080-exec-1] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@71e7b62f]}}
{{12:47:29.703 [http-nio-8080-exec-1] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@71e7b62f]}}
{{12:47:29.749 [http-nio-8080-exec-7] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 214275606.}}
{{12:47:29.749 [http-nio-8080-exec-7] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.752 [http-nio-8080-exec-7] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- ==> Parameters: null, null}}
{{12:47:29.761 [http-nio-8080-exec-7] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- <== Total: 0}}
{{12:47:29.762 [http-nio-8080-exec-7] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@cc59616]}}
{{12:47:29.769 [http-nio-8080-exec-7] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@cc59616]}}
{{12:47:29.808 [http-nio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 99174500.}}
{{12:47:29.808 [http-nio-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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.809 [http-nio-8080-exec-9] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- ==> Parameters: null, null}}
{{12:47:29.822 [http-nio-8080-exec-9] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- <== Total: 0}}
{{12:47:29.822 [http-nio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@5e94864]}}
{{12:47:29.834 [http-nio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@5e94864]}}
{{12:47:29.893 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 150670306.}}
{{12:47:29.893 [http-nio-8080-exec-5] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.893 [http-nio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 99174500 ...}}
{{12:47:29.894 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- ==> Parameters: null, null}}
{{12:47:29.902 [http-nio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 99174500 is GOOD!}}
{{12:47:29.903 [http-nio-8080-exec-9] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 99174500 to pool.}}
{{12:47:29.903 [http-nio-8080-exec-7] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 214275606 ...}}
{{12:47:29.903 [http-nio-8080-exec-9] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:29.903 [http-nio-8080-exec-9] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:29.907 [http-nio-8080-exec-5] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- <== Total: 0}}
{{12:47:29.907 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@8fb0be2]}}
{{12:47:29.910 [http-nio-8080-exec-7] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 214275606 is GOOD!}}
{{12:47:29.911 [http-nio-8080-exec-7] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 214275606 to pool.}}
{{12:47:29.911 [http-nio-8080-exec-7] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:29.911 [http-nio-8080-exec-1] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1911010863 ...}}
{{12:47:29.911 [http-nio-8080-exec-7] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:29.915 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@8fb0be2]}}
{{12:47:29.918 [http-nio-8080-exec-1] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1911010863 is GOOD!}}
{{12:47:29.919 [http-nio-8080-exec-1] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 1911010863 to pool.}}
{{12:47:29.919 [http-nio-8080-exec-3] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 2132244257 ...}}
{{12:47:29.919 [http-nio-8080-exec-1] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:29.919 [http-nio-8080-exec-1] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:29.926 [http-nio-8080-exec-3] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 2132244257 is GOOD!}}
{{12:47:29.927 [http-nio-8080-exec-3] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 2132244257 to pool.}}
{{12:47:29.927 [http-nio-8080-exec-8] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 204366919 ...}}
{{12:47:29.927 [http-nio-8080-exec-3] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:29.927 [http-nio-8080-exec-3] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:29.933 [http-nio-8080-exec-8] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 204366919 is GOOD!}}
{{12:47:29.933 [http-nio-8080-exec-8] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 204366919 to pool.}}
{{12:47:29.933 [http-nio-8080-exec-8] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:29.933 [http-nio-8080-exec-8] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:29.934 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 2082103122 ...}}
{{12:47:29.941 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 2082103122 is GOOD!}}
{{12:47:29.942 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Closed connection 2082103122.}}
{{12:47:29.942 [http-nio-8080-exec-23] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 99174500 from pool.}}
{{12:47:29.942 [http-nio-8080-exec-23] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 99174500 ...}}
{{12:47:29.942 [http-nio-8080-exec-6] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:29.943 [http-nio-8080-exec-6] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:29.955 [http-nio-8080-exec-23] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 99174500 is GOOD!}}
{{12:47:29.955 [http-nio-8080-exec-23] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Setting autocommit to false on JDBC Connection 
[org.postgresql.jdbc.PgConnection@5e94864]}}
{{12:47:29.955 [http-nio-8080-exec-23] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.955 [http-nio-8080-exec-20] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 214275606 from pool.}}
{{12:47:29.955 [http-nio-8080-exec-20] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 214275606 ...}}
{{12:47:29.956 [http-nio-8080-exec-23] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: null, null}}
{{12:47:29.962 [http-nio-8080-exec-20] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 214275606 is GOOD!}}
{{12:47:29.962 [http-nio-8080-exec-20] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Setting autocommit to false on JDBC Connection 
[org.postgresql.jdbc.PgConnection@cc59616]}}
{{12:47:29.962 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 1911010863 from pool.}}
{{12:47:29.962 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1911010863 ...}}
{{12:47:29.962 [http-nio-8080-exec-20] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.965 [http-nio-8080-exec-20] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: null, null}}
{{12:47:29.965 [http-nio-8080-exec-23] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0}}
{{12:47:29.967 [http-nio-8080-exec-23] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@5e94864]}}
{{12:47:29.971 [http-nio-8080-exec-20] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0}}
{{12:47:29.971 [http-nio-8080-exec-20] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@cc59616]}}
{{12:47:29.974 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1911010863 is GOOD!}}
{{12:47:29.974 [http-nio-8080-exec-13] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Setting autocommit to false on JDBC Connection 
[org.postgresql.jdbc.PgConnection@71e7b62f]}}
{{12:47:29.974 [http-nio-8080-exec-13] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.974 [http-nio-8080-exec-4] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 2132244257 from pool.}}
{{12:47:29.974 [http-nio-8080-exec-4] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 2132244257 ...}}
{{12:47:29.974 [http-nio-8080-exec-13] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: null, null}}
{{12:47:29.977 [http-nio-8080-exec-20] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@cc59616]}}
{{12:47:29.977 [http-nio-8080-exec-23] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@5e94864]}}
{{12:47:29.981 [http-nio-8080-exec-4] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 2132244257 is GOOD!}}
{{12:47:29.982 [http-nio-8080-exec-4] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Setting autocommit to false on JDBC Connection 
[org.postgresql.jdbc.PgConnection@7f177721]}}
{{12:47:29.982 [http-nio-8080-exec-16] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 204366919 from pool.}}
{{12:47:29.982 [http-nio-8080-exec-4] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.982 [http-nio-8080-exec-13] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0}}
{{12:47:29.983 [http-nio-8080-exec-16] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 204366919 ...}}
{{12:47:29.984 [http-nio-8080-exec-4] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- ==> Parameters: null, null}}
{{12:47:29.985 [http-nio-8080-exec-13] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@71e7b62f]}}
{{12:47:29.993 [http-nio-8080-exec-16] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 204366919 is GOOD!}}
{{12:47:29.994 [http-nio-8080-exec-16] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Setting autocommit to false on JDBC Connection 
[org.postgresql.jdbc.PgConnection@c2e6447]}}
{{12:47:29.994 [http-nio-8080-exec-16] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:29.994 [http-nio-8080-exec-16] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: null, null}}
{{12:47:29.995 [http-nio-8080-exec-4] DEBUG o.a.g.a.j.user.UserMapper.selectOne 
- <== Total: 0}}
{{12:47:29.995 [http-nio-8080-exec-4] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@7f177721]}}
{{12:47:29.995 [http-nio-8080-exec-13] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@71e7b62f]}}
{{12:47:30.002 [http-nio-8080-exec-4] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@7f177721]}}
{{12:47:30.007 [http-nio-8080-exec-16] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0}}
{{12:47:30.007 [http-nio-8080-exec-16] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@c2e6447]}}
{{12:47:30.015 [http-nio-8080-exec-16] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@c2e6447]}}
{{12:47:30.061 [http-nio-8080-exec-19] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 1285322205.}}
{{12:47:30.062 [http-nio-8080-exec-19] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1285322205 ...}}
{{12:47:30.070 [http-nio-8080-exec-19] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1285322205 is GOOD!}}
{{12:47:30.070 [http-nio-8080-exec-19] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:30.071 [http-nio-8080-exec-19] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: null, null}}
{{12:47:30.079 [http-nio-8080-exec-19] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0}}
{{12:47:30.079 [http-nio-8080-exec-19] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@4c9c75dd]}}
{{12:47:30.085 [http-nio-8080-exec-19] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@4c9c75dd]}}
{{12:47:30.138 [http-nio-8080-exec-17] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 1890396403.}}
{{12:47:30.139 [http-nio-8080-exec-17] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1890396403 ...}}
{{12:47:30.148 [http-nio-8080-exec-17] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1890396403 is GOOD!}}
{{12:47:30.149 [http-nio-8080-exec-17] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:30.149 [http-nio-8080-exec-17] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: null, null}}
{{12:47:30.160 [http-nio-8080-exec-17] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0}}
{{12:47:30.160 [http-nio-8080-exec-17] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@70ad28f3]}}
{{12:47:30.169 [http-nio-8080-exec-17] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@70ad28f3]}}
{{12:47:30.202 [http-nio-8080-exec-14] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 1435574736.}}
{{12:47:30.202 [http-nio-8080-exec-14] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:30.202 [http-nio-8080-exec-14] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: null, null}}
{{12:47:30.211 [http-nio-8080-exec-14] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0}}
{{12:47:30.211 [http-nio-8080-exec-14] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@559121d0]}}
{{12:47:30.216 [http-nio-8080-exec-14] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@559121d0]}}
{{12:47:30.279 [http-nio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - 
Created connection 909394068.}}
{{12:47:30.279 [http-nio-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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{12:47:30.279 [http-nio-8080-exec-14] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1435574736 ...}}
{{12:47:30.280 [http-nio-8080-exec-10] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: null, null}}
{{12:47:30.288 [http-nio-8080-exec-14] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1435574736 is GOOD!}}
{{12:47:30.289 [http-nio-8080-exec-14] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 1435574736 to pool.}}
{{12:47:30.289 [http-nio-8080-exec-10] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0}}
{{12:47:30.289 [http-nio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@36344094]}}
{{12:47:30.289 [http-nio-8080-exec-14] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.289 [http-nio-8080-exec-17] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1890396403 ...}}
{{12:47:30.289 [http-nio-8080-exec-14] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:30.296 [http-nio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@36344094]}}
{{12:47:30.298 [http-nio-8080-exec-17] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1890396403 is GOOD!}}
{{12:47:30.298 [http-nio-8080-exec-17] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 1890396403 to pool.}}
{{12:47:30.298 [http-nio-8080-exec-19] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1285322205 ...}}
{{12:47:30.298 [http-nio-8080-exec-17] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.298 [http-nio-8080-exec-17] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:30.305 [http-nio-8080-exec-19] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1285322205 is GOOD!}}
{{12:47:30.305 [http-nio-8080-exec-19] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 1285322205 to pool.}}
{{12:47:30.305 [http-nio-8080-exec-19] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.305 [http-nio-8080-exec-19] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:30.306 [http-nio-8080-exec-16] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 204366919 ...}}
{{12:47:30.313 [http-nio-8080-exec-16] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 204366919 is GOOD!}}
{{12:47:30.313 [http-nio-8080-exec-16] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 204366919 to pool.}}
{{12:47:30.313 [http-nio-8080-exec-16] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.313 [http-nio-8080-exec-16] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:30.314 [http-nio-8080-exec-4] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 2132244257 ...}}
{{12:47:30.324 [http-nio-8080-exec-4] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 2132244257 is GOOD!}}
{{12:47:30.324 [http-nio-8080-exec-4] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 2132244257 to pool.}}
{{12:47:30.324 [http-nio-8080-exec-4] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.324 [http-nio-8080-exec-4] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:30.326 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1911010863 ...}}
{{12:47:30.340 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1911010863 is GOOD!}}
{{12:47:30.341 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Closed connection 1911010863.}}
{{12:47:30.341 [http-nio-8080-exec-13] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.341 [http-nio-8080-exec-13] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:30.342 [http-nio-8080-exec-23] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 99174500 ...}}
{{12:47:30.348 [http-nio-8080-exec-23] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 99174500 is GOOD!}}
{{12:47:30.349 [http-nio-8080-exec-23] DEBUG o.a.i.d.pooled.PooledDataSource - 
Closed connection 99174500.}}
{{12:47:30.349 [http-nio-8080-exec-23] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.349 [http-nio-8080-exec-23] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:30.351 [http-nio-8080-exec-20] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 214275606 ...}}
{{12:47:30.356 [http-nio-8080-exec-20] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 214275606 is GOOD!}}
{{12:47:30.357 [http-nio-8080-exec-20] DEBUG o.a.i.d.pooled.PooledDataSource - 
Closed connection 214275606.}}
{{12:47:30.357 [http-nio-8080-exec-20] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.357 [http-nio-8080-exec-20] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:30.358 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 150670306 ...}}
{{12:47:30.366 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 150670306 is GOOD!}}
{{12:47:30.366 [http-nio-8080-exec-5] DEBUG o.a.i.d.pooled.PooledDataSource - 
Closed connection 150670306.}}
{{12:47:30.366 [http-nio-8080-exec-5] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.367 [http-nio-8080-exec-5] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:47:30.367 [http-nio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 909394068 ...}}
{{12:47:30.370 [http-nio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 909394068 is GOOD!}}
{{12:47:30.371 [http-nio-8080-exec-10] DEBUG o.a.i.d.pooled.PooledDataSource - 
Closed connection 909394068.}}
{{12:47:30.371 [http-nio-8080-exec-10] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{12:47:30.371 [http-nio-8080-exec-10] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{12:48:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:48:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:49:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:49:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:50:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:50:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:51:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:51:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:52:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:52:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:53:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:53:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:54:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:54:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:55:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:55:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:56:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:56:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:57:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:57:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:58:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:58:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{12:59:12.069 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{12:59:12.069 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{13:00:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{13:00:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{13:01:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{13:01:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{13:02:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{13:02:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{13:02:55.208 [http-nio-8080-exec-17] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.js"}}
{{13:02:55.208 [http-nio-8080-exec-19] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.css"}}
{{13:02:55.360 [http-nio-8080-exec-13] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
{{13:02:55.360 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 1435574736 from pool.}}
{{13:02:55.360 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1435574736 ...}}
{{13:02:55.373 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1435574736 is GOOD!}}
{{13:02:55.373 [http-nio-8080-exec-13] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Setting autocommit to false on JDBC Connection 
[org.postgresql.jdbc.PgConnection@559121d0]}}
{{13:02:55.374 [http-nio-8080-exec-13] 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'::guacamole_entity_type 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'::guacamole_entity_type}}
{{13:02:55.374 [http-nio-8080-exec-13] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - ==> Parameters: null, null}}
{{13:02:55.391 [http-nio-8080-exec-13] DEBUG 
o.a.g.a.j.user.UserMapper.selectOne - <== Total: 0}}
{{13:02:55.391 [http-nio-8080-exec-13] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Resetting autocommit to true on JDBC Connection 
[org.postgresql.jdbc.PgConnection@559121d0]}}
{{13:02:55.398 [http-nio-8080-exec-13] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Closing JDBC Connection [org.postgresql.jdbc.PgConnection@559121d0]}}
{{13:02:55.398 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 1435574736 ...}}
{{13:02:55.406 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 1435574736 is GOOD!}}
{{13:02:55.406 [http-nio-8080-exec-13] DEBUG o.a.i.d.pooled.PooledDataSource - 
Returned connection 1435574736 to pool.}}
{{13:02:55.406 [http-nio-8080-exec-13] DEBUG 
o.a.g.a.f.FileAuthenticationProvider - User mapping file 
"/config/guacamole/user-mapping.xml" does not exist and will not be read.}}
{{13:02:55.406 [http-nio-8080-exec-13] DEBUG o.a.g.r.auth.AuthenticationService 
- Anonymous authentication attempt from [10.1.xxx.xxx, 10.2.xxx.xxx] failed.}}
{{13:02:55.469 [http-nio-8080-exec-23] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/translations/en.json"}}
{{13:03:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{13:03:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
{{13:04:12.066 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
{{13:04:12.067 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 1 ms.}}

 

At this point every client gets a blank page. The only thing that helps is 
restarting the guacamole deployment or wait till the session timer expires (see 
timeline from the logs aprox 30min)

 

Funny thing though: the blank page has following source:

 
{quote}<!DOCTYPE html>
 <!--
 Licensed to the Apache Software Foundation (ASF) under one
 or more contributor license agreements. See the NOTICE file
 distributed with this work for additional information
 regarding copyright ownership. The ASF licenses this file
 to you under the Apache License, Version 2.0 (the
 "License"); you may not use this file except in compliance
 with the License. You may obtain a copy of the License at

[http://www.apache.org/licenses/LICENSE-2.0]

Unless required by applicable law or agreed to in writing,
 software distributed under the License is distributed on an
 "AS IS" BASIS, WITHOUT WARRANTIES OR CONDITIONS OF ANY
 KIND, either express or implied. See the License for the
 specific language governing permissions and limitations
 under the License.
 -->
 <html ng-app="index" ng-controller="indexController">
 <head>
 <meta http-equiv="Content-Type" content="text/html; charset=UTF-8"/>
 <meta http-equiv="x-ua-compatible" content="IE=edge"/>
 <meta name="viewport" content="width=device-width, initial-scale=1.0, 
maximum-scale=1.0, minimum-scale=1.0, user-scalable=no, 
target-densitydpi=medium-dpi"/>
 <meta name="mobile-web-app-capable" content="yes"/>
 <meta name="apple-mobile-web-app-capable" content="yes"/>
 <link rel="icon" type="image/png" href="images/logo-64.png"/>
 <link rel="icon" type="image/png" sizes="144x144" href="images/logo-144.png"/>
 <link rel="apple-touch-icon" type="image/png" href="images/logo-144.png"/>
 <link rel="stylesheet" type="text/css" href="app.css?v=1.0.0">
 <title ng-bind="page.title | translate"></title>
 </head>
 <body ng-class="page.bodyClassName">

<!-- Content for logged-in users -->
 <div ng-if="!expectedCredentials">

<!-- Global status/error dialog -->
 <div ng-class="\{shown: guacNotification.getStatus()}" class="status-outer">
 <div class="status-middle">
 <guac-notification 
notification="guacNotification.getStatus()"></guac-notification>
 </div>
 </div>

<div id="content" ng-view>
 </div>

</div>

<!-- Login screen for logged-out users -->
 <guac-login ng-show="expectedCredentials"
 help-text="loginHelpText"
 form="expectedCredentials"
 values="acceptedCredentials"></guac-login>

<!-- Reformat URL for AngularJS if query parameters are present -->
 <script type="text/javascript" src="relocateParameters.js"></script>

<!-- Utility libraries -->
 <script type="text/javascript" 
src="webjars/jquery/3.3.1/dist/jquery.min.js"></script>
 <script type="text/javascript" 
src="webjars/lodash/4.17.10/dist/lodash.min.js"></script>

<!-- AngularJS -->
 <script type="text/javascript" 
src="webjars/angular/1.6.9/angular.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-route/1.6.9/angular-route.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-touch/1.6.9/angular-touch.min.js"></script>

<!-- Internationalization -->
 <script type="text/javascript" 
src="webjars/messageformat/1.0.2/messageformat.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-translate/2.16.0/angular-translate.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-translate-interpolation-messageformat/2.16.0/angular-translate-interpolation-messageformat.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-translate-loader-static-files/2.16.0/angular-translate-loader-static-files.min.js"></script>

<!-- Polyfills for the "datalist" element, Blob and the FileSaver API -->
 <script type="text/javascript" 
src="webjars/blob-polyfill/1.0.20150320/Blob.js"></script>
 <script type="text/javascript" 
src="webjars/datalist-polyfill/1.14.0/datalist-polyfill.min.js"></script>
 <script type="text/javascript" 
src="webjars/filesaver/1.3.3/FileSaver.min.js"></script>

<!-- Allow arbitrary ordering of Angular module creation and retrieval -->
 <script type="text/javascript" 
src="webjars/angular-module-shim/0.0.4/angular-module-shim.js"></script>

<!-- Web application -->
 <script type="text/javascript" src="app.js?v=1.0.0"></script>

</body>
 </html>
{quote}
 


was (Author: mechanix):
[~mjumper] thanks for your reply. I've enabled debug logs on the guacamole 
deployment:

 

{{12:16:16.156 [http-nio-8080-exec-8] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.css"}}
 {{12:16:16.167 [http-nio-8080-exec-7] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.js"}}
 {{12:16:16.361 [http-nio-8080-exec-6] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
 {{12:16:16.361 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 52832043 from pool.}}
 {{12:16:16.361 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 52832043 ...}}
 {{12:16:16.471 [http-nio-8080-exec-9] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/translations/en.json"}}
 {{12:16:18.334 [http-nio-8080-exec-5] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection *(blank page -  browser refresh page)*}}
 {{12:16:19.199 [http-nio-8080-exec-9] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection *(blank page -  browser refresh page)*}}
 {{12:16:19.376 [http-nio-8080-exec-7] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection *(blank page -  browser refresh page)*}}
 {{12:16:19.609 [http-nio-8080-exec-1] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection *(blank page -  browser refresh page)*}}
 {{12:16:19.811 [http-nio-8080-exec-3] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection  *(blank page -  browser refresh page)***}}
 {{12:17:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
 {{12:17:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
 {{12:17:12.809 [http-nio-8080-exec-8] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
 {{12:18:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
 {{12:18:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
 {{12:31:52.793 [http-nio-8080-exec-6] WARN o.a.i.d.pooled.PooledDataSource - 
Execution of ping query 'SELECT 1' failed: An I/O error occurred while sending 
to the backend.}}
 {{12:31:52.794 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Connection 52832043 is BAD: An I/O error occurred while sending to the 
backend.}}
 {{12:31:52.794 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
A bad connection (52832043) was returned from the pool, getting another 
connection.}}
 {{12:31:52.794 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Checked out connection 699492272 from pool.}}
 {{12:31:52.794 [http-nio-8080-exec-6] DEBUG o.a.i.d.pooled.PooledDataSource - 
Testing connection 699492272 ...}}
 {{12:32:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
 {{12:32:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
 {{12:33:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
 {{12:33:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
 {{12:34:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Checking for expired sessions...}}
 {{12:34:12.064 [pool-1-thread-1] DEBUG o.a.g.rest.auth.HashTokenSessionMap - 
Session check completed in 0 ms.}}
 {{12:34:36.385 [http-nio-8080-exec-4] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.css"}}
 {{12:34:36.393 [http-nio-8080-exec-2] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/app.js"}}
 {{12:34:36.489 [http-nio-8080-exec-10] DEBUG o.a.i.t.jdbc.JdbcTransaction - 
Opening JDBC Connection}}
 {{12:34:36.563 [http-nio-8080-exec-2] DEBUG o.a.g.resource.ResourceServlet - 
Resource not modified: "/translations/en.json"}}

 

At this point every client gets a blank page. The only thing that helps is 
restarting the guacamole deployment.

 

Funny thing though: the blank page has following source:

 
{quote}
<!DOCTYPE html>
<!--
 Licensed to the Apache Software Foundation (ASF) under one
 or more contributor license agreements. See the NOTICE file
 distributed with this work for additional information
 regarding copyright ownership. The ASF licenses this file
 to you under the Apache License, Version 2.0 (the
 "License"); you may not use this file except in compliance
 with the License. You may obtain a copy of the License at

http://www.apache.org/licenses/LICENSE-2.0

Unless required by applicable law or agreed to in writing,
 software distributed under the License is distributed on an
 "AS IS" BASIS, WITHOUT WARRANTIES OR CONDITIONS OF ANY
 KIND, either express or implied. See the License for the
 specific language governing permissions and limitations
 under the License.
-->
<html ng-app="index" ng-controller="indexController">
 <head>
 <meta http-equiv="Content-Type" content="text/html; charset=UTF-8"/>
 <meta http-equiv="x-ua-compatible" content="IE=edge"/>
 <meta name="viewport" content="width=device-width, initial-scale=1.0, 
maximum-scale=1.0, minimum-scale=1.0, user-scalable=no, 
target-densitydpi=medium-dpi"/>
 <meta name="mobile-web-app-capable" content="yes"/>
 <meta name="apple-mobile-web-app-capable" content="yes"/>
 <link rel="icon" type="image/png" href="images/logo-64.png"/>
 <link rel="icon" type="image/png" sizes="144x144" href="images/logo-144.png"/>
 <link rel="apple-touch-icon" type="image/png" href="images/logo-144.png"/>
 <link rel="stylesheet" type="text/css" href="app.css?v=1.0.0">
 <title ng-bind="page.title | translate"></title>
 </head>
 <body ng-class="page.bodyClassName">

<!-- Content for logged-in users -->
 <div ng-if="!expectedCredentials">
 
 <!-- Global status/error dialog -->
 <div ng-class="\{shown: guacNotification.getStatus()}" class="status-outer">
 <div class="status-middle">
 <guac-notification 
notification="guacNotification.getStatus()"></guac-notification>
 </div>
 </div>
 
 <div id="content" ng-view>
 </div>
 
 </div>

<!-- Login screen for logged-out users -->
 <guac-login ng-show="expectedCredentials"
 help-text="loginHelpText"
 form="expectedCredentials"
 values="acceptedCredentials"></guac-login>

<!-- Reformat URL for AngularJS if query parameters are present -->
 <script type="text/javascript" src="relocateParameters.js"></script>

<!-- Utility libraries -->
 <script type="text/javascript" 
src="webjars/jquery/3.3.1/dist/jquery.min.js"></script>
 <script type="text/javascript" 
src="webjars/lodash/4.17.10/dist/lodash.min.js"></script>

<!-- AngularJS -->
 <script type="text/javascript" 
src="webjars/angular/1.6.9/angular.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-route/1.6.9/angular-route.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-touch/1.6.9/angular-touch.min.js"></script>

<!-- Internationalization -->
 <script type="text/javascript" 
src="webjars/messageformat/1.0.2/messageformat.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-translate/2.16.0/angular-translate.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-translate-interpolation-messageformat/2.16.0/angular-translate-interpolation-messageformat.min.js"></script>
 <script type="text/javascript" 
src="webjars/angular-translate-loader-static-files/2.16.0/angular-translate-loader-static-files.min.js"></script>

<!-- Polyfills for the "datalist" element, Blob and the FileSaver API -->
 <script type="text/javascript" 
src="webjars/blob-polyfill/1.0.20150320/Blob.js"></script>
 <script type="text/javascript" 
src="webjars/datalist-polyfill/1.14.0/datalist-polyfill.min.js"></script>
 <script type="text/javascript" 
src="webjars/filesaver/1.3.3/FileSaver.min.js"></script>

<!-- Allow arbitrary ordering of Angular module creation and retrieval -->
 <script type="text/javascript" 
src="webjars/angular-module-shim/0.0.4/angular-module-shim.js"></script>

<!-- Web application -->
 <script type="text/javascript" src="app.js?v=1.0.0"></script>

</body>
</html>
{quote}
 

> An I/O error occurred while sending to the backend
> --------------------------------------------------
>
>                 Key: GUACAMOLE-919
>                 URL: https://issues.apache.org/jira/browse/GUACAMOLE-919
>             Project: Guacamole
>          Issue Type: Bug
>          Components: guacamole-auth-jdbc-postgresql
>    Affects Versions: 1.0.0
>            Reporter: Mechanix
>            Priority: Major
>
> Hi,
> we use guacamole with postgresql and openid extension. Guacamole and guacd is 
> deployed inside a k8s cluster.
> For some reason, the authentication doesn't succeed sporadically; there is 
> only a blank page and this error message in the guacamole log:
> *[pool-1-thread-1] WARN o.a.i.d.pooled.PooledDataSource - Execution of ping 
> query 'SELECT 1' failed: An I/O error occurred while sending to the backend.*
> I suspect there is a weird timeout happening between guacamole and postgresql 
> but could figure out why.
> Any hints are much appreciated. Thanks
>  
>  



--
This message was sent by Atlassian Jira
(v8.3.4#803005)

Reply via email to