Unhold a call not working in Teams with Direct Routing

Copper Contributor

I have my Teams app setup via Direct Routing. I can make and receive calls from my carrier trunk, which is all good. I can hold a call, but I cannot resume it. Any idea or suggestion on how to debug this problem?

 

Please see the attached video clip. As you can see, after the call was put on hold, I tried to click Resume several times, but it wouldn't allow me to resume. I've managed to find the Teams log (attached below) around the time when I clicked the resume button. The first click was around 2020-10-29T04:25:46 and the second click was around 2020-10-29T04:25:55.

 

unhold.gif

 

 

2020-10-29T04:25:57.413Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/MediaStreamManager/MediaStream:0 Stopping track: "0a624874-65ab-43ac-8ccd-7315aea7f6fe"
2020-10-29T04:25:57.413Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/MediaStreamManager/MediaStream:0 Media stream stopped: Yw6UIM4vKZsW2TmIBcOMD3BcUURGaLQx1nQi
2020-10-29T04:25:57.413Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/MediaStreamManager/MediaStream:0 Track is stopped: "22154831-d9c7-4f36-ab52-42de6ba701e1"
2020-10-29T04:25:57.412Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/MediaStreamManager/MediaStream:0 Queueing media stream stop
2020-10-29T04:25:57.412Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/DeviceEnumerator stopping device polling. onDeviceChange callback used: undefined
2020-10-29T04:25:57.411Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/MediaStreamManager release media stream generation: 0 cached: 1
2020-10-29T04:25:57.411Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/MediaStreamManager onStreamDisposing event 0
2020-10-29T04:25:57.411Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/MediaStreamManager/MediaStream:0 All clients are disposed, disposing master stream
2020-10-29T04:25:57.411Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/MediaStreamManager/MediaStream:0/C:1 dispose
2020-10-29T04:25:57.411Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/DeviceManager/MediaStreamManager/MediaStream:0/C:3 dispose
2020-10-29T04:25:57.410Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1/SSM/Sender_"Audio"/S:408579927 Not removing sender from pc, connectioState: closed, track exists: true
2020-10-29T04:25:57.410Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1/SSM/Sender_"Audio" Disposing streamSender with 1 active senders
2020-10-29T04:25:57.410Z War	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/SessionV2 call onTerminated
2020-10-29T04:25:57.409Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1/videoStreamControl disposed
2020-10-29T04:25:57.409Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1/dominantSpeakerHistory disposed
2020-10-29T04:25:57.409Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1/subs disposed
2020-10-29T04:25:57.409Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1 [ddde2e32] reset candidate gathering
2020-10-29T04:25:57.400Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1/AudioRenderer dispose
2020-10-29T04:25:57.400Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1 [ddde2e32] reset peer connection
2020-10-29T04:25:57.400Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1 [ddde2e32] terminate
2020-10-29T04:25:57.399Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][7] [ddde2e32][cleanup]start
2020-10-29T04:25:57.399Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] WebRtcSignalingManager :: dispose
2020-10-29T04:25:57.399Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] sending http telemetry:, {"Ring":"general","Region":"amer","Partition":"amer02","EndpointId":"b9788770-937c-4867-a0d7-762d64f0d8be","ParticipantId":"24675de7-1c1e-4a9f-9bad-ff2085abfc31","CorrelationId":"ffae9a8a-62b6-44f6-9eb9-8857c115e508","SignalingSessionId":"aa9afe04-4d8a-4df6-9df6-6dc75dc9d9d9","ClientInformation":"SkypeSpaces/1415/1.0.0.2020101241/os=windows; osVer=10; deviceType=compu...
2020-10-29T04:25:57.395Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] sending modality telemetry:, {"Type":"SkypeConcore","ResultDetail":"CallEndReasonLocalUserInitiated","ResultValue":"Success","ResultCode":0,"ResultCauseId":"ddde2e32","JsCsaConfig":"SupportsCompressedPayload,BrokerOutgoingEnabled,BrokerIncomingEnabled,BrokerRequestBatchingEnabled,SyncTrouterResponse,handleMediaOfferFromPushNotification,handleNewOfferRequest,handleUnmuteMuteFromResponse,intern...
2020-10-29T04:25:57.394Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[ContentSharingManager] [ddde2e32][dispose]
2020-10-29T04:25:57.394Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[RenegotiationManager][Connected][stateCauseId=cbf9f454] mediaRenegotiationManager :: dispose
2020-10-29T04:25:57.394Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[CallOperationHandler][24675de7-1c1e-4a9f-9bad-ff2085abfc31] rejectLocalQueuedOperations
2020-10-29T04:25:57.394Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[CallOperationHandler][24675de7-1c1e-4a9f-9bad-ff2085abfc31] [ddde2e32][dispose]
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[ParticipantOperationHandler][b978877] rejectLocalQueuedOperations
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[ParticipantOperationHandler][b978877] [ddde2e32][dispose]
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[PM][b978877] [ddde2e32][dispose]
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] stopTimer called for : OutgoingCallEstablishment
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] timeoutManager :: dispose
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: SignalingAgent [ddde2e32][onCallCompleted] Session ID aa9afe04-4d8a-4df6-9df6-6dc75dc9d9d9 found. Deleted from signalingSession table
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP] [ddde2e32][cancelAllRequests]
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP] [09fcd3db][cancelRequestIfPending]no pending request with name=BrokerSubscribe
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP] [09fcd3db][cancelRequestIfPending]
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/Broker dispose
2020-10-29T04:25:57.393Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] [ddde2e32][dispose] end reason {"code":0,"phrase":"CallEndReasonLocalUserInitiated","subCode":0}
2020-10-29T04:25:57.392Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][7] setMediaStream removing 1
2020-10-29T04:25:57.390Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][7]/Stream[1] [ddde2e32][setMediaSession] {}
2020-10-29T04:25:57.390Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][7]/PluginlessCallStreamManager [ddde2e32][setMediaSession]
2020-10-29T04:25:57.388Z Inf	ContentSharingService: Handling removed sessions complete
2020-10-29T04:25:57.388Z Inf	ContentSharingService: Handling removed sessions (if any) ...
2020-10-29T04:25:57.388Z Inf	ContentSharingService: Handling added sessions complete
2020-10-29T04:25:57.388Z Inf	ContentSharingService: Handling added sessions (if any)...
2020-10-29T04:25:57.388Z Inf	ContentSharingService: Content changed
2020-10-29T04:25:57.388Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][7] [ddde2e32][cleanup]start
2020-10-29T04:25:57.388Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][7]/ICallParticipant[4:u11][5]/[ddde2e32][setState] state=5->4 reason=undefined->undefined
2020-10-29T04:25:57.387Z Inf	CallTranscriptMixinImplementation[ffae9a8a-62b6-44f6-9eb9-8857c115e508][28:b1902c3e-b9f7-4650-9b23-5772bd429747]: call is disconnected, cleaning up
2020-10-29T04:25:57.387Z Inf	callingParticipantService: Clearing subscriptions for teamsCallId: 1
2020-10-29T04:25:57.387Z Inf	NdiCallMixin: user left the call, callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508, object state and call event listener cleaned up
2020-10-29T04:25:57.386Z Inf	CallingBotService: call ffae9a8a-62b6-44f6-9eb9-8857c115e508 is disconnected, cleaning up bot instances and data channel registrations
2020-10-29T04:25:57.385Z Inf	[Scenario]call_state [step](6)stop (15ms/89264ms)
2020-10-29T04:25:57.385Z Inf	callingService: Call disposed callId: ffae9a8a-62b6-44f6-9eb9-8857c115e508, teamsCallId: 1
2020-10-29T04:25:57.385Z Inf	MultiCallListController: [onCallDisposed] Reset isMultiWindowCall.
2020-10-29T04:25:57.384Z Inf	CallRingingService: [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][callDispose] being disposed
2020-10-29T04:25:57.384Z Inf	ScdMixin: Disposing mixin null - Stopped
2020-10-29T04:25:57.383Z Inf	MeetingInfoMixin: End Of Meeting Alert Timer disposed with callDispose
2020-10-29T04:25:57.383Z Inf	MultiCallListController: callCountInDockedView: 0, context: floating_call_monitor, isShownInExternalWindow: false, isExpandedDockedMonitorShown: false, multiCallListClassName: , multiCallListHasExpandedCall: false
2020-10-29T04:25:57.383Z Inf	MultiCallListController: callCountInDockedView: 0, context: floating_call_monitor, isShownInExternalWindow: false, isExpandedDockedMonitorShown: false, multiCallListClassName: , multiCallListHasExpandedCall: false
2020-10-29T04:25:57.383Z Inf	MultiCallListController: [onCallDisposed] Reset isMultiWindowCall.
2020-10-29T04:25:57.383Z Inf	DraggableMultiCallList: [onCallDisposed] Reset isMultiWindowCall.
2020-10-29T04:25:57.383Z Inf	MultiCallListController: callCountInDockedView: 0, context: in_app_call_monitor, isShownInExternalWindow: false, isExpandedDockedMonitorShown: false, multiCallListClassName: , multiCallListHasExpandedCall: false
2020-10-29T04:25:57.382Z Inf	MultiCallListController: callCountInDockedView: 0, context: in_app_call_monitor, isShownInExternalWindow: false, isExpandedDockedMonitorShown: false, multiCallListClassName: , multiCallListHasExpandedCall: false
2020-10-29T04:25:57.382Z Inf	MultiCallListController: [onCallDisposed] Reset isMultiWindowCall.
2020-10-29T04:25:57.379Z Inf	callingService: delete call subscriptions for ffae9a8a-62b6-44f6-9eb9-8857c115e508
2020-10-29T04:25:57.379Z Inf	callingService: [wasAnsweredElsewhere] Checking for . [hasData=no][isToastEnabled=false][isEnabledForAllSimRings=false][isEnabledForCQ=true][callState=7]
2020-10-29T04:25:57.379Z War	MutedCallNotificationsService: [removeCall] Unable to remove a muted notification for call "ffae9a8a-62b6-44f6-9eb9-8857c115e508". No muted call is found.
2020-10-29T04:25:57.373Z Inf	addCallingMessage: creating SkypeMessageLocal for call.callId = ffae9a8a-62b6-44f6-9eb9-8857c115e508
2020-10-29T04:25:57.373Z Inf	addQuestionnaire: callId = ffae9a8a-62b6-44f6-9eb9-8857c115e508, did media connect = true, did signaling connect = true
2020-10-29T04:25:57.371Z Inf	callingService: [wasAnsweredElsewhere] Checking for . [hasData=no][isToastEnabled=false][isEnabledForAllSimRings=false][isEnabledForCQ=true][callState=7]
2020-10-29T04:25:57.371Z Inf	callingService: onDisconnectedCall for ffae9a8a-62b6-44f6-9eb9-8857c115e508
2020-10-29T04:25:57.370Z Inf	[Scenario]call_state [step](5)Disconnected (427ms)
2020-10-29T04:25:57.370Z Inf	callingService: callingService.onCallStateChanged: callId = ffae9a8a-62b6-44f6-9eb9-8857c115e508 to state = Disconnected, [call][callStateChanged=Disconnected][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][teamsCallType=1]
2020-10-29T04:25:57.370Z Inf	ExtendedCallStateMixin[callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508::teamsCallId=1]: extendedCallState changed: 6 => 7
2020-10-29T04:25:57.370Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][7]/[ddde2e32][setCallState] success=changed to 7, terminatedReason: 1, failureType=2}
2020-10-29T04:25:57.370Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][6]/[ddde2e32][setCallState] currentState=6, newState=7, terminatedReason=0
2020-10-29T04:25:57.369Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][6]/[Operation] [ddde2e32][_ConnectCall]maybeRejectOperation ignored, operation does not exist
2020-10-29T04:25:57.369Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][6]/[Operation] [ddde2e32][_WaitForAnswer]maybeRejectOperation ignored, operation does not exist
2020-10-29T04:25:57.369Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][6]/[ddde2e32][onCallStatusChanged] [status=LocalTerminated][reason={
  "code": 0,
  "subCode": 0,
  "phrase": "CallEndReasonLocalUserInitiated"
}]
2020-10-29T04:25:57.369Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] [ddde2e32][onCallStatusChanged][statusCode={"code":0,"phrase":"CallEndReasonLocalUserInitiated","subCode":0}]
2020-10-29T04:25:57.369Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] [ddde2e32][onCallStatusChanged]Connected=>LocalTerminated]
2020-10-29T04:25:57.368Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] [ddde2e32][terminateEstablishedCall]success
2020-10-29T04:25:57.368Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][LeaveConversation] [steps=[waitForTrouter],[trouterReady],[buildRequest],[requestBuilt],[sendRequest]]
2020-10-29T04:25:57.368Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][LeaveConversation] [success]
2020-10-29T04:25:57.368Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][LeaveConversation] [finished][status=204][statusText=][retryCount=1]
2020-10-29T04:25:57.368Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][Dispatcher] Request timer stopped
2020-10-29T04:25:57.368Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][Dispatcher] Request timer stopped
2020-10-29T04:25:57.368Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][Dispatcher] attempt successful, request response={
  "response": null,
  "request": {},
  "duration": 381
} status=204
2020-10-29T04:25:57.368Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: RequestDispatcher/[bb2a6bfe][Request] success, status=204
2020-10-29T04:25:56.987Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: RequestDispatcher/[bb2a6bfe][Request] sending, POST-https://api.flightproxy.teams.microsoft.com/api/v2/ep/conv-uswe-09.conv.skype.com/conv/-DtSrh9UBUy841QhrNmaeg/leave?i=134&e=637390937827011134
2020-10-29T04:25:56.987Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][Dispatcher] attempt to send request
2020-10-29T04:25:56.973Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][Dispatcher] next delay= 0 ms
2020-10-29T04:25:56.973Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][Dispatcher] scheduleNextRequest with retry
2020-10-29T04:25:56.973Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][Dispatcher] send api called
2020-10-29T04:25:56.973Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][Dispatcher] created request, url=https://api.flightproxy.teams.microsoft.com/api/v2/ep/conv-uswe-09.conv.skype.com/conv/-DtSrh9UBUy841QhrNmaeg/leave?i=134&e=637390937827011134
2020-10-29T04:25:56.973Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: SignalingAgent/TokenManager [ddde2e32][getToken] fetching new token, success token eyJhbGciOiJSUzI1NiIsImtpZCI6IjEwMiIsInR5cCI6IkpXVCJ9.eyJpYXQiOjE2MDM5NDU0NTQsImV4cCI6MTYwNDAzMTg1NCwic2t5cGVpZCI6Im9yZ2lkOjNkZDZhNTdlLTgyNTItNDVjYy1iMDZkLTU1NmU4YTFkYTI1YSIsInNjcCI6NzgwLCJjc2kiOiIxNjAzOTQ1MTU0IiwidGlkIjoiZTQzYTRiZmUtMmI5ZC00ZWZlLTlmMmMtZWFjY2RmYmQ2NTg1IiwicmduIjoiYW1lciJ9.WEKoUxBY3USevpojQf4RiD2sldOkc-gmVj-GqsesENtJiKRlQAfnrT3ymYWsrs_MjWMObZ...
2020-10-29T04:25:56.960Z Inf	callingService: Finish start call scenarios [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][action=cancel][abortReason=undefined][terminatedReason=0][callControllerCode=undefined][callControllerSubCode=undefined][reason=Hangup][primaryScenario=create_one_to_one_pstn_call][scenarios=create_one_to_one_pstn_call, calling_intent, media_connected]
2020-10-29T04:25:56.960Z War	ngClickDecorator: element set ng-click to send BI telemetry but no tracking attibutes {"class":"flex-fill ts-calling-screen-wrapper","dataTid":"calling-screen"}
2020-10-29T04:25:56.960Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: SignalingAgent/TokenManager [ddde2e32][getToken] fetching new token
2020-10-29T04:25:56.960Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP] getInternalHttpDispatcherConfig requesnt type: LeaveConversation config: {
  "retryIntervalsMs": [
    200,
    1000,
    5000
  ],
  "maxRequestAttempts": 4,
  "perRequestTimeoutMs": 45000,
  "perAttemptTimeoutMs": 15000,
  "requestHedgingCapability": [
    "disabled"
  ]
}
2020-10-29T04:25:56.960Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle]/[HTTP]/[ddde2e32][LeaveConversation] [scheduled]
2020-10-29T04:25:56.959Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Idle] [ddde2e32][leaveConversation]
2020-10-29T04:25:56.959Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Connected] [ddde2e32][terminateEstablishedCall]
2020-10-29T04:25:56.959Z Inf	callingAgents: calling-stack [JS.TsCalling.SignalingAgent] ffffffff: CSA[ffae9a8a-62b6-44f6-9eb9-8857c115e508][Connected] [ddde2e32][endAsync][rejectionData={}][endCallForAll=false]
2020-10-29T04:25:56.957Z Inf	callStartedMessageProviderService: Cleaning up resources for callId ffae9a8a-62b6-44f6-9eb9-8857c115e508
pendingMessageRequests length: 0
      pendingCallsSubscriptions length: 0
      pendingCallsOldIdNewIdMapping length: 0
      pendingCallsNewIdOldIdMapping length: 0
