Unable to create new employee

Hello,

I hope someone can help. I plan to log a ticket tomorrow if I get no response tonight.

I'm unable to create new employees in Manager or Data import process. I get no error and manager or data importer just locks. I can create new business roles, organizations but not employees.

I have some trace logs but i cannot make out what could be wrong.

Has anyone seen this or have some clue as to what could be the issue?

I will put as much as the logs i can in below.

Thank you, Lu

2020-10-28 23:37:07.6689 TRACE (SqlLog Database Session GC) : Cleanup physical connection pool
2020-10-28 23:37:07.6689 TRACE (SqlLog Database Session GC) : 0 connections disposed.
2020-10-28 23:37:07.6689 TRACE (SqlLog Database Session GC) : Available = 1
2020-10-28 23:37:13.2854 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Person: Creating new entity, creation type Interactive
2020-10-28 23:37:13.4416 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicInitialize
2020-10-28 23:37:13.4416 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Person: Initializing Customizer
2020-10-28 23:37:13.4416 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on RiskIndexCalculated CanEdit depends on _IsAdministrativeAccount
2020-10-28 23:37:13.4416 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running CanEdit for RiskIndexCalculated in Person
2020-10-28 23:37:13.4416 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '_IsAdministrativeAccount' for entity logic...
2020-10-28 23:37:13.4416 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Expensive function...
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: RiskIndexCalculated CanEdit = False
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on DialogUserSalt CanEdit depends on
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running CanEdit for DialogUserSalt in Person
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: DialogUserSalt CanEdit = False
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on DeactivationEnd CanEdit depends on DeactivationStart, IsTemporaryDeactivated
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running CanEdit for DeactivationEnd in Person
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value 'DeactivationStart' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value 'IsTemporaryDeactivated' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: DeactivationEnd CanEdit = False
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on UID_RealPerson CanEdit depends on IsDummyPerson
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running CanEdit for UID_RealPerson in Person
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value 'IsDummyPerson' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: UID_RealPerson CanEdit = False
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on UID_X500Person CanEdit depends on IsX500Dummy
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running CanEdit for UID_X500Person in Person
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value 'IsX500Dummy' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: UID_X500Person CanEdit = False
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on IsTemporaryDeactivated CanEdit depends on IsInactive
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running CanEdit for IsTemporaryDeactivated in Person
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value 'IsInactive' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: IsTemporaryDeactivated CanEdit = True
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on UID_PersonHead MinLen depends on IdentityType
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running MinLen for UID_PersonHead in Person
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value 'IdentityType' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: UID_PersonHead MinLen = 0
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on UID_PersonMasterIdentity MinLen depends on IdentityType
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running MinLen for UID_PersonMasterIdentity in Person
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value 'IdentityType' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: UID_PersonMasterIdentity MinLen = 0
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on DateLastWorked MinLen depends on ExitDate
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running MinLen for DateLastWorked in Person
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value 'ExitDate' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: DateLastWorked MinLen = 0
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on CreateAdminUser(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodEnabled depends on _AdminUserExists
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running MethodEnabled for CreateAdminUser(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) in Person
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '_AdminUserExists' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Expensive function...
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: CreateAdminUser(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodEnabled = False
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on CreateAdminUser(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodVisible depends on _AdminUserExists
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running MethodVisible for CreateAdminUser(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) in Person
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '_AdminUserExists' for entity logic...
2020-10-28 23:37:13.4572 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Got from cache.
2020-10-28 23:37:13.4572 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: CreateAdminUser(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodVisible = False
2020-10-28 23:37:13.4729 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicInitialize, New state: None
2020-10-28 23:37:13.4729 TRACE (ObjectLog ) : Loading strings from TSB.Customizer, Version=8.1.0.0, Culture=neutral, PublicKeyToken=5d0660cde5b231fd|TSB.Customizer.Properties.Strings.
2020-10-28 23:37:13.4729 TRACE (ObjectLog ) : Loading strings from QER.Customizer, Version=8.1.0.0, Culture=neutral, PublicKeyToken=5d0660cde5b231fd|QER.Customizer.Properties.Strings.
2020-10-28 23:37:13.4729 TRACE (ObjectLog ) : Loading strings from Common.Customizer, Version=8.1.0.0, Culture=neutral, PublicKeyToken=5d0660cde5b231fd|VI.Common.Customizer.Properties.Strings.
2020-10-28 23:37:13.4885 TRACE (ObjectLog ) : Loading strings from CPL.Customizer, Version=8.1.0.0, Culture=neutral, PublicKeyToken=5d0660cde5b231fd|CPL.Customizer.Properties.Strings.
2020-10-28 23:37:13.4729 DEBUG (StopWatch SW) : Getting PolicyColumns/global from cache. done in 0ms.
2020-10-28 23:37:13.4885 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicInitialize
2020-10-28 23:37:13.4885 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Person: Initializing Customizer
2020-10-28 23:37:13.4885 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on ComplianceCheckSimple(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodEnabled depends on [IsLoaded], Config(QER\ComplianceCheck\SimpleMode)
2020-10-28 23:37:13.4885 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running MethodEnabled for ComplianceCheckSimple(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) in Person
2020-10-28 23:37:13.4885 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '[IsLoaded]' for entity logic...
2020-10-28 23:37:13.4885 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.4885 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value 'Config(QER\ComplianceCheck\SimpleMode)' for entity logic...
2020-10-28 23:37:13.4885 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Config...
2020-10-28 23:37:13.4885 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: ComplianceCheckSimple(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodEnabled = False
2020-10-28 23:37:13.4885 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicInitialize, New state: None
2020-10-28 23:37:13.5041 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Set default values.
2020-10-28 23:37:13.5041 DEBUG (ObjectLog ) : Loading assembly C:\Users\adminUser\AppData\Local\One Identity\One Identity Manager\AssemblyCache\Tmpl_3aIzoSQxKbNZhI8wGt4AYhYQvI.dll
2020-10-28 23:37:13.5041 DEBUG (ObjectLog ) : ScriptClass: Loading assembly from buffer: Tmpl_3aIzoSQxKbNZhI8wGt4AYhYQvI
2020-10-28 23:37:13.5510 DEBUG (StopWatch SW) : Getting Table/DialogNextID from cache. done in 2ms.
2020-10-28 23:37:13.5510 DEBUG (StopWatch SW) : Getting Schema/DialogNextID from cache. done in 2ms.
2020-10-28 23:37:13.5510 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : DialogNextID: Creating new entity, creation type Interactive
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicInitialize
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : DialogNextID: Initializing Customizer
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on NextNumber CanEdit depends on
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running CanEdit for NextNumber in DialogNextID
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: NextNumber CanEdit = False
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicInitialize, New state: None
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicInitialize
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : DialogNextID: Initializing Customizer
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on SwitchToModuleGuid(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodVisible depends on [IsLoaded], _Enabled, _HasModuleGuid, _ProductionLevel
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running MethodVisible for SwitchToModuleGuid(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) in ModuleGuids
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '[IsLoaded]' for entity logic...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '_Enabled' for entity logic...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Expensive function...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '_HasModuleGuid' for entity logic...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Expensive function...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '_ProductionLevel' for entity logic...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Expensive function...
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: SwitchToModuleGuid(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodVisible = False
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Action on SwitchToNormalGuid(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodVisible depends on [IsLoaded], _Enabled, _HasModuleGuid, _NormalGuidsAllowed
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Running MethodVisible for SwitchToNormalGuid(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) in ModuleGuids
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '[IsLoaded]' for entity logic...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Local value...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '_Enabled' for entity logic...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Got from cache.
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '_HasModuleGuid' for entity logic...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Got from cache.
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value '_NormalGuidsAllowed' for entity logic...
2020-10-28 23:37:13.5666 TRACE (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Getting value from Expensive function...
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Result: SwitchToNormalGuid(VI.DB.Entities.ISession, VI.DB.Entities.IEntity, System.Threading.CancellationToken) MethodVisible = False
2020-10-28 23:37:13.5666 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicInitialize, New state: None
2020-10-28 23:37:13.6135 DEBUG (StopWatch SW) : Getting Notifications/DialogNextID from cache. done in 12ms.
2020-10-28 23:37:13.6291 DEBUG (StopWatch SW) : Getting ColumnDeps/DialogNextID from cache. done in 8ms.
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.7697 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.7854 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Begin transaction in read/write entity
2020-10-28 23:37:13.7854 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Check and execute column format Single Line with 83388cab-2185-4099-8d0a-2b9aa0d4a26d
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : DialogNextID.UID_DialogNextID = 83388cab-2185-4099-8d0a-2b9aa0d4a26d
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Commit transaction in read/write entity
2020-10-28 23:37:13.8010 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Creating new entity done in 248ms.
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Begin transaction in read/write entity
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Check and execute column format Single Line with Person.SecurityIdent
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : DialogNextID.Ident_DialogNextID = Person.SecurityIdent
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: None, New state: LogicSetValues
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Customizer SetValueAsync
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Old state: LogicSetValues, New state: None
2020-10-28 23:37:13.8166 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Commit transaction in read/write entity
2020-10-28 23:37:13.8791 DEBUG (ObjectLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Calling entity method GetNextID
2020-10-28 23:37:13.9104 TRACE (SqlLog ) : -- Connection 1 switched from Available to Working after comparison
2020-10-28 23:37:13.9104 TRACE (SqlLog ) : --> existing connection 1
2020-10-28 23:37:13.9104 DEBUG (SqlLog ffcfb894-bdbb-4c70-b256-f711989125d3) : (< 1 ms) - Pin DbSession to physical connection
2020-10-28 23:37:13.9104 TRACE (SqlLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Incremented pin count to 1
2020-10-28 23:37:13.9104 DEBUG (SqlLog ffcfb894-bdbb-4c70-b256-f711989125d3) : (4 ms) - BEGIN TRANSACTION
2020-10-28 23:37:13.9104 TRACE (SqlLog ffcfb894-bdbb-4c70-b256-f711989125d3) : Increment transaction count to 1
2020-10-28 23:37:13.9260 TRACE (SqlLog ffcfb894-bdbb-4c70-b256-f711989125d3) : ClaimConnectionAsync - read write, _transaction == 7931792
2020-10-28 23:37:13.9260 TRACE (SqlLog ffcfb894-bdbb-4c70-b256-f711989125d3) : ClaimConnectionAsync: Lock aquired on connection 1
2020-10-28 23:37:13.9260 TRACE (SqlLog ffcfb894-bdbb-4c70-b256-f711989125d3) : ClaimConnectionAsync: Use the transaction
2020-10-28 23:37:17.6818 TRACE (SqlLog Database Session GC) : Cleanup physical connection pool
2020-10-28 23:37:17.6818 TRACE (SqlLog Database Session GC) : 0 connections disposed.
2020-10-28 23:37:17.6818 TRACE (SqlLog Database Session GC) : Working = 1
2020-10-28 23:37:27.6830 TRACE (SqlLog Database Session GC) : Cleanup physical connection pool
2020-10-28 23:37:27.6830 TRACE (SqlLog Database Session GC) : 0 connections disposed.
2020-10-28 23:37:27.6830 TRACE (SqlLog Database Session GC) : Working = 1
2020-10-28 23:37:30.0928 TRACE (SqlLog ) : -- Connection 0 switched from Available to Reusable
2020-10-28 23:37:30.0928 TRACE (SqlLog ) : -- Connection 2 switched from Reusable to Initializing after comparison
2020-10-28 23:37:30.1108 TRACE (SqlLog ) : -- Connection 2 switched from Initializing to Working
2020-10-28 23:37:30.1108 TRACE (SqlLog ) : --> new connection 2
2020-10-28 23:37:30.1108 DEBUG (SqlLog 93a7225c-93bf-4661-a4ec-366a037f1ae2) : (7 ms) - select top 1 IsDBSchedulerDisabled, IsJobServiceDisabled,
case
when exists (select top 1 1
from QBM_VDBQueueContent
where UID_Task = 'QBM-K-COMMONWAITFORCOMPILER'
) then 1
else 0
end as DbQueueWaitForCompiler,
len(dbo.QBM_FGIMaintenanceRunning()) as Maintenance
from DialogDatabase with (nolock)
where IsMainDatabase = 1
2020-10-28 23:37:30.1108 TRACE (SqlLog ) : -- Connection 2 switched from Working to Available
2020-10-28 23:37:37.6891 TRACE (SqlLog Database Session GC) : Cleanup physical connection pool
2020-10-28 23:37:37.6891 TRACE (SqlLog Database Session GC) : 0 connections disposed.
2020-10-28 23:37:37.6891 TRACE (SqlLog Database Session GC) : Working = 1
2020-10-28 23:37:37.6891 TRACE (SqlLog Database Session GC) : Available = 1
2020-10-28 23:37:47.6929 TRACE (SqlLog Database Session GC) : Cleanup physical connection pool
2020-10-28 23:37:47.6929 TRACE (SqlLog Database Session GC) : 0 connections disposed.
2020-10-28 23:37:47.6929 TRACE (SqlLog Database Session GC) : Working = 1
2020-10-28 23:37:47.6929 TRACE (SqlLog Database Session GC) : Available = 1
2020-10-28 23:37:57.6923 TRACE (SqlLog Database Session GC) : Cleanup physical connection pool
2020-10-28 23:37:57.6923 TRACE (SqlLog Database Session GC) : 0 connections disposed.
2020-10-28 23:37:57.6923 TRACE (SqlLog Database Session GC) : Working = 1
2020-10-28 23:37:57.6923 TRACE (SqlLog Database Session GC) : Available = 1
2020-10-28 23:38:00.1092 TRACE (SqlLog ) : -- Connection 2 switched from Available to Working after comparison
2020-10-28 23:38:00.1092 TRACE (SqlLog ) : --> existing connection 2
2020-10-28 23:38:00.1092 DEBUG (SqlLog 93a7225c-93bf-4661-a4ec-366a037f1ae2) : (7 ms) - select top 1 IsDBSchedulerDisabled, IsJobServiceDisabled,
case
when exists (select top 1 1
from QBM_VDBQueueContent
where UID_Task = 'QBM-K-COMMONWAITFORCOMPILER'
) then 1
else 0
end as DbQueueWaitForCompiler,
len(dbo.QBM_FGIMaintenanceRunning()) as Maintenance
from DialogDatabase with (nolock)
where IsMainDatabase = 1
2020-10-28 23:38:00.1092 TRACE (SqlLog ) : -- Connection 2 switched from Working to Available
2020-10-28 23:38:07.7019 TRACE (SqlLog Database Session GC) : Cleanup physical connection pool
2020-10-28 23:38:07.7019 TRACE (SqlLog Database Session GC) : 0 connections disposed.
2020-10-28 23:38:07.7019 TRACE (SqlLog Database Session GC) : Working = 1
2020-10-28 23:38:07.7019 TRACE (SqlLog Database Session GC) : Available = 1
2020-10-28 23:38:17.7163 TRACE (SqlLog Database Session GC) : Cleanup physical connection pool
2020-10-28 23:38:17.7163 TRACE (SqlLog Database Session GC) : 0 connections disposed.
2020-10-28 23:38:17.7163 TRACE (SqlLog Database Session GC) : Working = 1
2020-10-28 23:38:17.7163 TRACE (SqlLog Database Session GC) : Available = 1
2020-10-28 23:38:27.7197 TRACE (SqlLog Database Session GC) : Cleanup physical connection pool
2020-10-28 23:38:27.7197 TRACE (SqlLog Database Session GC) : 0 connections disposed.
2020-10-28 23:38:27.7197 TRACE (SqlLog Database Session GC) : Working = 1
2020-10-28 23:38:27.7197 TRACE (SqlLog Database Session GC) : Available = 1
2020-10-28 23:38:30.1144 TRACE (SqlLog ) : -- Connection 2 switched from Available to Working after comparison
2020-10-28 23:38:30.1144 TRACE (SqlLog ) : --> existing connection 2
2020-10-28 23:38:30.1144 DEBUG (SqlLog 93a7225c-93bf-4661-a4ec-366a037f1ae2) : (7 ms) - select top 1 IsDBSchedulerDisabled, IsJobServiceDisabled,
case
when exists (select top 1 1
from QBM_VDBQueueContent
where UID_Task = 'QBM-K-COMMONWAITFORCOMPILER'
) then 1
else 0
end as DbQueueWaitForCompiler,
len(dbo.QBM_FGIMaintenanceRunning()) as Maintenance
from DialogDatabase with (nolock)
where IsMainDatabase = 1

  • Hello,

    I have no clue why but I restarted all the web servers and all is now well. I checked all web server sessions from the monitor page and did not notice anything weird.

    My only guess is something was locked up in the process of someone creating an employee from the web portal.

    This really freaked me out as there were no recent changes and I had 30+ employees created in the system today. I got an alert on my data imports not completing due to new employees trying to get entered.

    In any case I'm relieved it was nothing serious, although i wish i had an explanation to why this occurred.

    Thank you,

    Lu