AL icon indicating copy to clipboard operation
AL copied to clipboard
verified publisher icon microsoft

While debugging, frequently get "the remote party closed the websocket connection without completing the close handshake" while opening a base object.

Open mjmatthiesen opened this issue 3 years ago • 7 comments

1. Describe the bug Hit an error triggered in base. The debugger then tries to download the base object DAL file to show error source. The file attempts to load for 15ish seconds and then stops with the following error: image Sometimes if I try often enough, eventually it will load and the debug can continue. But this is rare, and the larger the file is, the more impossible it becomes. This causes the debugger to stop the current activity.

This is using an azure vm and the docker bc instance etc.

I can explore the sales line.dal from the downloaded symbols no problem.

2. To Reproduce Steps to reproduce the behaviour:

  1. Create a sales order that contains an item, qty 2. Ensure that item has no qty rounding precision set, i.e. it's zero.
  2. Run the following code
salesLine.Validate("Qty. to Ship", 1.99998);
  1. Step into the code to prompt the download of 37.dal
  2. Download times out?
  3. Hitting try again results in: Unable to open 37.dal - The remote party closed the WebSocket connection without completing the close handshake.

3. Expected behaviour Download the file and examine the code.

4. Actual behaviour vscode-debug-fail

5. Versions:

  • AL Language: v9.0.615906
  • Visual Studio Code: Version: 1.68.0
  • Business Central: Version: W1 20.1 (Platform 20.0.39668.41183 + Application 20.1.39764.41236)
  • List of Visual Studio Code extensions that you have installed:

Final Checklist

Please remember to do the following:

  • [X] Search the issue repository to ensure you are reporting a new issue

  • [X] Reproduce the issue after disabling all extensions except the AL Language extension

  • [X] Simplify your code around the issue to better isolate the problem

mjmatthiesen avatar Jun 10 '22 17:06 mjmatthiesen

Looks to me a networking (very slow connection) issue. Do you have any more details in teh docker event viewer of the server on the Azure VM.

kalberes avatar Jun 13 '22 17:06 kalberes

I doubt this is connected to network speed, as this previously worked, and I have gigabit internet at home and the office has something faster. And it happens in both locations. And over any connection I've tried.

Is there anything specific from the event viewer you're looking for? I have the following

The description for Event ID 705 from source MicrosoftDynamicsNavServer$BC cannot be found. Either the component that raises this event is not installed on your local computer or the installation is corrupted. You can install or repair the component on the local computer.

If the event originated on another computer, the display information had to be saved with the event.

The following information was included with the event: 

