SQL SERVER
Tuve el siguiente error cuando el último usuario autoriza el movimiento y lo que logró entender es que fallo el acceso o la conexión con la base de datos y fué un problema de infraestructura, luego reenvíe desde la pantalla de procesos y pasó; estamos progamando la actualización con el cliente no se si eso pueda mejorar este error. Agradezco su retroalimentación al respecto Gracias:
2018-03-01 16:33:36,545 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Inicia la ejecución de la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:33:36,545 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Reconfigurando el contenedor de Unity para la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:33:36,545 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Inicializando el MetadataService para ejecutar la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:33:36,638 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Obteniendo el servicio [Aseinfo.VH4.Acciones.Base.Movimientos.IMovimientoService] para ejecutar la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:33:36,654 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Iniciando la transacción para la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:33:36,638 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Obteniendo el servicio [Aseinfo.VH4.Acciones.Base.Movimientos.IMovimientoService] para ejecutar la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:33:36,654 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Iniciando la transacción para la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:33:36,654 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Finalización 4c82d81a-c69b-434a-94d7-c1acc702ddbb -- Ejecutanto procedimiento ANTES: acc.antes_finalizar_movimiento ...
2018-03-01 16:33:36,685 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Finalización 4c82d81a-c69b-434a-94d7-c1acc702ddbb -- Ejecutando VALIDACION en el Servicio ...
2018-03-01 16:33:36,701 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Finalización 4c82d81a-c69b-434a-94d7-c1acc702ddbb -- Ejecutando FINALIZACION en el Servicio ...
2018-03-01 16:33:36,779 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Construyendo objeto bitácora de ejecución de proceso 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:33:38,982 [AppJobWinServiceScheduler_Worker-2] ERROR Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Error al ejecutar la finalización 4c82d81a-c69b-434a-94d7-c1acc702ddbb
System.Data.Entity.Core.UpdateException: An error occurred while updating the entries. See the inner exception for details. ---> System.Data.SqlClient.SqlException: Transaction (Process ID 89) was deadlocked on lock resources with another process and has been chosen as the deadlock victim. Rerun the transaction.
at System.Data.SqlClient.SqlConnection.OnError(SqlException exception, Boolean breakConnection, Action`1 wrapCloseInAction)
at System.Data.SqlClient.TdsParser.ThrowExceptionAndWarning(TdsParserStateObject stateObj, Boolean callerHasConnectionLock, Boolean asyncClose)
at System.Data.SqlClient.TdsParser.TryRun(RunBehavior runBehavior, SqlCommand cmdHandler, SqlDataReader dataStream, BulkCopySimpleResultSet bulkCopyHandler, TdsParserStateObject stateObj, Boolean& dataReady)
at System.Data.SqlClient.SqlCommand.FinishExecuteReader(SqlDataReader ds, RunBehavior runBehavior, String resetOptionsString, Boolean isInternal, Boolean forDescribeParameterEncryption)
at System.Data.SqlClient.SqlCommand.RunExecuteReaderTds(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, Boolean async, Int32 timeout, Task& task, Boolean asyncWrite, Boolean inRetry, SqlDataReader ds, Boolean describeParameterEncryptionRequest)
at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method, TaskCompletionSource`1 completion, Int32 timeout, Task& task, Boolean& usedCache, Boolean asyncWrite, Boolean inRetry)
at System.Data.SqlClient.SqlCommand.InternalExecuteNonQuery(TaskCompletionSource`1 completion, String methodName, Boolean sendToPipe, Int32 timeout, Boolean& usedCache, Boolean asyncWrite, Boolean inRetry)
at System.Data.SqlClient.SqlCommand.ExecuteNonQuery()
at System.Data.Entity.Infrastructure.Interception.InternalDispatcher`1.Dispatch[TTarget,TInterceptionContext,TResult](TTarget target, Func`3 operation, TInterceptionContext interceptionContext, Action`3 executing, Action`3 executed)
at System.Data.Entity.Infrastructure.Interception.DbCommandDispatcher.NonQuery(DbCommand command, DbCommandInterceptionContext interceptionContext)
at System.Data.Entity.Core.Mapping.Update.Internal.DynamicUpdateCommand.Execute(Dictionary`2 identifierValues, List`1 generatedValues)
at System.Data.Entity.Core.Mapping.Update.Internal.UpdateTranslator.Update()
--- End of inner exception stack trace ---
at System.Data.Entity.Core.Mapping.Update.Internal.UpdateTranslator.Update()
at System.Data.Entity.Core.Objects.ObjectContext.ExecuteInTransaction[T](Func`1 func, IDbExecutionStrategy executionStrategy, Boolean startLocalTransaction, Boolean releaseConnectionOnSuccess)
at System.Data.Entity.Core.Objects.ObjectContext.SaveChangesToStore(SaveOptions options, IDbExecutionStrategy executionStrategy, Boolean startLocalTransaction)
at System.Data.Entity.SqlServer.DefaultSqlExecutionStrategy.Execute[TResult](Func`1 operation)
at System.Data.Entity.Core.Objects.ObjectContext.SaveChangesInternal(SaveOptions options, Boolean executeInExistingTransaction)
at Aseinfo.VH4.Data.AppDataContext.SaveChanges(SaveOptions options) in C:\vh4\VH4\Data\AppDataContext.cs:line 98
at Aseinfo.Infrastructure.Base.Entity.EntityServiceBase`2.SaveChanges() in C:\vh4\Infrastructure\Base\Entity\EntityServiceBase.cs:line 563
at Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob.Execute(JobExecutionContext context) in C:\vh4\VH4\WindowsServices\ApplicationJobsWindowsService\ApplicationJobs\AccionFinalizacionJob.cs:line 365
2018-03-01 16:33:39,014 [AppJobWinServiceScheduler_Worker-2] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Iniciando la programación del reintento dentro de un minuto proceso [Aseinfo.VH4.Acciones.Base.Movimientos.IMovimientoService] para ejecutar la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:34:39,014 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Inicia la ejecución de la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:34:39,014 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Reconfigurando el contenedor de Unity para la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:34:39,014 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Inicializando el MetadataService para ejecutar la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:34:39,171 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Obteniendo el servicio [Aseinfo.VH4.Acciones.Base.Movimientos.IMovimientoService] para ejecutar la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:34:39,202 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Iniciando la transacción para la finalización: 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:34:39,202 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Finalización 4c82d81a-c69b-434a-94d7-c1acc702ddbb -- Ejecutanto procedimiento ANTES: acc.antes_finalizar_movimiento ...
2018-03-01 16:34:39,249 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Finalización 4c82d81a-c69b-434a-94d7-c1acc702ddbb -- Ejecutando VALIDACION en el Servicio ...
2018-03-01 16:34:39,280 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Finalización 4c82d81a-c69b-434a-94d7-c1acc702ddbb -- Ejecutando FINALIZACION en el Servicio ...
2018-03-01 16:34:39,358 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Construyendo objeto bitácora de ejecución de proceso 4c82d81a-c69b-434a-94d7-c1acc702ddbb
2018-03-01 16:34:50,186 [AppJobWinServiceScheduler_Worker-4] ERROR Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Error al ejecutar la finalización 4c82d81a-c69b-434a-94d7-c1acc702ddbb
System.ObjectDisposedException: Cannot access a disposed object.
Object name: 'SqlDelegatedTransaction'.
at System.Data.SqlClient.SqlDelegatedTransaction.GetValidConnection()
at System.Data.SqlClient.SqlDelegatedTransaction.Rollback(SinglePhaseEnlistment enlistment)
at System.Transactions.TransactionStateDelegatedAborting.EnterState(InternalTransaction tx)
at System.Transactions.Transaction.Rollback()
at System.Transactions.TransactionScope.InternalDispose()
at System.Transactions.TransactionScope.Dispose()
at Aseinfo.VH4.Data.AppDataContext.SaveChanges(SaveOptions options) in C:\vh4\VH4\Data\AppDataContext.cs:line 112
at Aseinfo.Infrastructure.Base.Entity.EntityServiceBase`2.SaveChanges() in C:\vh4\Infrastructure\Base\Entity\EntityServiceBase.cs:line 563
at Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob.Execute(JobExecutionContext context) in C:\vh4\VH4\WindowsServices\ApplicationJobsWindowsService\ApplicationJobs\AccionFinalizacionJob.cs:line 365
2018-03-01 16:34:50,436 [AppJobWinServiceScheduler_Worker-4] INFO Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob - Generando empleados a notificar por el resultado de la bitácora de ejecución del proceso 4c82d81a-c69b-434a-94d7-c1acc702ddbb
System.ObjectDisposedException: Cannot access a disposed object. Object name: 'SqlDelegatedTransaction'. at System.Data.SqlClient.SqlDelegatedTransaction.GetValidConnection()
at System.Data.SqlClient.SqlDelegatedTransaction.Rollback(SinglePhaseEnlistment enlistment) at
System.Transactions.TransactionStateDelegatedAborting.EnterState(InternalTransaction tx) at System.Transactions.Transaction.Rollback()
at System.Transactions.TransactionScope.InternalDispose() at System.Transactions.TransactionScope.Dispose()
at Aseinfo.VH4.Data.AppDataContext.SaveChanges(SaveOptions options) in C:\vh4\VH4\Data\AppDataContext.cs:line 112
at Aseinfo.Infrastructure.Base.Entity.EntityServiceBase`2.SaveChanges()
in C:\vh4\Infrastructure\Base\Entity\EntityServiceBase.cs:line 563 at Aseinfo.VH4.WindowsServices.ApplicationJobs.AccionFinalizacionJob.Execute(JobExecutionContext context)
in C:\vh4\VH4\WindowsServices\ApplicationJobsWindowsService\ApplicationJobs\AccionFinalizacionJob.cs:line 365
Podrías agregar el resto del LOG, para el mismo Thread que estaba ejecutando la finalización, para poner en contexto el momento en que falló.
ya actualice el log, al parecer intento dos veces finalizar. La primera fue un deadlock y la segunda fue la reportada originalmente.
No se ha hecho ningún cambio sobre la parte del código donde sucede el error, en ningun hotfix o version posterior a la 1.11.0.3, así que no veo que actualizar de versión vaya a eliminar el problema.
Creo que hay que entender bien como está operando esa finalización, para poder diagnosticar que sucede.