2020-10-29T04:25:56.957Z Inf	CallRingingService: [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508] onCallStateChanged [callState=6
2020-10-29T04:25:56.957Z Inf	deviceManager: Speaker preference not populated from preferredSpeaker.
2020-10-29T04:25:56.957Z Inf	deviceManager: Mic preference not populated from preferredMic.
2020-10-29T04:25:56.957Z Inf	deviceManager: Application Previous state: 1, New State: 3
2020-10-29T04:25:56.957Z Inf	PresencePostActiveService: checkTransition: appState: Interactive, machineState: MachineInactive(2), isPostingActive: true, shouldPostActive: true
2020-10-29T04:25:56.945Z Inf	UnifiedPresenceService: app state changed to 3
2020-10-29T04:25:56.944Z Inf	AppState: App state changed from InCall to Interactive due to calling state changed: ongoingCall=false with code undefined after 61.639 seconds
2020-10-29T04:25:56.943Z Inf	[Scenario]call_state [step](4)Disconnecting (30672ms)
2020-10-29T04:25:56.942Z Inf	callingService: callingService.onCallStateChanged: callId = ffae9a8a-62b6-44f6-9eb9-8857c115e508 to state = Disconnecting, [call][callStateChanged=Disconnecting][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][teamsCallType=1]
2020-10-29T04:25:56.942Z Inf	ExtendedCallStateMixin[callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508::teamsCallId=1]: extendedCallState changed: 4 => 6
2020-10-29T04:25:56.942Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][6]/[ddde2e32][setCallState] success=changed to 6, terminatedReason: 0, failureType=2}
2020-10-29T04:25:56.942Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[ddde2e32][setCallState] currentState=4, newState=6, terminatedReason=0
2020-10-29T04:25:56.942Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[ddde2e32][stopInternal] started, terminatedReason=0, rejectReason={}
2020-10-29T04:25:56.937Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[Operation] [ddde2e32][StopCall] executing operation...
2020-10-29T04:25:56.937Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[Operation] [ddde2e32][StopCall][creating...]
2020-10-29T04:25:56.936Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[Operation] [ddde2e32][StopCall]API called, 
2020-10-29T04:25:56.936Z Inf	callingService: [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][endCallForEveryone=false] call.stop is being invoked
2020-10-29T04:25:56.931Z Inf	callingService: leaving call internal: 1  with call state: 4
2020-10-29T04:25:56.931Z Inf	[Scenario]leave_meetup start
2020-10-29T04:25:56.931Z Inf	callingService: leaving call: [callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][leaveCallContext=call_screen][leaveCallState:4][isCallOnServerHold=false]
2020-10-29T04:25:56.931Z Inf	calling-handler: Execute proceeding to hangup
2020-10-29T04:25:56.275Z Inf	immersiveModeHandler: Immersive mode supressed with hardSuppress undefined, context: CallingButtons - immersiveModeAllowed: true, isImmersive:  false, hardSuppressedBy: , timeoutSuppressedBy: CallingButtons
2020-10-29T04:25:55.768Z Inf	immersiveModeHandler: allowImmersiveMode called with hardAllow true, context: CallingMoreActionsMenu - immersiveModeAllowed: true, isImmersive:  false, hardSuppressedBy: , timeoutSuppressedBy: 
2020-10-29T04:25:55.755Z Err	Unhandled exception. TypeError: Cannot read property 'nodeName' of null, cause: , stack: Object.getScrollParent@https://statics.teams.cdn.office.net/hashedjs/1-vendor.min-9b4e6e9.js:77:7077 > Object.focus@https://statics.teams.cdn.office.net/hashedjs/1-vendor.min-9b4e6e9.js:77:8153 > n.activate@https://statics.teams.cdn.office.net/hashedjs/1-vendor.min-9b4e6e9.js:77:5958 > HTMLButtonElement.<anonymous>@https://statics.teams.cdn.office.net/hashedjs/1-vendor.min-9b4e6e9.js:77:5527 > HTMLButtonElement.dispatch...
2020-10-29T04:25:55.749Z Inf	[Scenario]resume_call [step](1)stop (43ms/43ms)
2020-10-29T04:25:55.709Z Inf	[Scenario]resume_call [step](2)stop (3ms/3ms)
2020-10-29T04:25:55.707Z Inf	CallTogglingService: cannot unhold callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508, because another unhold operation is in progress
2020-10-29T04:25:55.706Z Inf	[Scenario]resume_call start
2020-10-29T04:25:55.706Z Inf	[Scenario]resume_call [step](1)toggle_hold (0ms)
2020-10-29T04:25:55.706Z Inf	CallParkService: [unholdCall] [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508] being unheld using [toggleHold] with [context=call_screen]
2020-10-29T04:25:55.706Z Inf	callHoldService: [isMusicOnHoldV2Eligible][mohV2] isMohV2Eligible for call [callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508] is false
2020-10-29T04:25:55.706Z Inf	callHoldService: [isMusicOnHoldV2Eligible][mohV2] [isMusicOnHoldV2Enabled=false] [isMusicOnHoldAdminEnabled=true] [isOneToOnePstnCall=true] [isMohV2SlimcoreAvailable=true]
2020-10-29T04:25:55.706Z Inf	callHoldService: [isMusicOnHoldV2Enabled][mohV2] is false
2020-10-29T04:25:55.706Z Inf	[Scenario]resume_call start
2020-10-29T04:25:55.705Z Inf	CallParkService: [unholdCall] invoked for [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][context:call_screen][callState=4]
2020-10-29T04:25:55.705Z Inf	CallParkService: [toggleHold] [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508] is being unheld [unholdCall]
2020-10-29T04:25:55.705Z Inf	CallParkService: [toggleHold][call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][context=call_screen][forceLocalToggle=undefined]
2020-10-29T04:25:54.565Z Inf	Telemetry: [TelemetryManagerImpl]: Processing telemetry events took 2 ms for 1 events with total size of 4839 bytes, remaining events - 0
2020-10-29T04:25:54.203Z Inf	immersiveModeHandler: allowImmersiveMode called with hardAllow undefined, context: CallingButtons - immersiveModeAllowed: false, isImmersive:  false, hardSuppressedBy: CallingMoreActionsMenu, timeoutSuppressedBy: 
2020-10-29T04:25:53.694Z Inf	immersiveModeHandler: Immersive mode supressed with hardSuppress true, context: CallingMoreActionsMenu - immersiveModeAllowed: false, isImmersive:  false, hardSuppressedBy: CallingMoreActionsMenu, timeoutSuppressedBy: CallingButtons
2020-10-29T04:25:53.536Z Inf	more-handler: Execute called
2020-10-29T04:25:46.815Z Inf	immersiveModeHandler: Immersive mode supressed with hardSuppress undefined, context: CallingButtons - immersiveModeAllowed: true, isImmersive:  false, hardSuppressedBy: , timeoutSuppressedBy: CallingButtons
2020-10-29T04:25:46.401Z Inf	immersiveModeHandler: disableImmersive() - immersiveModeAllowed: true, isImmersive:  false, hardSuppressedBy: , timeoutSuppressedBy: 
2020-10-29T04:25:46.401Z Inf	immersiveModeHandler: Immersive mode deactivated with eventType: mousemove - immersiveModeAllowed: true, isImmersive:  true, hardSuppressedBy: , timeoutSuppressedBy: 
2020-10-29T04:25:46.242Z War	ngClickDecorator: element set ng-click to send BI telemetry but no tracking attibutes {"class":"flex-fill ts-calling-screen-wrapper","dataTid":"calling-screen"}
2020-10-29T04:25:46.241Z Inf	NdiCallMixin: user left the call, callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508, object state and call event listener cleaned up
2020-10-29T04:25:46.241Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4] setMediaStream removing 3
2020-10-29T04:25:46.241Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4] [5a202708]screenSharingOn=false
2020-10-29T04:25:46.240Z Inf	NdiCallMixin: user left the call, callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508, object state and call event listener cleaned up
2020-10-29T04:25:46.240Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4] setMediaStream removing 1
2020-10-29T04:25:46.240Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4] [5a202708]videoOn=false
2020-10-29T04:25:46.240Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[Operation]/[Chained] [5a202708][_RenegotiateOutgoing]enqueueing, queue=[ParkCall,_RenegotiateOutgoing], reset=false
2020-10-29T04:25:46.240Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[Operation] [5a202708][_RenegotiateOutgoing][u13]chained
2020-10-29T04:25:46.240Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[Operation] [5a202708][_RenegotiateOutgoing][u13][creating...]
2020-10-29T04:25:46.240Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4] [5a202708][onNegotiationRequired] Negotiation started, state: 4
2020-10-29T04:25:46.240Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1 [5a202708] triggering renegotiation
2020-10-29T04:25:46.240Z Inf	callingAgents: calling-stack [JS.TsCalling.MediaAgent] ffffffff: MA/webrtc2-1 [5a202708] configure modalities, audio: sendrecv, video: inactive, sharing: undefined, peerconnection: true, pc.signalingState: stable, needNewRenegotiation: true
2020-10-29T04:25:46.234Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[5a202708][updateMediaModalities] modalities={
  "audio": "sendrecv",
  "video": "inactive"
}
2020-10-29T04:25:46.234Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[5a202708][calculateModalityDirections] done, modalities={
  "audio": "sendrecv",
  "video": "inactive"
}
2020-10-29T04:25:46.234Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[5a202708][calculateModalityDirections] calculateSharingDirection, offer=undefined, requestedScreenSharingState=false, isSomeoneSharing=false
2020-10-29T04:25:46.234Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[5a202708][calculateModalityDirections] calculateVideoDirection, offer=undefined, requestedVideoState=false, isSomeoneStreamingVideo=false
2020-10-29T04:25:46.234Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[5a202708][calculateModalityDirections] calculateAudioDirection, offer=undefined, requestedAudioState=true
2020-10-29T04:25:46.234Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[5a202708][calculateModalityDirections] callUsesMixer=false
2020-10-29T04:25:46.233Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[5a202708][updateMediaModalities] options={},requestHoldState=false,requestedScreenSharingState=false
2020-10-29T04:25:46.233Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[5a202708][updateMediaModalities] callUsesMixer=false,isSomeoneSharing=false,isSomeoneStreamingVideo=false
2020-10-29T04:25:46.233Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[5a202708][setHold]hold=false start, holdOptions: undefined
2020-10-29T04:25:46.227Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[Operation] [5a202708][Unhold] executing operation...
2020-10-29T04:25:46.227Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[Operation] [5a202708][Unhold][creating...]
2020-10-29T04:25:46.227Z Inf	callingAgents: calling-stack [JS.TsCalling.Pluginless] ffffffff: PluginlessStack/Web[ffae9a8a-62b6-44f6-9eb9-8857c115e508][b9788770][24675de7][4]/[Operation] [5a202708][Unhold]API called, 
2020-10-29T04:25:46.227Z Inf	CallTogglingService: [unholdCall] Resuming call (callId = ffae9a8a-62b6-44f6-9eb9-8857c115e508)...
2020-10-29T04:25:46.227Z Inf	CallTogglingService: [unholdCall] Resetting background blurring (callId = ffae9a8a-62b6-44f6-9eb9-8857c115e508)
2020-10-29T04:25:46.226Z Inf	callingService: putOtherCallsOnHold: callsToHold as forceActiveCallToLocalHold: false except teamsCallId: 1, call Ids of other calls to hold: []
2020-10-29T04:25:46.176Z Inf	[Scenario]resume_call start
2020-10-29T04:25:46.175Z Inf	[Scenario]resume_call [step](1)toggle_hold (0ms)
2020-10-29T04:25:46.175Z Inf	CallParkService: [unholdCall] [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508] being unheld using [toggleHold] with [context=call_screen]
2020-10-29T04:25:46.175Z Inf	callHoldService: [isMusicOnHoldV2Eligible][mohV2] isMohV2Eligible for call [callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508] is false
2020-10-29T04:25:46.175Z Inf	callHoldService: [isMusicOnHoldV2Eligible][mohV2] [isMusicOnHoldV2Enabled=false] [isMusicOnHoldAdminEnabled=true] [isOneToOnePstnCall=true] [isMohV2SlimcoreAvailable=true]
2020-10-29T04:25:46.175Z Inf	callHoldService: [isMusicOnHoldV2Enabled][mohV2] is false
2020-10-29T04:25:46.175Z Inf	[Scenario]resume_call start
2020-10-29T04:25:46.175Z Inf	CallParkService: [unholdCall] invoked for [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][context:call_screen][callState=4]
2020-10-29T04:25:46.174Z Inf	CallParkService: [toggleHold] [call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508] is being unheld [unholdCall]
2020-10-29T04:25:46.174Z Inf	CallParkService: [toggleHold][call][callId=ffae9a8a-62b6-44f6-9eb9-8857c115e508][context=call_screen][forceLocalToggle=undefined]

 

Thanks

1 Reply

@danieltest what tool is used to check the logs?