Server instance: BC
Category: Runtime
ClientSessionId: 895eab25-4f9b-4582-b410-8cd205d67ac1
ClientActivityId: 5d3d0b4c-ad35-0993-1465-bf93d5e5528e
ServerSessionUniqueId: e5d2ac32-b18a-4bbc-95fa-34ac994f86dc
ServerActivityId: 139efb30-ffd5-41da-a4f7-68c994c93ddc
EventTime: 06/14/2022 23:07:04
Message (NavNSValidationFailedException): Sending exception to client. Session terminating: False
ParentException: NavNSValidationFailedException
The debugger stopped the current activity.
ExceptionStackTrace:
   at Microsoft.Dynamics.Nav.Service.NSField.Validate(NavSession session)
   at SyncInvokeValidateField(Object , Object[] , Object[] )
   at System.ServiceModel.Dispatcher.SyncMethodInvoker.Invoke(Object instance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.RunInTransactionCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.TransientErrorRetryCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.ErrorMappingCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
InnerException:
ParentException: NavNCLDebuggerActivityAbortedException
The debugger stopped the current activity.
ExceptionStackTrace:
   at Microsoft.Dynamics.Nav.Runtime.NavApplicationMethod.InvokeMethod(ITreeObject obj, String methodName, Object[] args, Boolean resolveHandler, Boolean throwOnNotFound)
   at Microsoft.Dynamics.Nav.Runtime.NavForm.RaiseOnValidate(Nullable`1 controlId, String validateTriggerMethodName)
   at Microsoft.Dynamics.Nav.Service.NSField.<>c__DisplayClass15_0.<ValidateField>b__0(NsDataAccess recDataAccess)
   at Microsoft.Dynamics.Nav.Service.NsFormDataAccess.RunWithFormDataAccess[T](ITreeObject parent, NavForm form, Func`2 func)
   at Microsoft.Dynamics.Nav.Service.NsDataAccess.RunWithDataAccess[T](ITreeObject parent, NavRecordState state, Boolean instantiateNewForm, Guid parentFormHandle, String parentControlName, Boolean& instantiatedForm, Func`2 func)
   at Microsoft.Dynamics.Nav.Service.NsDataAccess.RunWithDataAccess[T](ITreeObject parent, NavRecordState state, Func`2 func)
   at Microsoft.Dynamics.Nav.Service.NSField.Validate(NavSession session)
InnerException:
ParentException: TargetInvocationException
ExceptionStackTrace:
   at System.RuntimeMethodHandle.InvokeMethod(Object target, Object[] arguments, Signature sig, Boolean constructor)
   at System.Reflection.RuntimeMethodInfo.UnsafeInvokeInternal(Object obj, Object[] parameters, Object[] arguments)
   at System.Reflection.RuntimeMethodInfo.Invoke(Object obj, BindingFlags invokeAttr, Binder binder, Object[] parameters, CultureInfo culture)
   at Microsoft.Dynamics.Nav.Runtime.NavApplicationMethod.InvokeMethod(ITreeObject obj, String methodName, Object[] args, Boolean resolveHandler, Boolean throwOnNotFound)
InnerException:
RootException: NavNCLDebuggerActivityAbortedException
The debugger stopped the current activity.
ExceptionStackTrace:
   at Microsoft.Dynamics.Nav.Runtime.Debugger.DebugRuntime.ProcessBreak(NavMethodScope methodScope, Exception exception, Boolean setIgnoreSkip)
   at Microsoft.Dynamics.Nav.Runtime.Debugger.DebugRuntime.ProcessStatementHitOnRightConnection(NavMethodScope methodScope)
   at Microsoft.Dynamics.Nav.Runtime.ExecutionListener.CompoundExecutionListener.ProcessStatementHit(NavMethodScope methodScope)
   at Microsoft.Dynamics.Nav.BusinessApplication.Record37.Qtya46_to_Ship_a45_OnValidate_Scope.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Record37.Qtya46_to_Ship_a45_OnValidate()
   at Microsoft.Dynamics.Nav.Runtime.NavRecord.ValidateField(FieldTriggerHandler`1 fieldEvent, NCLMetaField metaField, Boolean isUserInput, List`1 beforeHandlers, List`1 afterHandlers)
   at Microsoft.Dynamics.Nav.Runtime.NavRecord.Validate(NCLMetaField metaField, NavValue newValue, NavRecord callerRecord, Boolean isUserInput)
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.UpdateSalesLineQty_Scope__1627262810.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.UpdateSalesLineQty(INavRecordHandle lineBuffer, Decimal18 qtyToPack)
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.Procedure_Scope_526288335.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.Procedure(INavRecordHandle lineBuffer, Decimal18 qtyToPack)
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.Procedure_Scope_1389373220.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.Procedure_1389373220(INavRecordHandle lineBuffer, Boolean isWeightCalculated, Boolean isLineTransfer)
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.Procedure_Scope_2130080744.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.Procedure(INavRecordHandle lineBuffer, Boolean isWeightCalculated, Boolean isLineTransfer)
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.Procedure(INavRecordHandle ptrecLineBuffer)
   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit55501.OnInvoke(Int32 memberId, Object[] args)
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Procedureg_Scope__520101421.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Procedureg()
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Procedure_Scope_383746852.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Procedure(NavText ptScan)
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Procedure_Scope__576345626.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Procedure(NavText ptScan)
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Procedure_Scope__1371086254.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Procedure(NavText ptScan)
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Field_a45_OnValidate_Scope.OnRun()
   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()
   at Microsoft.Dynamics.Nav.BusinessApplication.Page55502.Field_a45_OnValidate()
CallerStackTrace:
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.ErrorMappingCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.PartnerTelemetryErrorCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase m, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.PartnerTelemetryPermissionErrorCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.PushPopCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationTracer.TraceScopeCombinator(Category telemetryCategory, ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass14_0.<PerformanceCounterCombinator>b__0()
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.PerformanceCounterCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.InitClientTelemetryIdsCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.TlsClearCombinator(ServiceOperation innerOperation, NSServiceBase serviceInstance, MethodBase syncMethod, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.<>c__DisplayClass32_1.<Combine>b__1(NSServiceBase serviceInstance, Object[] inputs, Object[]& outputs)
   at Microsoft.Dynamics.Nav.Service.ServiceOperationInvoker.Invoke(Object instance, Object[] inputs, Object[]& outputs)
   at System.ServiceModel.Dispatcher.DispatchOperationRuntime.InvokeBegin(MessageRpc& rpc)
   at System.ServiceModel.Dispatcher.ImmutableDispatchRuntime.ProcessMessage5(MessageRpc& rpc)
   at System.ServiceModel.Dispatcher.ImmutableDispatchRuntime.ProcessMessage11(MessageRpc& rpc)
   at System.ServiceModel.Dispatcher.MessageRpc.Process(Boolean isOperationContextSet)
   at System.ServiceModel.Dispatcher.ChannelHandler.DispatchAndReleasePump(RequestContext request, Boolean cleanThread, OperationContext currentOperationContext)
   at System.ServiceModel.Dispatcher.ChannelHandler.HandleRequest(RequestContext request, OperationContext currentOperationContext)
   at System.ServiceModel.Dispatcher.ChannelHandler.AsyncMessagePump(IAsyncResult result)
   at System.ServiceModel.Dispatcher.ChannelHandler.OnAsyncReceiveComplete(IAsyncResult result)
   at System.Runtime.Fx.AsyncThunk.UnhandledExceptionFrame(IAsyncResult result)
   at Microsoft.Dynamics.Nav.Types.Channels.ReceiveCorrelator.AsyncResult.Complete(Action setValue)
   at Microsoft.Dynamics.Nav.Types.Channels.ReceiveCorrelator.OnNewMessage(IAsyncResult ar)
   at System.Runtime.AsyncResult.Complete(Boolean completedSynchronously)
   at System.ServiceModel.Security.SecuritySessionServerSettings.ServerSecuritySessionChannel.ReceiveRequestAsyncResult.Complete(Boolean synchronous, Exception exception)
   at System.ServiceModel.Security.SecuritySessionServerSettings.ServerSecuritySessionChannel.ReceiveRequestAsyncResult.OnReceive(IAsyncResult result)
   at System.Runtime.Fx.AsyncThunk.UnhandledExceptionFrame(IAsyncResult result)
   at System.Runtime.AsyncResult.Complete(Boolean completedSynchronously)
   at System.ServiceModel.Channels.ReliableChannelBinder`1.InputAsyncResult`1.OnInputComplete(IAsyncResult result)
   at System.ServiceModel.Channels.ReliableChannelBinder`1.InputAsyncResult`1.OnInputCompleteStatic(IAsyncResult result)
   at System.Runtime.Fx.AsyncThunk.UnhandledExceptionFrame(IAsyncResult result)
   at System.Runtime.AsyncResult.Complete(Boolean completedSynchronously)
   at System.ServiceModel.Channels.TransportDuplexSessionChannel.TryReceiveAsyncResult.OnReceive(IAsyncResult result)
   at System.Runtime.Fx.AsyncThunk.UnhandledExceptionFrame(IAsyncResult result)
   at System.Runtime.AsyncResult.Complete(Boolean completedSynchronously)
   at System.ServiceModel.Channels.SynchronizedMessageSource.ReceiveAsyncResult.OnReceiveComplete(Object state)
   at System.ServiceModel.Channels.SessionConnectionReader.OnAsyncReadComplete(Object state)
   at System.Runtime.Fx.AsyncThunk.UnhandledExceptionFrame(IAsyncResult result)
   at System.Net.LazyAsyncResult.Complete(IntPtr userToken)
   at System.Net.LazyAsyncResult.ProtectedInvokeCallback(Object result, IntPtr userToken)
   at System.Net.Security._SslStream.ProcessFrameBody(Int32 readBytes, Byte[] buffer, Int32 offset, Int32 count, AsyncProtocolRequest asyncRequest)
   at System.Net.Security._SslStream.ReadFrameCallback(AsyncProtocolRequest asyncRequest)
   at System.Net.AsyncProtocolRequest.CompleteRequest(Int32 result)
   at System.Net.FixedSizeReader.CheckCompletionBeforeNextRead(Int32 bytes)
   at System.Net.FixedSizeReader.ReadCallback(IAsyncResult transportResult)
   at System.Runtime.AsyncResult.Complete(Boolean completedSynchronously)
   at System.ServiceModel.Channels.ConnectionStream.IOAsyncResult.OnAsyncIOComplete(Object state)
   at System.Net.Sockets.SocketAsyncEventArgs.OnCompleted(SocketAsyncEventArgs e)
   at System.Net.Sockets.SocketAsyncEventArgs.FinishOperationSuccess(SocketError socketError, Int32 bytesTransferred, SocketFlags flags)
   at System.Net.Sockets.SocketAsyncEventArgs.CompletionPortCallback(UInt32 errorCode, UInt32 numBytes, NativeOverlapped* nativeOverlapped)
   at System.Threading._IOCompletionCallback.PerformIOCompletionCallback(UInt32 errorCode, UInt32 numBytes, NativeOverlapped* pOVERLAP)


ProcessId: 7396
Tag: 00000HC
ThreadId: 85
CounterInformation: 
CustomParameters: {
}
GatewayCorrelationId: 

The description for Event ID 705 from source MicrosoftDynamicsNavServer$BC cannot be found. Either the component that raises this event is not installed on your local computer or the installation is corrupted. You can install or repair the component on the local computer.

If the event originated on another computer, the display information had to be saved with the event.

The following information was included with the event: 

Server instance: BC
Category: Runtime
ClientSessionId: 00000000-0000-0000-0000-000000000000
ClientActivityId: e095febd-5406-449d-a6d9-1c000df158a7
ServerSessionUniqueId: d838dc2e-aef7-4cc0-8a23-5195d56fea79
ServerActivityId: 76cfb86c-7d0b-4e10-a9e6-66526dc50587
EventTime: 06/14/2022 23:06:15
Message Lock timeout is reset to undefined value.
ProcessId: 7396
Tag: 0000DQE
ThreadId: 144
CounterInformation: 
CustomParameters: {"Lock timeout value": "-1","Call stack": "   at Microsoft.Dynamics.Nav.Runtime.NavSqlConnection.SetLockTimeout(Boolean enableLockTimeout)\r\n   at Microsoft.Dynamics.Nav.Runtime.SqlTableDataProvider.BeginTransaction(TransactionType transactionType)\r\n   at Microsoft.Dynamics.Nav.Runtime.TransactionManager.EnsureWriteTransactionStarted()\r\n   at Microsoft.Dynamics.Nav.Runtime.SessionTransactionManager.ForEachTransactionManager(Action`1 actionToPerform, Action`1 actionToPerformOnFailure)\r\n   at Microsoft.Dynamics.Nav.Runtime.SystemTableTriggers.EnsureIsSystemTableAndInWriteTransaction(NCLMetaTable table, Int32& tableId)\r\n   at Microsoft.Dynamics.Nav.Runtime.SystemTableTriggers.OnBeforeDelete(MutableRecordBuffer recordBuffer)\r\n   at Microsoft.Dynamics.Nav.Runtime.TransactionalDataCache.OnBeforeDelete(Int32 companyToken, MutableRecordBuffer recordBuffer, Boolean& recordWasReadWithLockInsideThisTransaction, Action& onAfterDelete)\r\n   at Microsoft.Dynamics.Nav.Runtime.DataAccess.Delete(Int32 companyToken, MutableRecordBuffer recordBuffer, ResultSetEnumerator& resultSetEnumerator)\r\n   at Microsoft.Dynamics.Nav.Runtime.RecordImplementation.DeleteRecord(DataError errorLevel)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavRecord.Delete(DataError errorLevel, Boolean runApplicationTrigger, Boolean isCalledFromUI, Boolean isBulkDelete)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavRecord.ALDelete(DataError errorLevel, Boolean runApplicationTrigger, Boolean isBulkDelete)\r\n   at Microsoft.Dynamics.Nav.Runtime.TaskRunnerCodeUnit.<>c__DisplayClass27_0.<RemoveTaskScheduleFromTenant>b__0()\r\n   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.RunActionWithOverrideCancellationToken(NavCancellationToken newCancellationToken, Action action)\r\n   at Microsoft.Dynamics.Nav.Runtime.TaskRunnerCodeUnit.RemoveTaskScheduleFromTenant(NavSession session)\r\n   at Microsoft.Dynamics.Nav.Runtime.TaskRunnerCodeUnit.TaskSpecificInternalRun()\r\n   at Microsoft.Dynamics.Nav.Runtime.TaskRunner.InternalRun(Action continuation, NavDiagnosticStateFactory navDiagnosticStateFactory)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavTaskFactory.<>c__DisplayClass1_0.<RunTask>b__0()\r\n   at System.Threading.Tasks.Task.Execute()\r\n   at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)\r\n   at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)\r\n   at System.Threading.Tasks.Task.ExecuteWithThreadLocal(Task& currentTaskSlot)\r\n   at System.Threading.Tasks.Task.ExecuteEntry(Boolean bPreventDoubleExecution)\r\n   at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)\r\n   at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)\r\n   at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)\r\n   at System.Threading.ThreadHelper.ThreadStart(Object obj)\r\n"}
GatewayCorrelationId: 

The description for Event ID 705 from source MicrosoftDynamicsNavServer$BC cannot be found. Either the component that raises this event is not installed on your local computer or the installation is corrupted. You can install or repair the component on the local computer.

If the event originated on another computer, the display information had to be saved with the event.

The following information was included with the event: 

Server instance: BC
Category: Runtime
ClientSessionId: 00000000-0000-0000-0000-000000000000
ClientActivityId: e095febd-5406-449d-a6d9-1c000df158a7
ServerSessionUniqueId: d838dc2e-aef7-4cc0-8a23-5195d56fea79
ServerActivityId: 76cfb86c-7d0b-4e10-a9e6-66526dc50587
EventTime: 06/14/2022 23:06:15
Message Lock timeout is reset to undefined value.
ProcessId: 7396
Tag: 0000DQE
ThreadId: 144
CounterInformation: 
CustomParameters: {"Lock timeout value": "-1","Call stack": "   at Microsoft.Dynamics.Nav.Runtime.NavSqlConnection.SetLockTimeout(Boolean enableLockTimeout)\r\n   at Microsoft.Dynamics.Nav.Runtime.ALDatabase.set_ALLockTimeout(Boolean value)\r\n   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit80.OnRun_Scope.OnRun()\r\n   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()\r\n   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit80.OnRun(INavRecordHandle εrec)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavCodeunit.DoRun(DataError errorLevel, NavRecord record)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavCodeunit.RunCodeunit(DataError errorLevel, Int32 objectId, NavRecord record)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()\r\n   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit130411.PostSalesInvoice(INavRecordHandle salesHeader)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()\r\n   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit130411.WarmupInvoicePosting()\r\n   at System.RuntimeMethodHandle.InvokeMethod(Object target, Object[] arguments, Signature sig, Boolean constructor)\r\n   at System.Reflection.RuntimeMethodInfo.UnsafeInvokeInternal(Object obj, Object[] parameters, Object[] arguments)\r\n   at System.Reflection.RuntimeMethodInfo.Invoke(Object obj, BindingFlags invokeAttr, Binder binder, Object[] parameters, CultureInfo culture)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavTestCodeunit.ExecuteTestMethod(MethodInfo method, TestTransactionModel transactionModel)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavTestCodeunit.DoRun(DataError errorLevel, NavRecord record)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavCodeunit.RunCodeunit(DataError errorLevel, Int32 objectId)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavMethodScope.Run()\r\n   at Microsoft.Dynamics.Nav.BusinessApplication.Codeunit130410.OnRun(INavRecordHandle εrec)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavCodeunit.DoRun(DataError errorLevel, NavRecord record)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavTestRunnerCodeUnit.DoRun(DataError errorLevel, NavRecord record)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavCodeunit.RunCodeunit(DataError errorLevel, Int32 objectId)\r\n   at Microsoft.Dynamics.Nav.Runtime.TaskRunnerCodeUnit.RunCodeunit(Int32 codeunitId, NavRecordRef recordRef)\r\n   at Microsoft.Dynamics.Nav.Runtime.TaskRunnerCodeUnit.TaskSpecificInternalRun()\r\n   at Microsoft.Dynamics.Nav.Runtime.TaskRunner.InternalRun(Action continuation, NavDiagnosticStateFactory navDiagnosticStateFactory)\r\n   at Microsoft.Dynamics.Nav.Runtime.NavTaskFactory.<>c__DisplayClass1_0.<RunTask>b__0()\r\n   at System.Threading.Tasks.Task.Execute()\r\n   at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)\r\n   at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)\r\n   at System.Threading.Tasks.Task.ExecuteWithThreadLocal(Task& currentTaskSlot)\r\n   at System.Threading.Tasks.Task.ExecuteEntry(Boolean bPreventDoubleExecution)\r\n   at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)\r\n   at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)\r\n   at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)\r\n   at System.Threading.ThreadHelper.ThreadStart(Object obj)\r\n"}
GatewayCorrelationId:

mjmatthiesen avatar Jun 14 '22 23:06 mjmatthiesen

While you are debugging the source code is retrieved from teh server, if not from symbols if you are allowed to do it. In your case the debugging session is killed. It is not clear why. Definitely not because your validation fails on the "Qty. to Ship" field. You could enable verbose logging in VsCode "al.editorServicesLogLevel": "Verbose" and somehow send the DebuggerServices.log found in C:\Users<username>.vscode\extensions\ms-dynamics-smb.al-\bin

Don't forget to reset the settings, since the log will grow very fast very large.

kalberes avatar Jun 16 '22 19:06 kalberes

Here's the log, censoring the objects and vm name.

06/16/2022 14:06:30 [/1] Editor Services Host v9.0.9.26100 starting (pid 14484)...
06/16/2022 14:06:30 [/1] Editor Services Host started!
06/16/2022 14:06:30 [/7] Process:

 initialize
06/16/2022 14:06:30 [/7] Processing initialize took 0.000366 seconds
06/16/2022 14:06:30 [/8] Process:

 launch
06/16/2022 14:06:30 [/8] Sending request to https://vm-name.region.cloudapp.azure.com:7049/BC/dev/apps?tenant=default&SchemaUpdateMode=forcesync&DependencyPublishingOption=default with request ID 05e04e66-6051-48b3-937d-36f483c327c6 and session ID 1a53da65-08f6-45a6-b33d-ed6abd85aa90
06/16/2022 14:06:42 [/21] Establishing SignalR connection using AspNetCore SignalR library
06/16/2022 14:06:44 [/16] Processing launch took 14.331087 seconds
06/16/2022 14:06:47 [/8] Process:

 setBreakpoints
06/16/2022 14:06:48 [/8] Parsing Codeunit OBJECT.
06/16/2022 14:06:48 [/13] Processing setbreakpoints took 0.516878 seconds
06/16/2022 14:06:48 [/8] Process:

 configurationDone
06/16/2022 14:06:48 [/7] Processing configurationdone took 0.0826275 seconds
06/16/2022 14:06:48 [/8] Process:

 threads
06/16/2022 14:06:48 [/8] Processing threads took 0.0003533 seconds
06/16/2022 14:06:53 [/8] Process:

 threads
06/16/2022 14:06:53 [/8] Processing threads took 2.82E-05 seconds
06/16/2022 14:06:53 [/8] Process:

 stackTrace
06/16/2022 14:06:53 [/8] Parsing OBJECTS
06/16/2022 14:06:54 [/8] Processing stacktrace took 0.4812569 seconds
06/16/2022 14:06:54 [/8] Process:

 stackTrace
06/16/2022 14:06:54 [/8] Processing stacktrace took 0.0001396 seconds
06/16/2022 14:06:54 [/8] Process:

 stackTrace
06/16/2022 14:06:54 [/8] Processing stacktrace took 5.7E-05 seconds
06/16/2022 14:06:54 [/16] Process:

 scopes
06/16/2022 14:06:54 [/16] Processing scopes took 0.0012518 seconds
06/16/2022 14:06:55 [/13] Process:

 stepIn
06/16/2022 14:06:55 [/16] Processing stepin took 0.0787187 seconds
06/16/2022 14:06:55 [/13] Process:

 threads
06/16/2022 14:06:55 [/13] Processing threads took 6.2E-05 seconds
06/16/2022 14:06:55 [/8] Process:

 stackTrace
06/16/2022 14:06:55 [/8] Processing stacktrace took 5.38E-05 seconds
06/16/2022 14:06:55 [/8] Process:

 stackTrace
06/16/2022 14:06:55 [/8] Processing stacktrace took 0.0007991 seconds
06/16/2022 14:06:55 [/8] Process:

 threads
06/16/2022 14:06:55 [/8] Processing threads took 1.43E-05 seconds
06/16/2022 14:06:55 [/8] Process:

 stackTrace
06/16/2022 14:06:55 [/8] Processing stacktrace took 0.0001987 seconds
06/16/2022 14:06:55 [/8] Process:

 al/previewDocument
06/16/2022 14:06:56 [/8] Process:

 stackTrace
06/16/2022 14:06:56 [/8] Processing stacktrace took 3.82E-05 seconds
06/16/2022 14:07:06 [/7] Processing al/previewDocument took 10.7743794 seconds
06/16/2022 14:07:06 [/7] Please report this issue to https://github.com/microsoft/al/issues including information on how to reproduce it, if possible.For more information change the log level in VSCode: '"al.editorServicesLogLevel": "Verbose"' or for ALC: '/loglevel:Verbose'
You can find the log file in 'DebuggerServices.log'

Processing of message 'al/previewDocument' failed with error: 'The remote party closed the WebSocket connection without completing the close handshake.'
Details:
System.Net.WebSockets.WebSocketException (0x80004005): The remote party closed the WebSocket connection without completing the close handshake.
 ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host..
 ---> System.Net.Sockets.SocketException (10054): An existing connection was forcibly closed by the remote host.
   --- End of inner exception stack trace ---
   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.GetResult(Int16 token)
   at System.Net.Security.SslStream.ReadAsyncInternal[TIOAdapter](TIOAdapter adapter, Memory`1 buffer)
   at System.Net.Http.HttpConnection.ReadBufferedAsyncCore(Memory`1 destination)
   at System.Net.Http.HttpConnection.RawConnectionStream.ReadAsync(Memory`1 buffer, CancellationToken cancellationToken)
   at System.Net.WebSockets.ManagedWebSocket.EnsureBufferContainsAsync(Int32 minimumRequiredBytes, CancellationToken cancellationToken, Boolean throwOnPrematureClosure)
   at System.Net.WebSockets.ManagedWebSocket.ReceiveAsyncPrivate[TWebSocketReceiveResultGetter,TWebSocketReceiveResult](Memory`1 payloadBuffer, CancellationToken cancellationToken, TWebSocketReceiveResultGetter resultGetter)
   at System.Net.WebSockets.ManagedWebSocket.ReceiveAsyncPrivate[TWebSocketReceiveResultGetter,TWebSocketReceiveResult](Memory`1 payloadBuffer, CancellationToken cancellationToken, TWebSocketReceiveResultGetter resultGetter)
   at Microsoft.AspNetCore.Http.Connections.Client.Internal.WebSocketsTransport.StartReceiving(WebSocket socket)
   at System.IO.Pipelines.PipeCompletion.ThrowLatchedException()
   at System.IO.Pipelines.Pipe.GetReadResult(ReadResult& result)
   at System.IO.Pipelines.Pipe.GetReadAsyncResult()
   at Microsoft.AspNetCore.SignalR.Client.HubConnection.ReceiveLoop(ConnectionState connectionState)
   at Microsoft.AspNetCore.SignalR.Client.HubConnection.InvokeCoreAsyncCore(String methodName, Type returnType, Object[] args, CancellationToken cancellationToken)
   at System.Threading.Tasks.ForceAsyncAwaiter`1.GetResult()
   at Microsoft.AspNetCore.SignalR.Client.HubConnection.InvokeCoreAsync(String methodName, Type returnType, Object[] args, CancellationToken cancellationToken)
   at Microsoft.AspNetCore.SignalR.Client.HubConnectionExtensions.InvokeCoreAsync[TResult](HubConnection hubConnection, String methodName, Object[] args, CancellationToken cancellationToken)
   at Microsoft.Dynamics.Nav.EditorServices.Protocol.DebugAdapter.HubBasedDebuggerService.GetSourceAsync(ApplicationObjectIdWrapper id, String objectName) in D:\a\1\s\source\Prod\Microsoft.Dynamics.Nav.EditorServices.Protocol\DebugAdapter\DebuggerServices\HubBasedDebuggerService.cs:line 316
   at Microsoft.Dynamics.Nav.EditorServices.Protocol.DebugAdapter.DebuggerService.GetSourceAsync(ApplicationObjectIdWrapper id, String objectName) in D:\a\1\s\source\Prod\Microsoft.Dynamics.Nav.EditorServices.Protocol\DebugAdapter\DebuggerServices\DebuggerService.cs:line 236
   at Microsoft.Dynamics.Nav.EditorServices.Protocol.DebugAdapter.DebuggerService.GetSourceAsync(Uri previewFile) in D:\a\1\s\source\Prod\Microsoft.Dynamics.Nav.EditorServices.Protocol\DebugAdapter\DebuggerServices\DebuggerService.cs:line 267
   at Microsoft.Dynamics.Nav.EditorServices.Protocol.LanguageServer.Extensions.DebugAdapterOpenPreviewDocumentRequestHandler.HandleAsync(PreviewDocumentRequest request, Int32 requestId, CancellationToken cancellationToken) in D:\a\1\s\source\Prod\Microsoft.Dynamics.Nav.EditorServices.Protocol\DebugAdapter\DebugAdapterOpenPreviewDocumentRequestHandler.cs:line 26
   at Microsoft.Dynamics.Nav.EditorServices.Protocol.MessageProtocol.RequestHandlerBase`1.HandleAsync(JToken requestContents, Int32 requestId, CancellationToken cancellationToken) in D:\a\1\s\source\Prod\Microsoft.Dynamics.Nav.EditorServices.Protocol\MessageProtocol\RequestHandlerBase.cs:line 84
   at Microsoft.Dynamics.Nav.EditorServices.Protocol.RequestRegistry.Process(Message message) in D:\a\1\s\source\Prod\Microsoft.Dynamics.Nav.EditorServices.Protocol\Endpoints\RequestRegistry.cs:line 86

06/16/2022 14:07:07 [/8] Process:

 scopes
06/16/2022 14:07:07 [/8] Processing scopes took 1.26E-05 seconds
06/16/2022 14:07:11 [/8] Process:

 continue
06/16/2022 14:07:11 [/8] [RegisteringHandler] [Debug] - Registering handler for client method 'HubConnected'.
[RegisteringHandler] [Debug] - Registering handler for client method 'OnAttachedToConnection'.
[RegisteringHandler] [Debug] - Registering handler for client method 'OnDetachedFromConnection'.
[RegisteringHandler] [Debug] - Registering handler for client method 'OnFatalDebuggerException'.
[RegisteringHandler] [Debug] - Registering handler for client method 'LogServerMessage'.
[RegisteringHandler] [Debug] - Registering handler for client method 'IsAlive'.
[RegisteringHandler] [Debug] - Registering handler for client method 'Break'.
[Starting] [Debug] - Starting HubConnection.
[Starting] [Debug] - Starting HttpConnection.
[EstablishingConnection] [Debug] - Establishing connection with server at 'https://vm-name.region.cloudapp.azure.com:7049/BC/dev/DebuggerHub?&tenant=default&Authentication=Basic YWRtaW46RGV2ZWxvcG1lbnQyMDE5IQ%3D%3D'.
[Established] [Debug] - Established connection '2PAyVgU4xwoHAc3lS95JrA' with the server.
[StartingTransport] [Debug] - Starting transport 'WebSockets' with Url: https://vm-name.region.cloudapp.azure.com:7049/BC/dev/DebuggerHub?&tenant=default&Authentication=Basic YWRtaW46RGV2ZWxvcG1lbnQyMDE5IQ%3D%3D.
[StartTransport] [Information] - Starting transport. Transfer mode: Text. Url: 'wss://vm-name.region.cloudapp.azure.com:7049/BC/dev/DebuggerHub?&tenant=default&Authentication=Basic YWRtaW46RGV2ZWxvcG1lbnQyMDE5IQ%3D%3D&id=2PAyVgU4xwoHAc3lS95JrA'.
[StartedTransport] [Debug] - Started transport.
[TransportStarted] [Debug] - Transport 'WebSockets' started.
[Started] [Information] - HttpConnection Started.
[HubProtocol] [Information] - Using HubProtocol 'json v1'.
[SendingHubHandshake] [Debug] - Sending Hub Handshake.
[ReceivedFromApp] [Debug] - Received message from application. Payload size: 32.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3, EndOfMessage: True.
[HandshakeComplete] [Debug] - Handshake with server complete.
[ReceiveLoopStarting] [Debug] - Receive loop starting.
[SendingMessageGeneric] [Debug] - Sending PingMessage message.
[ReceivedFromApp] [Debug] - Received message from application. Payload size: 11.
[MessageSentGeneric] [Debug] - Sending PingMessage message completed.
[Started] [Information] - HubConnection started.
[MessageReceived] [Debug] - Message received. Type: Text, size: 50, EndOfMessage: True.
[ProcessingMessage] [Debug] - Processing 50 byte message from server.
[RegisteringInvocation] [Debug] - Registering Invocation ID '1' for tracking.
[SendingMessage] [Debug] - Sending InvocationMessage message '1'.
[MessageSent] [Debug] - Sending InvocationMessage message '1' completed.
[ReceivedFromApp] [Debug] - Received message from application. Payload size: 179.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1361, EndOfMessage: True.
[ProcessingMessage] [Debug] - Processing 1361 byte message from server.
[RegisteringInvocation] [Debug] - Registering Invocation ID '2' for tracking.
[SendingMessage] [Debug] - Sending InvocationMessage message '2'.
[MessageSent] [Debug] - Sending InvocationMessage message '2' completed.
[ReceivedFromApp] [Debug] - Received message from application. Payload size: 179.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1413, EndOfMessage: True.
[ProcessingMessage] [Debug] - Processing 1413 byte message from server.
[RegisteringInvocation] [Debug] - Registering Invocation ID '3' for tracking.
[SendingMessage] [Debug] - Sending InvocationMessage message '3'.
[MessageSent] [Debug] - Sending InvocationMessage message '3' completed.
[ReceivedFromApp] [Debug] - Received message from application. Payload size: 302.
[MessageReceived] [Debug] - Message received. Type: Text, size: 44, EndOfMessage: True.
[ProcessingMessage] [Debug] - Processing 44 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2045, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 2045 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 237, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 2282 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 11, EndOfMessage: True.
[ProcessingMessage] [Debug] - Processing 11 byte message from server.
[RegisteringInvocation] [Debug] - Registering Invocation ID '4' for tracking.
[SendingMessage] [Debug] - Sending InvocationMessage message '4'.
[MessageSent] [Debug] - Sending InvocationMessage message '4' completed.
[ReceivedFromApp] [Debug] - Received message from application. Payload size: 79.
[MessageReceived] [Debug] - Message received. Type: Text, size: 44, EndOfMessage: True.
[ProcessingMessage] [Debug] - Processing 44 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2047, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 2047 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 933, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 2980 byte message from server.
[RegisteringInvocation] [Debug] - Registering Invocation ID '5' for tracking.
[SendingMessage] [Debug] - Sending InvocationMessage message '5'.
[MessageSent] [Debug] - Sending InvocationMessage message '5' completed.
[ReceivedFromApp] [Debug] - Received message from application. Payload size: 140.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1072, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 976, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 2048 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 4096 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1072, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 976, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 6144 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2045, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 8189 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1075, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 973, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 10237 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 12285 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1075, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 973, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 14333 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 16381 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1075, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 973, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 18429 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2045, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 20474 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1078, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 970, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 22522 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 24570 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1078, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 970, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 26618 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 28666 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1078, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 968, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 30712 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 32760 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1080, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 968, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 34808 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 36856 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1080, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 968, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 38904 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 40952 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1080, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 968, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 43000 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2045, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 45045 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1083, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 965, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 47093 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2045, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 49138 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1086, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 962, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 51186 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 53234 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1086, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 962, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 55282 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2046, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 57328 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1088, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 960, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 59376 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 61424 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1088, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 958, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 63470 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 65518 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1090, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 958, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 67566 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2046, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 69612 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1092, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 956, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 71660 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 73708 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1092, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 956, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 75756 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 77804 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1092, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 956, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 79852 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 81900 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1092, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 956, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 83948 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2045, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 85993 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1095, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 953, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 88041 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 90089 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1095, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 953, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 92137 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 94185 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1095, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 953, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 96233 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 98281 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1095, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 953, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 100329 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3143, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 953, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 104425 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3143, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 953, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 108521 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3143, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 950, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 112614 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3146, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 949, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 116709 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3147, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 949, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 119856 byte message from server.
[ProcessingMessage] [Debug] - Processing 120805 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3147, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 949, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 124901 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3147, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 949, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 128997 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3147, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 949, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 133093 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3147, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 946, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 137186 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3150, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 946, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 141282 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3150, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 945, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 145377 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 945, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 149473 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 945, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 153569 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 945, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 157665 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 945, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 161761 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 945, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 165857 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 945, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 169953 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 943, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 174047 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3153, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 940, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 178140 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3156, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 937, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 182233 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3159, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 937, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 186329 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3159, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 937, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 190425 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3159, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 937, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 194521 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3159, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 937, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 198617 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3159, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 937, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 202713 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3159, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 935, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 206807 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3161, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 935, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 210903 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3161, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 935, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 214999 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3161, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 932, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 219092 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3164, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 932, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 223188 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3164, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 932, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 227284 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3164, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 932, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 231380 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3164, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 932, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 235476 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3164, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 932, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 239572 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3164, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 931, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 243667 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3165, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 931, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 247763 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3165, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 931, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 251859 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3165, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 931, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 255955 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3165, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 929, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 260049 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3167, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 929, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 264145 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3167, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 929, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 268241 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3167, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 929, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 272337 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3167, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 929, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 276433 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3167, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 926, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 280526 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3170, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 926, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 284622 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3170, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 926, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 288718 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 3170, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 926, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 292814 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 294862 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1122, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 924, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 296908 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 298956 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1124, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 921, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 301001 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 303049 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1127, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 921, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 305097 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2045, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 307142 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1130, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 915, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 309187 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 311235 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1133, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 915, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 313283 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 315331 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1133, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 912, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 317376 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2046, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 319422 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1138, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 907, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 321467 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 323515 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1141, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 907, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 325563 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 327611 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1141, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 907, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 329659 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 331707 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1141, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 904, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 333752 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 335800 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1144, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 902, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 337846 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 339894 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1146, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 902, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 341942 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 343990 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1146, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 902, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 346038 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 348086 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1146, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 902, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 350134 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2047, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 352181 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1147, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 899, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 354227 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 356275 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1149, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 899, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 358323 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 360371 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1149, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 897, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 362417 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 364465 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 897, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 366513 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 368561 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 897, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 370609 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 372657 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1151, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 895, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 374703 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 376751 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1153, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 895, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 378799 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2046, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 380845 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1155, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 893, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 382893 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 2048, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 384941 byte message from server.
[MessageReceived] [Debug] - Message received. Type: Text, size: 1155, EndOfMessage: False.
[MessageReceived] [Debug] - Message received. Type: Text, size: 890, EndOfMessage: False.
[ProcessingMessage] [Debug] - Processing 386986 byte message from server.
[ReceiveStopped] [Debug] - Receive loop stopped.
[ServerDisconnectedWithError] [Error] - The server connection was terminated with an error.
[SendStopped] [Debug] - Send loop stopped.
[DisposingHttpConnection] [Debug] - Disposing HttpConnection.
[TransportStopping] [Information] - Transport is stopping.
[TransportStopped] [Debug] - Transport stopped.
[Disposed] [Information] - HttpConnection Disposed.
[CancelingOutstandingInvocations] [Debug] - Canceling all outstanding invocations.
[ShutdownWithError] [Error] - Connection is shutting down due to an error.
[InvokingClosedEventHandler] [Debug] - Invoking the Closed event handler.

06/16/2022 14:07:11 [/8] Processing continue took 0.006972 seconds
06/16/2022 14:07:11 [/8] Process:

 disconnect
06/16/2022 14:07:11 [/8] Processing disconnect took 0.0012582 seconds

What's interesting is that I have two folders: ms-dynamics-smb.al-9.0.615906 ms-dynamics-smb.al-9.2.639332

And the log is generated in the 9.0 folder. Not sure where the 9.2 folder came from since I only use the extension from the store on this machine.

It looks like the VM is closing the connection, but I haven't changed any configuration. The only recent change was moving it from 19 something to 20 something.

mjmatthiesen avatar Jun 16 '22 20:06 mjmatthiesen

Hi,

I am encountering the same issue, with the difference that the debugger will always crash when loading variable values in codeunit 5790 "Available to Promise".

MicrosoftTeams-image

When debugging my own objects in that project, the debugger works, although very very slowly (each step and loading of variable values takes multiple seconds). After some time, it crashes with the same error.

It is really difficult working like this and wastes a lot of time.

Version: DE Business Central 20.3 (Platform 20.0.42653.43814 + Application 20.3.42673.43856) AL Language v9.3.646020

GOBNilsGruettner avatar Jul 29 '22 09:07 GOBNilsGruettner

Same here. Are there any Ideas?

aptMattKoe avatar Sep 28 '22 13:09 aptMattKoe

okay, I do not know if thats new but it works for BC19 but not BC20 (same infrastructure).

aptMattKoe avatar Sep 29 '22 10:09 aptMattKoe

I similarly had no issues on BC19, but on BC21 I am seeing this as well (we skipped BC20). Strange thing is that it consistently failed when the automated test calls codeunit 90 (Purch.-Post). All previous entries in the call stack work, up to and including codeunit 91 (Purch.-Post).

Edit: whenever the test runs through table 37 (sales line), the debugger crashes as well. For me it seems to consistently break on specific objects.

PeterConijn avatar Nov 23 '22 08:11 PeterConijn

A project based on BC20.1 is facing the same issue. Whenever the codeunit 90 is touched while debugging the debugger will be disconnected.

hsiungw avatar Dec 14 '22 07:12 hsiungw

We have been able to pinpoint the cause of the connection breaks, the fix is coming with the January update (expected next week). We have also found the cause of the slowdown, the fix will come separately in one of the future updates.

ap3rus avatar Dec 30 '22 09:12 ap3rus

@JesperSchulz - do you know which cumulative updates this is available with (on prem installation) - we have a customer on 20 cu 04, and probably 20% of the objects whilst debugging have the above issue). Would this just be a cumulative update to the executables?

rtwcolumbus avatar Mar 06 '23 17:03 rtwcolumbus

This fix is in 20.10. An update of the platform should suffice, yes.

JesperSchulz avatar Mar 07 '23 07:03 JesperSchulz

ty - it doesn't appear to be fixed in 20 CU11 - I have the complication of using an embedded (mega) app as well so can't replicate in a docker thanks

rtwcolumbus avatar Mar 07 '23 16:03 rtwcolumbus

Ok, I will have to ask the team which fixed the issue then. It looks as if the issue was resolved for 20.10... Someone from the fixing team will get back to you.

JesperSchulz avatar Mar 07 '23 16:03 JesperSchulz

The team confirmed that the fix was backported. It was even released with 20.9. If you are still experiencing problems, we would need more details as to what these are.

JesperSchulz avatar Mar 07 '23 17:03 JesperSchulz

I can confirm the issue still exists in my BC 21 docker. It appears to be happening most often when I have a variable in my watch list that is out of scope to the procedure I am debugging in. Removing the watch variable seems to fix that. Other times I just find a particular line of code will cause the debugger to stop and the only way around it is setting a breakpoint beyond it instead of stepping through it.

When I come across a repeatable one again I will post the details.

jklassen avatar Mar 07 '23 23:03 jklassen

Thanks for the details, the issue tracked in this thread and which has been fixed with January updates, was related specifically to a debugger session termination caused by network latency, low connectivity or other factors causing large source files download to take more than 10 seconds, leading to an internal timeout.

The issue you are experiencing appears to be related to a specific state debugger gets into when a certain variable is present in the watch. We will get back to you.

ap3rus avatar Mar 08 '23 18:03 ap3rus