Realizei a instalação do sistema, na sua versão 1.1, conforme manual para Windows disponível no portal. No seu primeiro acesso, o sistema pede para alterar a senha do usuário 'admin', porém ao confirmar a nova senha, o sistema apresenta a seguinte mensagem: "Ocorreu um erro inesperado no sistema", e não altera a senha.
Em anexo segue arquivo contendo as ultimas linhas do log do sistema.
Gostaria de saber se vocês já possuem relato de algum problema semelhante, de forma que possam nos ajudar.
Grato.
Autor: Maxwell Souza Correia
33 comentários
2012-08-23 15:43:33,917 [http-8080-1] INFO br.com.ggas.parametrosistema.impl.ControladorParametroSistemaImpl - ### obterParametroPorCodigo: CODIGO_CARACTERE_MAIUSCULO
2012-08-23 15:43:33,932 [http-8080-1] INFO br.com.ggas.parametrosistema.impl.ControladorParametroSistemaImpl - ### obterParametroPorCodigo: TAMANHO_MINIMO_SENHA
2012-08-23 15:43:33,932 [http-8080-1] INFO br.com.ggas.parametrosistema.impl.ControladorParametroSistemaImpl - ### obterParametroPorCodigo: TAMANHO_MAXIMO_SENHA
2012-08-23 15:43:34,573 [http-8080-1] WARN org.hibernate.util.JDBCExceptionReporter - SQL Error: 0, SQLState: 22003
2012-08-23 15:43:34,573 [http-8080-1] ERROR org.hibernate.util.JDBCExceptionReporter - Valor inválido para tipo long : -LOB
2012-08-23 15:43:34,573 [http-8080-1] INFO org.hibernate.event.def.DefaultLoadEventListener - Error performing load command
org.hibernate.exception.DataException: could not load an entity: [br.com.ggas.cadastro.empresa.impl.EmpresaImpl#1]
at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:102)
at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:66)
at org.hibernate.loader.Loader.loadEntity(Loader.java:2041)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:86)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:76)
at org.hibernate.persister.entity.AbstractEntityPersister.load(AbstractEntityPersister.java:3294)
at org.hibernate.event.def.DefaultLoadEventListener.loadFromDatasource(DefaultLoadEventListener.java:496)
at org.hibernate.event.def.DefaultLoadEventListener.doLoad(DefaultLoadEventListener.java:477)
at org.hibernate.event.def.DefaultLoadEventListener.load(DefaultLoadEventListener.java:227)
at org.hibernate.event.def.DefaultLoadEventListener.proxyOrLoad(DefaultLoadEventListener.java:285)
at org.hibernate.event.def.DefaultLoadEventListener.onLoad(DefaultLoadEventListener.java:152)
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Updating entity: [br.com.ggas.controleacesso.impl.UsuarioImpl#1]
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.SQL - update USUARIO_SISTEMA set USSI_NM_LOGIN=?, USSI_NM_SENHA=?, USSI_NR_VERSAO=?, USSI_TM_ULTIMA_ALTERACAO=?, USSI_IN_USO=?, USSI_TM_ULTIMO_ACESSO=?, USSI_IN_SENHA_EXPIRADA=?, USSI_NR_TENTATIVAS_SENHAS=?, USSI_DT_EXPIRACAO=?, USSI_IN_PRIMEIRO_ACESSO=?, USSI_DT_CRIACAO_SENHA=?, USSI_NR_EXPIRA_EM=?, FUNC_CD=? where USSI_CD=?
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - preparing statement
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Dehydrating entity: [br.com.ggas.controleacesso.impl.UsuarioImpl#1]
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [1] as [VARCHAR] - admin
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [2] as [VARCHAR] - d7c5ba314636e629d5d0da4e0f8ba1e8
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [3] as [INTEGER] - 3
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [4] as [TIMESTAMP] - 2012-08-24 08:32:27.0
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [5] as [BIT] - true
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [6] as [TIMESTAMP] - 2011-05-31 12:29:39.0
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [7] as [BIT] - false
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [8] as [INTEGER] - 1
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [9] as [TIMESTAMP] - <null>
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [10] as [BIT] - false
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [11] as [TIMESTAMP] - 2012-08-24 00:00:00.0
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [12] as [INTEGER] - <null>
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [13] as [BIGINT] - 1
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [14] as [BIGINT] - 1
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.cache.UpdateTimestampsCache - Pre-invalidating space [USUARIO_SISTEMA]
2012-08-24 15:11:20,456 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - Executing batch size: 1
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - closing statement
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.ConnectionManager - registering flush end
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - post flush
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.query.QueryPlanCache - located HQL query plan in cache ( select entidade.versao from UsuarioImpl entidade where entidade.chavePrimaria = ? )
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - flushing session
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - processing flush-time cascades
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - processing cascade ACTION_SAVE_UPDATE for: br.com.ggas.controleacesso.impl.PapelImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.PapelImpl.permissoes
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.PapelImpl.permissoes
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - deleting orphans for collection: br.com.ggas.controleacesso.impl.PapelImpl.permissoes
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done deleting orphans for collection: br.com.ggas.controleacesso.impl.PapelImpl.permissoes
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.PapelImpl.usuarios
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.CascadingAction - cascading to saveOrUpdate: br.com.ggas.controleacesso.impl.UsuarioImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractSaveEventListener - persistent instance of: br.com.ggas.controleacesso.impl.UsuarioImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultSaveOrUpdateEventListener - ignoring persistent instance
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultSaveOrUpdateEventListener - object already associated with session: [br.com.ggas.controleacesso.impl.UsuarioImpl#1]
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.PapelImpl.usuarios
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done processing cascade ACTION_SAVE_UPDATE for: br.com.ggas.controleacesso.impl.PapelImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - processing cascade ACTION_SAVE_UPDATE for: br.com.ggas.controleacesso.impl.UsuarioImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.UsuarioImpl.papeis
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.CascadingAction - cascading to saveOrUpdate: br.com.ggas.controleacesso.impl.PapelImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractSaveEventListener - persistent instance of: br.com.ggas.controleacesso.impl.PapelImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultSaveOrUpdateEventListener - ignoring persistent instance
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultSaveOrUpdateEventListener - object already associated with session: [br.com.ggas.controleacesso.impl.PapelImpl#1]
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.UsuarioImpl.papeis
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done processing cascade ACTION_SAVE_UPDATE for: br.com.ggas.controleacesso.impl.UsuarioImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - dirty checking collections
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Flushing entities and processing referenced collections
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Collections - Collection found: [br.com.ggas.controleacesso.impl.PapelImpl.permissoes#1], was: [br.com.ggas.controleacesso.impl.PapelImpl.permissoes#1] (uninitialized)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Collections - Collection found: [br.com.ggas.controleacesso.impl.PapelImpl.usuarios#1], was: [br.com.ggas.controleacesso.impl.PapelImpl.usuarios#1] (initialized)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Collections - Collection found: [br.com.ggas.controleacesso.impl.UsuarioImpl.papeis#1], was: [br.com.ggas.controleacesso.impl.UsuarioImpl.papeis#1] (initialized)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Processing unreferenced collections
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Scheduling collection removes/(re)creates/updates
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Flushed: 0 insertions, 0 updates, 0 deletions to 2 objects
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 3 collections
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.pretty.Printer - listing entities:
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.pretty.Printer - br.com.ggas.controleacesso.impl.UsuarioImpl{periodicidadeSenha=null, ultimaAlteracao=2012-08-24 08:32:27.0, dataExpirarSenha=null, chavePrimaria=1, funcionario=br.com.ggas.cadastro.funcionario.impl.FuncionarioImpl#1, primeiroAcesso=false, senha=d7c5ba314636e629d5d0da4e0f8ba1e8, tentativasSenhaErrada=1, dataCriacaoSenha=2012-08-24 00:00:00.0, papeis=[br.com.ggas.controleacesso.impl.PapelImpl#1], habilitado=true, ultimoAcesso=2011-05-31 12:29:39.0, login=admin, versao=3, senhaExpirada=false}
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.pretty.Printer - br.com.ggas.controleacesso.impl.PapelImpl{permissoes=<uninitialized>, complemento=null, habilitado=true, admin=true, ultimaAlteracao=2011-04-15 17:40:29.0, chavePrimaria=1, funcionario=null, versao=1, descricao=Administrador, usuarios=[br.com.ggas.controleacesso.impl.UsuarioImpl#1]}
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultAutoFlushEventListener - Dont need to execute flush
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.query.HQLQueryPlan - find: select entidade.versao from UsuarioImpl entidade where entidade.chavePrimaria = ?
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.QueryParameters - parameters: [1]
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.QueryParameters - named parameters: {}
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.SQL - select usuarioimp0_.USSI_NR_VERSAO as col_0_0_ from USUARIO_SISTEMA usuarioimp0_ where usuarioimp0_.USSI_CD=?
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - preparing statement
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [1] as [BIGINT] - 1
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - Bound [2] parameters total
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 0)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - processing result set
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - result set row: 0
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - result row:
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicExtractor - found [3] as column [col_0_0_]
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - done processing result set (1 rows)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 1)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - closing statement
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.StatefulPersistenceContext - initializing non-lazy collections
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.springframework.transaction.support.TransactionSynchronizationManager - Retrieved value [org.springframework.orm.hibernate3.SessionHolder@718f92] for key [org.hibernate.impl.SessionFactoryImpl@1260f2f] bound to thread [http-8080-11]
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.query.QueryPlanCache - located HQL query plan in cache ( select count (*) from UsuarioImpl where login = ? and chavePrimaria != ?)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.query.QueryPlanCache - located HQL query plan in cache ( select count (*) from UsuarioImpl where login = ? and chavePrimaria != ?)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - flushing session
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - processing flush-time cascades
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - processing cascade ACTION_SAVE_UPDATE for: br.com.ggas.controleacesso.impl.PapelImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.PapelImpl.permissoes
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.PapelImpl.permissoes
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - deleting orphans for collection: br.com.ggas.controleacesso.impl.PapelImpl.permissoes
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done deleting orphans for collection: br.com.ggas.controleacesso.impl.PapelImpl.permissoes
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.PapelImpl.usuarios
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.CascadingAction - cascading to saveOrUpdate: br.com.ggas.controleacesso.impl.UsuarioImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractSaveEventListener - persistent instance of: br.com.ggas.controleacesso.impl.UsuarioImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultSaveOrUpdateEventListener - ignoring persistent instance
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultSaveOrUpdateEventListener - object already associated with session: [br.com.ggas.controleacesso.impl.UsuarioImpl#1]
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.PapelImpl.usuarios
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done processing cascade ACTION_SAVE_UPDATE for: br.com.ggas.controleacesso.impl.PapelImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - processing cascade ACTION_SAVE_UPDATE for: br.com.ggas.controleacesso.impl.UsuarioImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.UsuarioImpl.papeis
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.CascadingAction - cascading to saveOrUpdate: br.com.ggas.controleacesso.impl.PapelImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractSaveEventListener - persistent instance of: br.com.ggas.controleacesso.impl.PapelImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultSaveOrUpdateEventListener - ignoring persistent instance
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultSaveOrUpdateEventListener - object already associated with session: [br.com.ggas.controleacesso.impl.PapelImpl#1]
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done cascade ACTION_SAVE_UPDATE for collection: br.com.ggas.controleacesso.impl.UsuarioImpl.papeis
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Cascade - done processing cascade ACTION_SAVE_UPDATE for: br.com.ggas.controleacesso.impl.UsuarioImpl
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - dirty checking collections
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Flushing entities and processing referenced collections
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Collections - Collection found: [br.com.ggas.controleacesso.impl.PapelImpl.permissoes#1], was: [br.com.ggas.controleacesso.impl.PapelImpl.permissoes#1] (uninitialized)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Collections - Collection found: [br.com.ggas.controleacesso.impl.PapelImpl.usuarios#1], was: [br.com.ggas.controleacesso.impl.PapelImpl.usuarios#1] (initialized)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.Collections - Collection found: [br.com.ggas.controleacesso.impl.UsuarioImpl.papeis#1], was: [br.com.ggas.controleacesso.impl.UsuarioImpl.papeis#1] (initialized)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Processing unreferenced collections
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Scheduling collection removes/(re)creates/updates
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Flushed: 0 insertions, 0 updates, 0 deletions to 2 objects
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 3 collections
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.pretty.Printer - listing entities:
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.pretty.Printer - br.com.ggas.controleacesso.impl.UsuarioImpl{periodicidadeSenha=null, ultimaAlteracao=2012-08-24 08:32:27.0, dataExpirarSenha=null, chavePrimaria=1, funcionario=br.com.ggas.cadastro.funcionario.impl.FuncionarioImpl#1, primeiroAcesso=false, senha=d7c5ba314636e629d5d0da4e0f8ba1e8, tentativasSenhaErrada=1, dataCriacaoSenha=2012-08-24 00:00:00.0, papeis=[br.com.ggas.controleacesso.impl.PapelImpl#1], habilitado=true, ultimoAcesso=2011-05-31 12:29:39.0, login=admin, versao=3, senhaExpirada=false}
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.pretty.Printer - br.com.ggas.controleacesso.impl.PapelImpl{permissoes=<uninitialized>, complemento=null, habilitado=true, admin=true, ultimaAlteracao=2011-04-15 17:40:29.0, chavePrimaria=1, funcionario=null, versao=1, descricao=Administrador, usuarios=[br.com.ggas.controleacesso.impl.UsuarioImpl#1]}
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.event.def.DefaultAutoFlushEventListener - Dont need to execute flush
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.query.HQLQueryPlan - find: select count (*) from UsuarioImpl where login = ? and chavePrimaria != ?
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.QueryParameters - parameters: [admin, 1]
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.engine.QueryParameters - named parameters: {}
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.SQL - select count(*) as col_0_0_ from USUARIO_SISTEMA usuarioimp0_ where usuarioimp0_.USSI_NM_LOGIN=? and usuarioimp0_.USSI_CD<>?
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - preparing statement
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [1] as [VARCHAR] - admin
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicBinder - binding parameter [2] as [BIGINT] - 1
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - Bound [3] parameters total
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 0)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - processing result set
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - result set row: 0
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - result row:
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.type.descriptor.sql.BasicExtractor - found [0] as column [col_0_0_]
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.loader.Loader - done processing result set (1 rows)
2012-08-24 15:11:20,472 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 1)
2012-08-24 15:11:20,487 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2012-08-24 15:11:20,487 [http-8080-11] DEBUG org.hibernate.jdbc.AbstractBatcher - closing statement