Skip to content

Instantly share code, notes, and snippets.

@vegar

vegar/log

Created April 25, 2013 07:43
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save vegar/5458141 to your computer and use it in GitHub Desktop.
Save vegar/5458141 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
log
2013-04-25 09:41:42,323 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=de5e5dbb-d393-4e1f-a509-69f00c61066b] opened session at timestamp: 635024797023, for session factory: [/f356b6e2e842431db71726e28be22db7]
2013-04-25 09:41:42,324 [3] DEBUG NHibernate.Linq - Expression (partially evaluated): value(NHibernate.Linq.NhQueryable`1[DIPS.IDMConnector.Entities.User]).Where(u => (u.UserName == "PASIENT")).FirstOrDefault()
2013-04-25 09:41:42,324 [3] DEBUG NHibernate.Engine.Query.QueryPlanCache - located HQL query plan in cache (.FirstOrDefault[DIPS.IDMConnector.Entities.User](.Where[DIPS.IDMConnector.Entities.User](NHibernate.Linq.NhQueryable`1[DIPS.IDMConnector.Entities.User], Quote((u, ) => (String.op_Equality(u.UserName, p1))), ), ))
2013-04-25 09:41:42,324 [3] DEBUG NHibernate.Engine.Query.QueryPlanCache - located HQL query plan in cache (.FirstOrDefault[DIPS.IDMConnector.Entities.User](.Where[DIPS.IDMConnector.Entities.User](NHibernate.Linq.NhQueryable`1[DIPS.IDMConnector.Entities.User], Quote((u, ) => (String.op_Equality(u.UserName, p1))), ), ))
2013-04-25 09:41:42,324 [3] DEBUG NHibernate.Engine.Query.HQLQueryPlan - find: .FirstOrDefault[DIPS.IDMConnector.Entities.User](.Where[DIPS.IDMConnector.Entities.User](NHibernate.Linq.NhQueryable`1[DIPS.IDMConnector.Entities.User], Quote((u, ) => (String.op_Equality(u.UserName, p1))), ), )
2013-04-25 09:41:42,324 [3] DEBUG NHibernate.Engine.QueryParameters - named parameters: {'p1'='PASIENT'}
2013-04-25 09:41:42,325 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,325 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: select PERSONID2_,ETTERNAVN2_,FORNAVN2_,ADRESSEID2_,DIPSSIGN2_4_,BRUKERIB3_4_,PASSORD4_,ANTALLGR5_4_,ANTALLFE6_4_,PASSORDE7_4_ from ( select user0_.PERSONID as PERSONID2_, user0_.ETTERNAVN as ETTERNAVN2_, user0_.FORNAVN as FORNAVN2_, user0_.ADRESSEID as ADRESSEID2_, user0_2_.DIPSSIGNATUR as DIPSSIGN2_4_, user0_2_.BRUKERIBRUK as BRUKERIB3_4_, user0_2_.PASSORD as PASSORD4_, user0_2_.ANTALLGRACELOGINS as ANTALLGR5_4_, user0_2_.ANTALLFEILPASSORD as ANTALLFE6_4_, user0_2_.PASSORDENDRTID as PASSORDE7_4_ from DWPERSON user0_ inner join DWANSATT user0_1_ on user0_.PERSONID=user0_1_.PERSONID inner join DWBRUKER user0_2_ on user0_.PERSONID=user0_2_.PERSONID where user0_2_.DIPSSIGNATUR=? ) where rownum <=1
2013-04-25 09:41:42,325 [3] DEBUG NHibernate.Type.StringType - binding 'PASIENT' to parameter: 0
2013-04-25 09:41:42,325 [3] INFO NHibernate.Loader.Loader - select PERSONID2_,ETTERNAVN2_,FORNAVN2_,ADRESSEID2_,DIPSSIGN2_4_,BRUKERIB3_4_,PASSORD4_,ANTALLGR5_4_,ANTALLFE6_4_,PASSORDE7_4_ from ( select user0_.PERSONID as PERSONID2_, user0_.ETTERNAVN as ETTERNAVN2_, user0_.FORNAVN as FORNAVN2_, user0_.ADRESSEID as ADRESSEID2_, user0_2_.DIPSSIGNATUR as DIPSSIGN2_4_, user0_2_.BRUKERIBRUK as BRUKERIB3_4_, user0_2_.PASSORD as PASSORD4_, user0_2_.ANTALLGRACELOGINS as ANTALLGR5_4_, user0_2_.ANTALLFEILPASSORD as ANTALLFE6_4_, user0_2_.PASSORDENDRTID as PASSORDE7_4_ from DWPERSON user0_ inner join DWANSATT user0_1_ on user0_.PERSONID=user0_1_.PERSONID inner join DWBRUKER user0_2_ on user0_.PERSONID=user0_2_.PERSONID where user0_2_.DIPSSIGNATUR=:p0 ) where rownum <=1
2013-04-25 09:41:42,325 [3] DEBUG NHibernate.SQL - select PERSONID2_,ETTERNAVN2_,FORNAVN2_,ADRESSEID2_,DIPSSIGN2_4_,BRUKERIB3_4_,PASSORD4_,ANTALLGR5_4_,ANTALLFE6_4_,PASSORDE7_4_ from ( select user0_.PERSONID as PERSONID2_, user0_.ETTERNAVN as ETTERNAVN2_, user0_.FORNAVN as FORNAVN2_, user0_.ADRESSEID as ADRESSEID2_, user0_2_.DIPSSIGNATUR as DIPSSIGN2_4_, user0_2_.BRUKERIBRUK as BRUKERIB3_4_, user0_2_.PASSORD as PASSORD4_, user0_2_.ANTALLGRACELOGINS as ANTALLGR5_4_, user0_2_.ANTALLFEILPASSORD as ANTALLFE6_4_, user0_2_.PASSORDENDRTID as PASSORDE7_4_ from DWPERSON user0_ inner join DWANSATT user0_1_ on user0_.PERSONID=user0_1_.PERSONID inner join DWBRUKER user0_2_ on user0_.PERSONID=user0_2_.PERSONID where user0_2_.DIPSSIGNATUR=:p0 ) where rownum <=1;:p0 = 'PASIENT' [Type: String (0)]
2013-04-25 09:41:42,330 [3] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteReader took 8 ms
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened IDataReader, open IDataReaders: 1
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.Loader.Loader - processing result set
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.Loader.Loader - result set row: 0
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.Type.Int64Type - returning '2008510' as column: PERSONID2_
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.Loader.Loader - result row: EntityKey[DIPS.IDMConnector.Entities.User#2008510]
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [DIPS.IDMConnector.Entities.User#2008510]
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Hydrating entity: [DIPS.IDMConnector.Entities.User#2008510]
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.Type.StringType - returning 'SELV' as column: ETTERNAVN2_
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.Type.StringType - returning 'PASIENTEN' as column: FORNAVN2_
2013-04-25 09:41:42,339 [3] DEBUG NHibernate.Type.Int64Type - returning '2008689' as column: ADRESSEID2_
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.Type.StringType - returning 'PASIENT' as column: DIPSSIGN2_4_
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.Type.BooleanType - returning 'True' as column: BRUKERIB3_4_
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.Type.StringType - returning 'C6FE49ACBAF0B3B1E7EF578FC0C6FD83' as column: PASSORD4_
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.Type.Int32Type - returning '7' as column: ANTALLGR5_4_
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.Type.Int32Type - returning '0' as column: ANTALLFE6_4_
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.Type.DateTimeType - returning '14.03.4751 12:59:50' as column: PASSORDE7_4_
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.Loader.Loader - done processing result set (1 rows)
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDataReader, open IDataReaders :0
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - DataReader was closed after 9 ms
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:42,340 [3] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.Loader.Loader - total objects hydrated: 1
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.Engine.TwoPhaseLoad - resolving associations for [DIPS.IDMConnector.Entities.User#2008510]
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.Engine.Loading.LoadContexts - creating collection wrapper:[DIPS.IDMConnector.Entities.User.UserRoles#2008510]
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.Event.Default.DefaultLoadEventListener - loading entity: [DIPS.IDMConnector.Entities.Address#2008689]
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.Event.Default.DefaultLoadEventListener - creating new proxy for entity
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.Engine.TwoPhaseLoad - done materializing entity [DIPS.IDMConnector.Entities.User#2008510]
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.Engine.StatefulPersistenceContext - initializing non-lazy collections
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.AdoNet.ConnectionManager - after autocommit
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2013-04-25 09:41:42,349 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:42,350 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=de5e5dbb-d393-4e1f-a509-69f00c61066b] running ISession.Dispose()
2013-04-25 09:41:42,350 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=de5e5dbb-d393-4e1f-a509-69f00c61066b] executing real Dispose(True)
2013-04-25 09:41:42,350 [3] DEBUG NHibernate.Impl.SessionImpl - closing session
2013-04-25 09:41:42,350 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - running BatcherImpl.Dispose(true)
2013-04-25 09:41:42,350 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=c68a84e6-5a70-4fef-90bf-1e84dd24be0a] opened session at timestamp: 635024797023, for session factory: [/f356b6e2e842431db71726e28be22db7]
2013-04-25 09:41:42,351 [3] DEBUG NHibernate.Transaction.AdoTransaction - Begin (ReadCommitted)
2013-04-25 09:41:42,351 [3] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2013-04-25 09:41:42,351 [3] DEBUG NHibernate.Engine.Query.QueryPlanCache - located native-sql query plan in cache (call DIPSCOREDB.CPSESJON.DWP_APPSTART(:USERID))
2013-04-25 09:41:42,351 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,351 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: call DIPSCOREDB.CPSESJON.DWP_APPSTART(?)
2013-04-25 09:41:42,351 [3] DEBUG NHibernate.Type.StringType - binding '1' to parameter: 0
2013-04-25 09:41:42,351 [3] DEBUG NHibernate.SQL - call DIPSCOREDB.CPSESJON.DWP_APPSTART(:p0);:p0 = '1' [Type: String (0)]
2013-04-25 09:41:42,352 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,362 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 9 ms
2013-04-25 09:41:42,362 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,362 [3] DEBUG NHibernate.Engine.Query.QueryPlanCache - located native-sql query plan in cache (update dwbruker set antallfeilpassord = 0 where personid = :personId)
2013-04-25 09:41:42,362 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,362 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: update dwbruker set antallfeilpassord = 0 where personid = ?
2013-04-25 09:41:42,362 [3] DEBUG NHibernate.Type.Int64Type - binding '2008510' to parameter: 0
2013-04-25 09:41:42,362 [3] DEBUG NHibernate.SQL - update dwbruker set antallfeilpassord = 0 where personid = :p0;:p0 = 2008510 [Type: Int64 (0)]
2013-04-25 09:41:42,362 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,377 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 14 ms
2013-04-25 09:41:42,377 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,377 [3] DEBUG NHibernate.Transaction.AdoTransaction - Start Commit
2013-04-25 09:41:42,377 [3] DEBUG NHibernate.Impl.SessionImpl - before transaction completion
2013-04-25 09:41:42,386 [3] DEBUG NHibernate.Transaction.AdoTransaction - IDbTransaction Committed
2013-04-25 09:41:42,386 [3] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2013-04-25 09:41:42,386 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:42,386 [3] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2013-04-25 09:41:42,394 [3] DEBUG NHibernate.Transaction.AdoTransaction - IDbTransaction disposed.
2013-04-25 09:41:42,394 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=c68a84e6-5a70-4fef-90bf-1e84dd24be0a] running ISession.Dispose()
2013-04-25 09:41:42,394 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=c68a84e6-5a70-4fef-90bf-1e84dd24be0a] executing real Dispose(True)
2013-04-25 09:41:42,394 [3] DEBUG NHibernate.Impl.SessionImpl - closing session
2013-04-25 09:41:42,394 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - running BatcherImpl.Dispose(true)
2013-04-25 09:41:42,394 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=f3fca612-c644-4773-800d-ad27a4b777a9] opened session at timestamp: 635024797023, for session factory: [/f356b6e2e842431db71726e28be22db7]
2013-04-25 09:41:42,396 [3] DEBUG NHibernate.Loader.Criteria.CriteriaQueryTranslator - put criteria=((PersonId = 2008510 and (ValidFrom < 25.04.2013 09:41:42 or ValidFrom is null)) and (ValidTo > 25.04.2013 09:41:42 or ValidTo is null)) alias=this0_
2013-04-25 09:41:42,396 [3] DEBUG NHibernate.Loader.Criteria.CriteriaQueryTranslator - returning alias=this_ for criteria=((PersonId = 2008510 and (ValidFrom < 25.04.2013 09:41:42 or ValidFrom is null)) and (ValidTo > 25.04.2013 09:41:42 or ValidTo is null))
2013-04-25 09:41:42,396 [3] DEBUG NHibernate.Loader.Criteria.CriteriaQueryTranslator - returning alias=this_ for criteria=((PersonId = 2008510 and (ValidFrom < 25.04.2013 09:41:42 or ValidFrom is null)) and (ValidTo > 25.04.2013 09:41:42 or ValidTo is null))
2013-04-25 09:41:42,396 [3] DEBUG NHibernate.Loader.Criteria.CriteriaQueryTranslator - returning alias=this_ for criteria=((PersonId = 2008510 and (ValidFrom < 25.04.2013 09:41:42 or ValidFrom is null)) and (ValidTo > 25.04.2013 09:41:42 or ValidTo is null))
2013-04-25 09:41:42,396 [3] DEBUG NHibernate.Loader.Criteria.CriteriaQueryTranslator - returning alias=this_ for criteria=((PersonId = 2008510 and (ValidFrom < 25.04.2013 09:41:42 or ValidFrom is null)) and (ValidTo > 25.04.2013 09:41:42 or ValidTo is null))
2013-04-25 09:41:42,396 [3] DEBUG NHibernate.Loader.Criteria.CriteriaQueryTranslator - returning alias=this_ for criteria=((PersonId = 2008510 and (ValidFrom < 25.04.2013 09:41:42 or ValidFrom is null)) and (ValidTo > 25.04.2013 09:41:42 or ValidTo is null))
2013-04-25 09:41:42,397 [3] DEBUG NHibernate.SqlCommand.SqlSelectBuilder - The initial capacity was set too low at: 8 for the SelectSqlBuilder that needed a capacity of: 12 for the table DWBRUKERROLLE this_
2013-04-25 09:41:42,397 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,397 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: SELECT this_.BRUKERROLLEID as BRUKERRO1_5_0_, this_.PERSONID as PERSONID5_0_, this_.BETEGNELSE as BETEGNELSE5_0_, this_.SYKEHUSID as SYKEHUSID5_0_, this_.AVDID as AVDID5_0_, this_.GYLDIGFRA as GYLDIGFRA5_0_, this_.GYLDIGTIL as GYLDIGTIL5_0_, this_.TILGANGALLEAVD as TILGANGA8_5_0_ FROM DWBRUKERROLLE this_ WHERE ((this_.PERSONID = ? and (this_.GYLDIGFRA < ? or this_.GYLDIGFRA is null)) and (this_.GYLDIGTIL > ? or this_.GYLDIGTIL is null))
2013-04-25 09:41:42,397 [3] DEBUG NHibernate.Type.Int64Type - binding '2008510' to parameter: 0
2013-04-25 09:41:42,397 [3] DEBUG NHibernate.Type.DateTimeType - binding '25.04.2013 09:41:42' to parameter: 1
2013-04-25 09:41:42,397 [3] DEBUG NHibernate.Type.DateTimeType - binding '25.04.2013 09:41:42' to parameter: 2
2013-04-25 09:41:42,397 [3] INFO NHibernate.Loader.Loader - SELECT this_.BRUKERROLLEID as BRUKERRO1_5_0_, this_.PERSONID as PERSONID5_0_, this_.BETEGNELSE as BETEGNELSE5_0_, this_.SYKEHUSID as SYKEHUSID5_0_, this_.AVDID as AVDID5_0_, this_.GYLDIGFRA as GYLDIGFRA5_0_, this_.GYLDIGTIL as GYLDIGTIL5_0_, this_.TILGANGALLEAVD as TILGANGA8_5_0_ FROM DWBRUKERROLLE this_ WHERE ((this_.PERSONID = :p0 and (this_.GYLDIGFRA < :p1 or this_.GYLDIGFRA is null)) and (this_.GYLDIGTIL > :p2 or this_.GYLDIGTIL is null))
2013-04-25 09:41:42,397 [3] DEBUG NHibernate.SQL - SELECT this_.BRUKERROLLEID as BRUKERRO1_5_0_, this_.PERSONID as PERSONID5_0_, this_.BETEGNELSE as BETEGNELSE5_0_, this_.SYKEHUSID as SYKEHUSID5_0_, this_.AVDID as AVDID5_0_, this_.GYLDIGFRA as GYLDIGFRA5_0_, this_.GYLDIGTIL as GYLDIGTIL5_0_, this_.TILGANGALLEAVD as TILGANGA8_5_0_ FROM DWBRUKERROLLE this_ WHERE ((this_.PERSONID = :p0 and (this_.GYLDIGFRA < :p1 or this_.GYLDIGFRA is null)) and (this_.GYLDIGTIL > :p2 or this_.GYLDIGTIL is null));:p0 = 2008510 [Type: Int64 (0)], :p1 = 25.04.2013 09:41:42 [Type: DateTime (0)], :p2 = 25.04.2013 09:41:42 [Type: DateTime (0)]
2013-04-25 09:41:42,399 [3] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteReader took 8 ms
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened IDataReader, open IDataReaders: 1
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Loader.Loader - processing result set
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Loader.Loader - result set row: 0
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Type.Int64Type - returning '1001660' as column: BRUKERRO1_5_0_
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Loader.Loader - result row: EntityKey[DIPS.IDMConnector.Entities.UserRole#1001660]
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [DIPS.IDMConnector.Entities.UserRole#1001660]
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Hydrating entity: [DIPS.IDMConnector.Entities.UserRole#1001660]
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Type.Int64Type - returning '2008510' as column: PERSONID5_0_
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Type.StringType - returning 'PASIENT: Standard rolle for Selv, Pasienten' as column: BETEGNELSE5_0_
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Type.Int64Type - returning '1' as column: SYKEHUSID5_0_
2013-04-25 09:41:42,409 [3] DEBUG NHibernate.Type.Int64Type - returning '22' as column: AVDID5_0_
2013-04-25 09:41:42,410 [3] DEBUG NHibernate.Type.DateTimeType - returning '10.05.2009 00:00:00' as column: GYLDIGFRA5_0_
2013-04-25 09:41:42,410 [3] DEBUG NHibernate.Type.DateTimeType - returning null as column: GYLDIGTIL5_0_
2013-04-25 09:41:42,410 [3] DEBUG NHibernate.Type.BooleanType - returning 'False' as column: TILGANGA8_5_0_
2013-04-25 09:41:42,410 [3] DEBUG NHibernate.Loader.Loader - done processing result set (1 rows)
2013-04-25 09:41:42,410 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDataReader, open IDataReaders :0
2013-04-25 09:41:42,410 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - DataReader was closed after 10 ms
2013-04-25 09:41:42,410 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,410 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:42,410 [3] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.Loader.Loader - total objects hydrated: 1
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.Engine.TwoPhaseLoad - resolving associations for [DIPS.IDMConnector.Entities.UserRole#1001660]
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.Engine.TwoPhaseLoad - done materializing entity [DIPS.IDMConnector.Entities.UserRole#1001660]
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.Engine.StatefulPersistenceContext - initializing non-lazy collections
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.AdoNet.ConnectionManager - after autocommit
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=f3fca612-c644-4773-800d-ad27a4b777a9] running ISession.Dispose()
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=f3fca612-c644-4773-800d-ad27a4b777a9] executing real Dispose(True)
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.Impl.SessionImpl - closing session
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - running BatcherImpl.Dispose(true)
2013-04-25 09:41:42,418 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=4257caa5-404e-47bb-9f74-171fea749b46] opened session at timestamp: 635024797024, for session factory: [/f356b6e2e842431db71726e28be22db7]
2013-04-25 09:41:42,419 [3] DEBUG NHibernate.Engine.Query.QueryPlanCache - located native-sql query plan in cache (call DwSecurity.ciDinaContext.CreateNewSession(:ticket, :userId, :roleId))
2013-04-25 09:41:42,419 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,419 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: call DwSecurity.ciDinaContext.CreateNewSession(?, ?, ?)
2013-04-25 09:41:42,419 [3] DEBUG NHibernate.Type.StringType - binding '22a2e665-d485-41b4-a23c-976a349ee01f' to parameter: 0
2013-04-25 09:41:42,419 [3] DEBUG NHibernate.Type.Int64Type - binding '2008510' to parameter: 1
2013-04-25 09:41:42,419 [3] DEBUG NHibernate.Type.Int64Type - binding '1001660' to parameter: 2
2013-04-25 09:41:42,419 [3] DEBUG NHibernate.SQL - call DwSecurity.ciDinaContext.CreateNewSession(:p0, :p1, :p2);:p0 = '22a2e665-d485-41b4-a23c-976a349ee01f' [Type: String (0)], :p1 = 2008510 [Type: Int64 (0)], :p2 = 1001660 [Type: Int64 (0)]
2013-04-25 09:41:42,420 [3] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2013-04-25 09:41:42,434 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 14 ms
2013-04-25 09:41:42,435 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,435 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:42,435 [3] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2013-04-25 09:41:42,442 [3] DEBUG NHibernate.AdoNet.ConnectionManager - after autocommit
2013-04-25 09:41:42,442 [3] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2013-04-25 09:41:42,442 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:42,442 [3] DEBUG NHibernate.Transaction.AdoTransaction - Begin (ReadCommitted)
2013-04-25 09:41:42,442 [3] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2013-04-25 09:41:42,443 [3] DEBUG NHibernate.Engine.Query.QueryPlanCache - located native-sql query plan in cache (call DIPSCOREDB.CPSESJON.DWP_APPSTART(:USERID))
2013-04-25 09:41:42,443 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,443 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: call DIPSCOREDB.CPSESJON.DWP_APPSTART(?)
2013-04-25 09:41:42,443 [3] DEBUG NHibernate.Type.StringType - binding '2008510' to parameter: 0
2013-04-25 09:41:42,443 [3] DEBUG NHibernate.SQL - call DIPSCOREDB.CPSESJON.DWP_APPSTART(:p0);:p0 = '2008510' [Type: String (0)]
2013-04-25 09:41:42,444 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,453 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 8 ms
2013-04-25 09:41:42,453 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,453 [3] DEBUG NHibernate.Engine.Query.QueryPlanCache - located native-sql query plan in cache (update dwbruker set sisteinnloggingtid = :time where personid = :personId)
2013-04-25 09:41:42,453 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,453 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: update dwbruker set sisteinnloggingtid = ? where personid = ?
2013-04-25 09:41:42,453 [3] DEBUG NHibernate.Type.DateTimeType - binding '25.04.2013 09:41:42' to parameter: 0
2013-04-25 09:41:42,453 [3] DEBUG NHibernate.Type.Int64Type - binding '2008510' to parameter: 1
2013-04-25 09:41:42,453 [3] DEBUG NHibernate.SQL - update dwbruker set sisteinnloggingtid = :p0 where personid = :p1;:p0 = 25.04.2013 09:41:42 [Type: DateTime (0)], :p1 = 2008510 [Type: Int64 (0)]
2013-04-25 09:41:42,454 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,468 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 13 ms
2013-04-25 09:41:42,468 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,468 [3] DEBUG NHibernate.Transaction.AdoTransaction - Start Commit
2013-04-25 09:41:42,468 [3] DEBUG NHibernate.Impl.SessionImpl - before transaction completion
2013-04-25 09:41:42,476 [3] DEBUG NHibernate.Transaction.AdoTransaction - IDbTransaction Committed
2013-04-25 09:41:42,476 [3] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2013-04-25 09:41:42,476 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:42,476 [3] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2013-04-25 09:41:42,484 [3] DEBUG NHibernate.Transaction.AdoTransaction - IDbTransaction disposed.
2013-04-25 09:41:42,484 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=4257caa5-404e-47bb-9f74-171fea749b46] running ISession.Dispose()
2013-04-25 09:41:42,484 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=4257caa5-404e-47bb-9f74-171fea749b46] executing real Dispose(True)
2013-04-25 09:41:42,484 [3] DEBUG NHibernate.Impl.SessionImpl - closing session
2013-04-25 09:41:42,484 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - running BatcherImpl.Dispose(true)
2013-04-25 09:41:42,507 [3] WARN ServiceStack.Common.Support.AssignmentDefinition - Error trying to set properties DIPS.IDMConnector.UserRequest.Nullable`1 > DIPS.IDMConnector.Entities.User.Boolean
System.NullReferenceException: Object reference not set to an instance of an object.
at lambda_method(Closure , Object , Object )
at ServiceStack.Common.Support.AssignmentDefinition.Populate(Object to, Object from, Func`2 propertyInfoPredicate, Func`2 valuePredicate) in c:\Users\ths\Downloads\ServiceStack3942\ServiceStack-3.9.42\src\ServiceStack.Common\Support\AssignmentDefinition.cs:line 181
2013-04-25 09:41:42,507 [3] DEBUG NHibernate.Impl.SessionImpl - [session-id=f1e8cc31-7ff3-4821-9af4-603aec5b7091] opened session at timestamp: 635024797025, for session factory: [/f356b6e2e842431db71726e28be22db7]
2013-04-25 09:41:42,507 [3] DEBUG NHibernate.Transaction.AdoTransaction - Begin (Unspecified)
2013-04-25 09:41:42,507 [3] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2013-04-25 09:41:42,507 [3] DEBUG NHibernate.Engine.IdentifierValue - unsaved-value: 0
2013-04-25 09:41:42,508 [3] DEBUG NHibernate.Event.Default.AbstractSaveEventListener - transient instance of: DIPS.IDMConnector.Entities.User
2013-04-25 09:41:42,508 [3] DEBUG NHibernate.Event.Default.DefaultSaveOrUpdateEventListener - saving transient instance
2013-04-25 09:41:42,508 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,508 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: SELECT DIPSCOREDB.CPSESJON.DWF_SEKVENSVERDI('DWPERSON', 'NEXT') AS LONGID FROM DUAL
2013-04-25 09:41:42,508 [3] DEBUG NHibernate.SQL - SELECT DIPSCOREDB.CPSESJON.DWF_SEKVENSVERDI('DWPERSON', 'NEXT') AS LONGID FROM DUAL
2013-04-25 09:41:42,508 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteReader took 10 ms
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened IDataReader, open IDataReaders: 1
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDataReader, open IDataReaders :0
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - DataReader was closed after 10 ms
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.Event.Default.AbstractSaveEventListener - generated identifier: 2011543, using strategy: DIPS.IDMConnector.Server.DB.Entities.Mappings.IdGenerators.LongIdFromSequenceGenerator
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.Event.Default.AbstractSaveEventListener - saving [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:42,519 [3] INFO NHibernate.Engine.Cascade - processing cascade NHibernate.Engine.CascadingAction+SaveUpdateCascadingAction for: DIPS.IDMConnector.Entities.User
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.Engine.CascadingAction - cascading to saveOrUpdate: DIPS.IDMConnector.Entities.Address
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.Engine.IdentifierValue - unsaved-value: 0
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.Event.Default.AbstractSaveEventListener - transient instance of: DIPS.IDMConnector.Entities.Address
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.Event.Default.DefaultSaveOrUpdateEventListener - saving transient instance
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: SELECT DIPSCOREDB.CPSESJON.DWF_SEKVENSVERDI('DWADRESSE', 'NEXT') AS LONGID FROM DUAL
2013-04-25 09:41:42,519 [3] DEBUG NHibernate.SQL - SELECT DIPSCOREDB.CPSESJON.DWF_SEKVENSVERDI('DWADRESSE', 'NEXT') AS LONGID FROM DUAL
2013-04-25 09:41:42,520 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,530 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteReader took 9 ms
2013-04-25 09:41:42,530 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened IDataReader, open IDataReaders: 1
2013-04-25 09:41:42,530 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDataReader, open IDataReaders :0
2013-04-25 09:41:42,530 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - DataReader was closed after 10 ms
2013-04-25 09:41:42,530 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,530 [3] DEBUG NHibernate.Event.Default.AbstractSaveEventListener - generated identifier: 2194454, using strategy: DIPS.IDMConnector.Server.DB.Entities.Mappings.IdGenerators.LongIdFromSequenceGenerator
2013-04-25 09:41:42,530 [3] DEBUG NHibernate.Event.Default.AbstractSaveEventListener - saving [DIPS.IDMConnector.Entities.Address#2194454]
2013-04-25 09:41:42,530 [3] INFO NHibernate.Engine.Cascade - done processing cascade NHibernate.Engine.CascadingAction+SaveUpdateCascadingAction for: DIPS.IDMConnector.Entities.User
2013-04-25 09:41:42,530 [3] INFO NHibernate.Engine.Cascade - processing cascade NHibernate.Engine.CascadingAction+SaveUpdateCascadingAction for: DIPS.IDMConnector.Entities.User
2013-04-25 09:41:42,530 [3] INFO NHibernate.Engine.Cascade - done processing cascade NHibernate.Engine.CascadingAction+SaveUpdateCascadingAction for: DIPS.IDMConnector.Entities.User
2013-04-25 09:41:42,530 [3] DEBUG NHibernate.Transaction.AdoTransaction - Start Commit
2013-04-25 09:41:42,530 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - flushing session
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - processing flush-time cascades
2013-04-25 09:41:42,531 [3] INFO NHibernate.Engine.Cascade - processing cascade NHibernate.Engine.CascadingAction+SaveUpdateCascadingAction for: DIPS.IDMConnector.Entities.User
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Engine.CascadingAction - cascading to saveOrUpdate: DIPS.IDMConnector.Entities.Address
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.AbstractSaveEventListener - persistent instance of: DIPS.IDMConnector.Entities.Address
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.DefaultSaveOrUpdateEventListener - ignoring persistent instance
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.DefaultSaveOrUpdateEventListener - object already associated with session: [DIPS.IDMConnector.Entities.Address#2194454]
2013-04-25 09:41:42,531 [3] INFO NHibernate.Engine.Cascade - done processing cascade NHibernate.Engine.CascadingAction+SaveUpdateCascadingAction for: DIPS.IDMConnector.Entities.User
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - dirty checking collections
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Flushing entities and processing referenced collections
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Processing unreferenced collections
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Scheduling collection removes/(re)creates/updates
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Flushed: 2 insertions, 0 updates, 0 deletions to 2 objects
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Impl.Printer - listing entities:
2013-04-25 09:41:42,531 [3] DEBUG NHibernate.Impl.Printer - DIPS.IDMConnector.Entities.Address{'AddressId'='2194454'}
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.Impl.Printer - DIPS.IDMConnector.Entities.User{'PersonId'='2011543', 'UserRoles'='null', 'LastName'='Balle Klorin', 'FirstName'='Dagens Andre', 'Address'='DIPS.IDMConnector.Entities.Address#2194454', 'UserName'='BALLEKLOR', 'IsActive'='True', 'Passord'=null, 'GraceLogonCount'='7', 'WrongPasswordCount'='0', 'PasswordLastChangeDateTime'='01.01.0001 00:00:00'}
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - executing flush
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.AdoNet.ConnectionManager - registering flush begin
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Inserting entity: [DIPS.IDMConnector.Entities.Address#2194454]
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: INSERT INTO DWADRESSE (ADRESSEID) VALUES (?)
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Dehydrating entity: [DIPS.IDMConnector.Entities.Address#2194454]
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.Type.Int64Type - binding '2194454' to parameter: 0
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.SQL - INSERT INTO DWADRESSE (ADRESSEID) VALUES (:p0);:p0 = 2194454 [Type: Int64 (0)]
2013-04-25 09:41:42,532 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 12 ms
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Inserting entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteBatch for 1 statements took 0 ms
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: INSERT INTO DWPERSON (ETTERNAVN, FORNAVN, ADRESSEID, PERSONID) VALUES (?, ?, ?, ?)
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Dehydrating entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.Type.StringType - binding 'Balle Klorin' to parameter: 0
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.Type.StringType - binding 'Dagens Andre' to parameter: 1
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.Type.Int64Type - binding '2194454' to parameter: 2
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.Type.Int64Type - binding '2011543' to parameter: 3
2013-04-25 09:41:42,545 [3] DEBUG NHibernate.SQL - INSERT INTO DWPERSON (ETTERNAVN, FORNAVN, ADRESSEID, PERSONID) VALUES (:p0, :p1, :p2, :p3);:p0 = 'Balle Klorin' [Type: String (0)], :p1 = 'Dagens Andre' [Type: String (0)], :p2 = 2194454 [Type: Int64 (0)], :p3 = 2011543 [Type: Int64 (0)]
2013-04-25 09:41:42,546 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,663 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 117 ms
2013-04-25 09:41:42,663 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Inserting entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:42,663 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteBatch for 1 statements took 0 ms
2013-04-25 09:41:42,664 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,664 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,664 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: INSERT INTO DWANSATT (PERSONID) VALUES (?)
2013-04-25 09:41:42,664 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Dehydrating entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:42,664 [3] DEBUG NHibernate.Type.Int64Type - binding '2011543' to parameter: 0
2013-04-25 09:41:42,664 [3] DEBUG NHibernate.SQL - INSERT INTO DWANSATT (PERSONID) VALUES (:p0);:p0 = 2011543 [Type: Int64 (0)]
2013-04-25 09:41:42,664 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,674 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 9 ms
2013-04-25 09:41:42,674 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,674 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Inserting entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:42,674 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,674 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (?, ?, ?, ?, ?, ?, ?)
2013-04-25 09:41:42,674 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Dehydrating entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:42,674 [3] DEBUG NHibernate.Type.StringType - binding 'BALLEKLOR' to parameter: 0
2013-04-25 09:41:42,675 [3] DEBUG NHibernate.Type.BooleanType - binding 'True' to parameter: 1
2013-04-25 09:41:42,675 [3] DEBUG NHibernate.Type.StringType - binding null to parameter: 2
2013-04-25 09:41:42,675 [3] DEBUG NHibernate.Type.Int32Type - binding '7' to parameter: 3
2013-04-25 09:41:42,675 [3] DEBUG NHibernate.Type.Int32Type - binding '0' to parameter: 4
2013-04-25 09:41:42,675 [3] DEBUG NHibernate.Type.DateTimeType - binding '01.01.0001 00:00:00' to parameter: 5
2013-04-25 09:41:42,675 [3] DEBUG NHibernate.Type.Int64Type - binding '2011543' to parameter: 6
2013-04-25 09:41:42,675 [3] DEBUG NHibernate.SQL - INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (:p0, :p1, :p2, :p3, :p4, :p5, :p6);:p0 = 'BALLEKLOR' [Type: String (0)], :p1 = True [Type: Int16 (0)], :p2 = NULL [Type: String (0)], :p3 = 7 [Type: Int32 (0)], :p4 = 0 [Type: Int32 (0)], :p5 = 01.01.0001 00:00:00 [Type: DateTime (0)], :p6 = 2011543 [Type: Int64 (0)]
2013-04-25 09:41:42,677 [3] DEBUG NHibernate.Transaction.AdoTransaction - Enlist Command
2013-04-25 09:41:42,749 [3] ERROR NHibernate.AdoNet.AbstractBatcher - Could not execute command: INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (:p0, :p1, :p2, :p3, :p4, :p5, :p6)
Oracle.DataAccess.Client.OracleException ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA) at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure, Boolean bCheck)
at Oracle.DataAccess.Client.OracleException.HandleError(Int32 errCode, OracleConnection conn, String procedure, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, Boolean bCheck)
at Oracle.DataAccess.Client.OracleCommand.ExecuteNonQuery()
at NHibernate.AdoNet.AbstractBatcher.ExecuteNonQuery(IDbCommand cmd)
2013-04-25 09:41:42,768 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 90 ms
2013-04-25 09:41:42,785 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,790 [3] DEBUG NHibernate.Util.ADOExceptionReporter - could not insert: [DIPS.IDMConnector.Entities.User#2011543]
[ INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (:p0, :p1, :p2, :p3, :p4, :p5, :p6) ]
Oracle.DataAccess.Client.OracleException ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA) at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure, Boolean bCheck)
at Oracle.DataAccess.Client.OracleException.HandleError(Int32 errCode, OracleConnection conn, String procedure, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, Boolean bCheck)
at Oracle.DataAccess.Client.OracleCommand.ExecuteNonQuery()
at NHibernate.AdoNet.AbstractBatcher.ExecuteNonQuery(IDbCommand cmd)
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Boolean[] notNull, Int32 j, SqlCommandInfo sql, Object obj, ISessionImplementor session)
2013-04-25 09:41:42,791 [3] WARN NHibernate.Util.ADOExceptionReporter - Oracle.DataAccess.Client.OracleException ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA) at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure, Boolean bCheck)
at Oracle.DataAccess.Client.OracleException.HandleError(Int32 errCode, OracleConnection conn, String procedure, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, Boolean bCheck)
at Oracle.DataAccess.Client.OracleCommand.ExecuteNonQuery()
at NHibernate.AdoNet.AbstractBatcher.ExecuteNonQuery(IDbCommand cmd)
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Boolean[] notNull, Int32 j, SqlCommandInfo sql, Object obj, ISessionImplementor session)
2013-04-25 09:41:42,791 [3] ERROR NHibernate.Util.ADOExceptionReporter - ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA)
2013-04-25 09:41:42,809 [3] ERROR NHibernate.Event.Default.AbstractFlushingEventListener - Could not synchronize database state with session
NHibernate.Exceptions.GenericADOException: could not insert: [DIPS.IDMConnector.Entities.User#2011543][SQL: INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (?, ?, ?, ?, ?, ?, ?)] ---> Oracle.DataAccess.Client.OracleException: ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA)
at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure, Boolean bCheck)
at Oracle.DataAccess.Client.OracleException.HandleError(Int32 errCode, OracleConnection conn, String procedure, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, Boolean bCheck)
at Oracle.DataAccess.Client.OracleCommand.ExecuteNonQuery()
at NHibernate.AdoNet.AbstractBatcher.ExecuteNonQuery(IDbCommand cmd)
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Boolean[] notNull, Int32 j, SqlCommandInfo sql, Object obj, ISessionImplementor session)
--- End of inner exception stack trace ---
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Boolean[] notNull, Int32 j, SqlCommandInfo sql, Object obj, ISessionImplementor session)
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Object obj, ISessionImplementor session)
at NHibernate.Action.EntityInsertAction.Execute()
at NHibernate.Engine.ActionQueue.Execute(IExecutable executable)
at NHibernate.Engine.ActionQueue.ExecuteActions(IList list)
at NHibernate.Engine.ActionQueue.ExecuteActions()
at NHibernate.Event.Default.AbstractFlushingEventListener.PerformExecutions(IEventSource session)
2013-04-25 09:41:42,829 [3] DEBUG NHibernate.AdoNet.ConnectionManager - registering flush end
2013-04-25 09:41:42,830 [3] DEBUG NHibernate.Transaction.AdoTransaction - Rollback
2013-04-25 09:41:42,843 [3] DEBUG NHibernate.Transaction.AdoTransaction - IDbTransaction RolledBack
2013-04-25 09:41:42,843 [3] DEBUG NHibernate.Transaction.AdoTransaction - IDbTransaction disposed.
2013-04-25 09:41:42,843 [3] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2013-04-25 09:41:42,843 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:42,843 [3] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2013-04-25 09:41:42,882 [3] ERROR ServiceStack.ServiceHost.DtoUtils - ServiceBase<TRequest>::Service Exception
Oracle.DataAccess.Client.OracleException ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA) at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure, Boolean bCheck)
at Oracle.DataAccess.Client.OracleException.HandleError(Int32 errCode, OracleConnection conn, String procedure, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, Boolean bCheck)
at Oracle.DataAccess.Client.OracleCommand.ExecuteNonQuery()
at NHibernate.AdoNet.AbstractBatcher.ExecuteNonQuery(IDbCommand cmd)
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Boolean[] notNull, Int32 j, SqlCommandInfo sql, Object obj, ISessionImplementor session)
2013-04-25 09:41:42,945 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - flushing session
2013-04-25 09:41:42,945 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - processing flush-time cascades
2013-04-25 09:41:42,945 [3] INFO NHibernate.Engine.Cascade - processing cascade NHibernate.Engine.CascadingAction+SaveUpdateCascadingAction for: DIPS.IDMConnector.Entities.User
2013-04-25 09:41:42,945 [3] DEBUG NHibernate.Engine.CascadingAction - cascading to saveOrUpdate: DIPS.IDMConnector.Entities.Address
2013-04-25 09:41:42,945 [3] DEBUG NHibernate.Event.Default.AbstractSaveEventListener - persistent instance of: DIPS.IDMConnector.Entities.Address
2013-04-25 09:41:42,945 [3] DEBUG NHibernate.Event.Default.DefaultSaveOrUpdateEventListener - ignoring persistent instance
2013-04-25 09:41:42,945 [3] DEBUG NHibernate.Event.Default.DefaultSaveOrUpdateEventListener - object already associated with session: [DIPS.IDMConnector.Entities.Address#2194454]
2013-04-25 09:41:42,946 [3] INFO NHibernate.Engine.Cascade - done processing cascade NHibernate.Engine.CascadingAction+SaveUpdateCascadingAction for: DIPS.IDMConnector.Entities.User
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - dirty checking collections
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Flushing entities and processing referenced collections
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Processing unreferenced collections
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Scheduling collection removes/(re)creates/updates
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Flushed: 2 insertions, 0 updates, 0 deletions to 2 objects
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Impl.Printer - listing entities:
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Impl.Printer - DIPS.IDMConnector.Entities.Address{'AddressId'='2194454'}
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Impl.Printer - DIPS.IDMConnector.Entities.User{'PersonId'='2011543', 'UserRoles'='null', 'LastName'='Balle Klorin', 'FirstName'='Dagens Andre', 'Address'='DIPS.IDMConnector.Entities.Address#2194454', 'UserName'='BALLEKLOR', 'IsActive'='True', 'Passord'=null, 'GraceLogonCount'='7', 'WrongPasswordCount'='0', 'PasswordLastChangeDateTime'='01.01.0001 00:00:00'}
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Event.Default.AbstractFlushingEventListener - executing flush
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.AdoNet.ConnectionManager - registering flush begin
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Inserting entity: [DIPS.IDMConnector.Entities.Address#2194454]
2013-04-25 09:41:42,946 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,947 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: INSERT INTO DWADRESSE (ADRESSEID) VALUES (?)
2013-04-25 09:41:42,947 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Dehydrating entity: [DIPS.IDMConnector.Entities.Address#2194454]
2013-04-25 09:41:42,947 [3] DEBUG NHibernate.Type.Int64Type - binding '2194454' to parameter: 0
2013-04-25 09:41:42,947 [3] DEBUG NHibernate.SQL - INSERT INTO DWADRESSE (ADRESSEID) VALUES (:p0);:p0 = 2194454 [Type: Int64 (0)]
2013-04-25 09:41:42,947 [3] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 12 ms
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Inserting entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteBatch for 1 statements took 0 ms
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.AdoNet.ConnectionManager - skipping aggressive-release due to flush cycle
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: INSERT INTO DWPERSON (ETTERNAVN, FORNAVN, ADRESSEID, PERSONID) VALUES (?, ?, ?, ?)
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Dehydrating entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.Type.StringType - binding 'Balle Klorin' to parameter: 0
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.Type.StringType - binding 'Dagens Andre' to parameter: 1
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.Type.Int64Type - binding '2194454' to parameter: 2
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.Type.Int64Type - binding '2011543' to parameter: 3
2013-04-25 09:41:42,960 [3] DEBUG NHibernate.SQL - INSERT INTO DWPERSON (ETTERNAVN, FORNAVN, ADRESSEID, PERSONID) VALUES (:p0, :p1, :p2, :p3);:p0 = 'Balle Klorin' [Type: String (0)], :p1 = 'Dagens Andre' [Type: String (0)], :p2 = 2194454 [Type: Int64 (0)], :p3 = 2011543 [Type: Int64 (0)]
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 94 ms
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Inserting entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteBatch for 1 statements took 0 ms
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.AdoNet.ConnectionManager - skipping aggressive-release due to flush cycle
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: INSERT INTO DWANSATT (PERSONID) VALUES (?)
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Dehydrating entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.Type.Int64Type - binding '2011543' to parameter: 0
2013-04-25 09:41:43,056 [3] DEBUG NHibernate.SQL - INSERT INTO DWANSATT (PERSONID) VALUES (:p0);:p0 = 2011543 [Type: Int64 (0)]
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 12 ms
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.AdoNet.ConnectionManager - skipping aggressive-release due to flush cycle
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Inserting entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Opened new IDbCommand, open IDbCommands: 1
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Building an IDbCommand object for the SqlString: INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (?, ?, ?, ?, ?, ?, ?)
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Dehydrating entity: [DIPS.IDMConnector.Entities.User#2011543]
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.Type.StringType - binding 'BALLEKLOR' to parameter: 0
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.Type.BooleanType - binding 'True' to parameter: 1
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.Type.StringType - binding null to parameter: 2
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.Type.Int32Type - binding '7' to parameter: 3
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.Type.Int32Type - binding '0' to parameter: 4
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.Type.DateTimeType - binding '01.01.0001 00:00:00' to parameter: 5
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.Type.Int64Type - binding '2011543' to parameter: 6
2013-04-25 09:41:43,069 [3] DEBUG NHibernate.SQL - INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (:p0, :p1, :p2, :p3, :p4, :p5, :p6);:p0 = 'BALLEKLOR' [Type: String (0)], :p1 = True [Type: Int16 (0)], :p2 = NULL [Type: String (0)], :p3 = 7 [Type: Int32 (0)], :p4 = 0 [Type: Int32 (0)], :p5 = 01.01.0001 00:00:00 [Type: DateTime (0)], :p6 = 2011543 [Type: Int64 (0)]
2013-04-25 09:41:43,111 [3] ERROR NHibernate.AdoNet.AbstractBatcher - Could not execute command: INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (:p0, :p1, :p2, :p3, :p4, :p5, :p6)
Oracle.DataAccess.Client.OracleException ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA) at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure, Boolean bCheck)
at Oracle.DataAccess.Client.OracleException.HandleError(Int32 errCode, OracleConnection conn, String procedure, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, Boolean bCheck)
at Oracle.DataAccess.Client.OracleCommand.ExecuteNonQuery()
at NHibernate.AdoNet.AbstractBatcher.ExecuteNonQuery(IDbCommand cmd)
2013-04-25 09:41:43,124 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - ExecuteNonQuery took 53 ms
2013-04-25 09:41:43,139 [3] DEBUG NHibernate.AdoNet.AbstractBatcher - Closed IDbCommand, open IDbCommands: 0
2013-04-25 09:41:43,139 [3] DEBUG NHibernate.AdoNet.ConnectionManager - skipping aggressive-release due to flush cycle
2013-04-25 09:41:43,139 [3] DEBUG NHibernate.Util.ADOExceptionReporter - could not insert: [DIPS.IDMConnector.Entities.User#2011543]
[ INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (:p0, :p1, :p2, :p3, :p4, :p5, :p6) ]
Oracle.DataAccess.Client.OracleException ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA) at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure, Boolean bCheck)
at Oracle.DataAccess.Client.OracleException.HandleError(Int32 errCode, OracleConnection conn, String procedure, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, Boolean bCheck)
at Oracle.DataAccess.Client.OracleCommand.ExecuteNonQuery()
at NHibernate.AdoNet.AbstractBatcher.ExecuteNonQuery(IDbCommand cmd)
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Boolean[] notNull, Int32 j, SqlCommandInfo sql, Object obj, ISessionImplementor session)
2013-04-25 09:41:43,139 [3] WARN NHibernate.Util.ADOExceptionReporter - Oracle.DataAccess.Client.OracleException ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA) at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure, Boolean bCheck)
at Oracle.DataAccess.Client.OracleException.HandleError(Int32 errCode, OracleConnection conn, String procedure, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, Boolean bCheck)
at Oracle.DataAccess.Client.OracleCommand.ExecuteNonQuery()
at NHibernate.AdoNet.AbstractBatcher.ExecuteNonQuery(IDbCommand cmd)
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Boolean[] notNull, Int32 j, SqlCommandInfo sql, Object obj, ISessionImplementor session)
2013-04-25 09:41:43,140 [3] ERROR NHibernate.Util.ADOExceptionReporter - ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA)
2013-04-25 09:41:43,152 [3] ERROR NHibernate.Event.Default.AbstractFlushingEventListener - Could not synchronize database state with session
NHibernate.Exceptions.GenericADOException: could not insert: [DIPS.IDMConnector.Entities.User#2011543][SQL: INSERT INTO DWBRUKER (DIPSSIGNATUR, BRUKERIBRUK, PASSORD, ANTALLGRACELOGINS, ANTALLFEILPASSORD, PASSORDENDRTID, PERSONID) VALUES (?, ?, ?, ?, ?, ?, ?)] ---> Oracle.DataAccess.Client.OracleException: ORA-00001: brudd på unik skranke (DIPSCOREDB.DWBRUKER_UINDDIPSSIGNA)
at Oracle.DataAccess.Client.OracleException.HandleErrorHelper(Int32 errCode, OracleConnection conn, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, String procedure, Boolean bCheck)
at Oracle.DataAccess.Client.OracleException.HandleError(Int32 errCode, OracleConnection conn, String procedure, IntPtr opsErrCtx, OpoSqlValCtx* pOpoSqlValCtx, Object src, Boolean bCheck)
at Oracle.DataAccess.Client.OracleCommand.ExecuteNonQuery()
at NHibernate.AdoNet.AbstractBatcher.ExecuteNonQuery(IDbCommand cmd)
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Boolean[] notNull, Int32 j, SqlCommandInfo sql, Object obj, ISessionImplementor session)
--- End of inner exception stack trace ---
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Boolean[] notNull, Int32 j, SqlCommandInfo sql, Object obj, ISessionImplementor session)
at NHibernate.Persister.Entity.AbstractEntityPersister.Insert(Object id, Object[] fields, Object obj, ISessionImplementor session)
at NHibernate.Action.EntityInsertAction.Execute()
at NHibernate.Engine.ActionQueue.Execute(IExecutable executable)
at NHibernate.Engine.ActionQueue.ExecuteActions(IList list)
at NHibernate.Engine.ActionQueue.ExecuteActions()
at NHibernate.Event.Default.AbstractFlushingEventListener.PerformExecutions(IEventSource session)
2013-04-25 09:41:43,166 [3] DEBUG NHibernate.AdoNet.ConnectionManager - registering flush end
2013-04-25 09:41:43,166 [3] DEBUG NHibernate.AdoNet.ConnectionManager - aggressively releasing database connection
2013-04-25 09:41:43,167 [3] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment