I have a call with Bot, PSTN, and team participants. When I invoke the Hold operation on PSTN participant (IParticipant.StartHoldMusicAsync method) I hear hold music in the phone but the operation fails after the 30s with the exception "Timed out while waiting for the operation with context 05d9886f-6419-46c3-b16a-9dc58b5137d8 to complete."
When I use older versions of libraries (Microsoft.Graph.Communications.Calls 1.2.7270...) and the same settings in my app, the Hold operation is OK.
Libs version:
Microsoft.Graph.Communications Calls SDK version 1.2.0.10115
Microsoft.Graph 5.44.0.0
I have a call with Bot, PSTN, and team participants. When I invoke the Hold operation on PSTN participant (IParticipant.StartHoldMusicAsync method) I hear hold music in the phone but the operation fails after the 30s with the exception "Timed out while waiting for the operation with context 05d9886f-6419-46c3-b16a-9dc58b5137d8 to complete." When I use older versions of libraries (Microsoft.Graph.Communications.Calls 1.2.7270...) and the same settings in my app, the Hold operation is OK.
Libs version: Microsoft.Graph.Communications Calls SDK version 1.2.0.10115 Microsoft.Graph 5.44.0.0
Log:
2024-03-06T10:07:49.3645168+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Holding the participant 6225db03-cbab-46d8-adbb-8268db8a249f meeting audio for the current call leg and play music.,REQID:,TS:3/6/2024 10:06:32 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:49.4578314+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Generating client context: 05d9886f-6419-46c3-b16a-9dc58b5137d8,REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:00000000-0000-0000-0000-000000000000 2024-03-06T10:07:49.5636721+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Waiting for completion of operation with id: 05d9886f-6419-46c3-b16a-9dc58b5137d8 and timeout: 00:00:30,REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:00000000-0000-0000-0000-000000000000 2024-03-06T10:07:49.5959321+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Authenticating request for tenant: f3ba0372-2fa1-44e3-9fe3-460c12e76132,REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:49.6251373+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:{ "additionalData": {}, "backingStore": { "initializationCompleted": true, "returnOnlyChangedValues": false }, "clientContext": "7a4f5731-d739-4f34-82cb-6a78b39145e6", "customPrompt": null },REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:49.9307057+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Scenario ID extracted from Scenario-Id is: 7581aca0-768a-4fde-abce-127eb83311e6,REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:49.9357288+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Request ID extracted from X-Microsoft-Skype-Message-ID is: 303ca39d-6e20-46c2-8100-85a83ab905fc,REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:49.9408113+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Authenticating inbound request (303ca39d-6e20-46c2-8100-85a83ab905fc): https://teamscti.kordina.cz/callnotify,REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:49.9574058+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Queue found for resource communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants, current queue size 7 with id communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3,REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.0010896+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:{ "value": [ { "changeType": "Updated", "resourceUrl": "/communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants", "resource": "/app/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants", "resourceData": [ { "participants": [ { "info": { "additionalData": { "endpointId": "cdc7da5e-ffff-ffff-c5a0-2bea5d7e7e70", "platformId": "49", "clientVersion": "CallSignalingAgent (49/24033.811.2738.2546/24020205511;release_releases/CL2024.R04.2024.04.01.4;releases/CL2024.R04)", "replacementLink": "https://cc-euno-05-prod-aks.cc.skype.com/cc/v1/callParticipant/44fa855e-85bf-463a-863f-c522b8941888/1529/k2/2187/replacement?rt=4b5f6192a97c4be8840220361849f08f&rc=eyJydGlkIjoiODpvcmdpZDo5YzM0NjhkNy1kMjJmLTQwZGEtOGU2OS1iNGFkMzBmM2VkMGEiLCJydGxpIjoiZW4tdXMiLCJydHJzIjoiRW50ZXJwcmlzZVByb3h5In0%253D&i=10-60-1-10&e=638449233857537186" }, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "countryCode": null, "endpointType": 0, "identity": { "additionalData": {}, "application": null, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "device": null, "odataType": "#microsoft.graph.identitySet", "user": { "additionalData": { "tenantId": "f3ba0372-2fa1-44e3-9fe3-460c12e76132" }, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "displayName": "Jaroslav Kordina", "id": "9c3468d7-d22f-40da-8e69-b4ad30f3ed0a", "odataType": "#microsoft.graph.identity" } }, "languageId": "en-us", "odataType": "#microsoft.graph.participantInfo", "participantId": "4b5f6192-a97c-4be8-8402-20361849f08f", "region": null }, "isInLobby": false, "isMuted": false, "mediaStreams": [ { "additionalData": {}, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "direction": 3, "label": "main-audio", "mediaType": 0, "odataType": "#microsoft.graph.mediaStream", "serverMuted": false, "sourceId": "615" }, { "additionalData": {}, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "direction": 2, "label": "main-video", "mediaType": 1, "odataType": "#microsoft.graph.mediaStream", "serverMuted": false, "sourceId": "616" }, { "additionalData": {}, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "direction": 2, "label": "applicationsharing-video", "mediaType": 2, "odataType": "#microsoft.graph.mediaStream", "serverMuted": false, "sourceId": "626" }, { "additionalData": {}, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "direction": 3, "label": "data", "mediaType": 3, "odataType": "#microsoft.graph.mediaStream", "serverMuted": false, "sourceId": "627" } ], "metadata": null, "recordingInfo": null, "removedState": null, "restrictedExperience": null, "rosterSequenceNumber": 9, "additionalData": { "publishedStates": [], "meetingRole": "none", "replacementLink": "https://cc-euno-05-prod-aks.cc.skype.com/cc/v1/callParticipant/44fa855e-85bf-463a-863f-c522b8941888/1529/k2/2187/replacement?rt=4b5f6192a97c4be8840220361849f08f&rc=eyJydGlkIjoiODpvcmdpZDo5YzM0NjhkNy1kMjJmLTQwZGEtOGU2OS1iNGFkMzBmM2VkMGEiLCJydGxpIjoiZW4tdXMiLCJydHJzIjoiRW50ZXJwcmlzZVByb3h5In0%253D&i=10-60-1-10&e=638449233857537186", "isIdentityAnonymized": "False" }, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "id": "4b5f6192-a97c-4be8-8402-20361849f08f", "odataType": "#microsoft.graph.participant" }, { "info": { "additionalData": { "endpointId": "7e13418e-ccbb-4623-a96f-7a192b783a1f", "clientVersion": "pstn (Teams/)", "replacementLink": "https://cc-euno-05-prod-aks.cc.skype.com/cc/v1/callParticipant/44fa855e-85bf-463a-863f-c522b8941888/13/k3/414/replacement?rt=6225db03cbab46d8adbb8268db8a249f&rc=eyJydGxicmMiOnsiZXRpZCI6ImYzYmEwMzcyLTJmYTEtNDRlMy05ZmUzLTQ2MGMxMmU3NjEzMiIsImlwZGl0YyI6ZmFsc2UsInBzdCI6IkRpcmVjdFJvdXRpbmcifSwicnRpZCI6IjQ6KzQyMDcyNTIxMDM0NCIsInJ0cGZzIjp7fX0%253D&i=10-60-1-10&e=638449233857537186" }, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "countryCode": null, "endpointType": 0, "identity": { "additionalData": { "phone": { "@odata.type": "#microsoft.graph.identity", "id": "+420725210344", "displayName": "+420725210344", "identityProvider": "None" } }, "application": null, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "device": null, "odataType": "#microsoft.graph.identitySet", "user": null }, "languageId": null, "odataType": "#microsoft.graph.participantInfo", "participantId": "6225db03-cbab-46d8-adbb-8268db8a249f", "region": null }, "isInLobby": false, "isMuted": false, "mediaStreams": [ { "additionalData": {}, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "direction": 3, "label": "main-audio", "mediaType": 0, "odataType": "#microsoft.graph.mediaStream", "serverMuted": false, "sourceId": "201" } ], "metadata": null, "recordingInfo": null, "removedState": null, "restrictedExperience": null, "rosterSequenceNumber": 2, "additionalData": { "publishedStates": [], "meetingRole": "none", "replacementLink": "https://cc-euno-05-prod-aks.cc.skype.com/cc/v1/callParticipant/44fa855e-85bf-463a-863f-c522b8941888/13/k3/414/replacement?rt=6225db03cbab46d8adbb8268db8a249f&rc=eyJydGxicmMiOnsiZXRpZCI6ImYzYmEwMzcyLTJmYTEtNDRlMy05ZmUzLTQ2MGMxMmU3NjEzMiIsImlwZGl0YyI6ZmFsc2UsInBzdCI6IkRpcmVjdFJvdXRpbmcifSwicnRpZCI6IjQ6KzQyMDcyNTIxMDM0NCIsInJ0cGZzIjp7fX0%253D&i=10-60-1-10&e=638449233857537186", "isIdentityAnonymized": "False" }, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "id": "6225db03-cbab-46d8-adbb-8268db8a249f", "odataType": "#microsoft.graph.participant" }, { "info": { "additionalData": { "endpointId": "92f5ba7e-f8c4-401d-9526-961402ddfa33", "clientVersion": "JaKBot_Test (appid:e9d1ec23-399e-4920-8e3b-85ec393e6657)", "replacementLink": "https://cc-euno-05-prod-aks.cc.skype.com/cc/v1/callParticipant/44fa855e-85bf-463a-863f-c522b8941888/27/k2/441/replacement?rt=c50a0e4ac3d44bcfa232834d2fbd01fd&rc=eyJydGlkIjoiMjg6b3JnaWQ6ZDBmZWI2ZTItMzY2ZC00M2ZlLWE1NGUtNWQ1ZWVmODIwNmQ4In0%253D&i=10-60-1-10&e=638449233857537186" }, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "countryCode": null, "endpointType": 0, "identity": { "additionalData": { "applicationInstance": { "@odata.type": "#microsoft.graph.identity", "id": "d0feb6e2-366d-43fe-a54e-5d5eef8206d8", "tenantId": "f3ba0372-2fa1-44e3-9fe3-460c12e76132", "identityProvider": "AAD" } }, "application": null, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "device": null, "odataType": "#microsoft.graph.identitySet", "user": null }, "languageId": null, "odataType": "#microsoft.graph.participantInfo", "participantId": "c50a0e4a-c3d4-4bcf-a232-834d2fbd01fd", "region": null }, "isInLobby": false, "isMuted": false, "mediaStreams": [ { "additionalData": {}, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "direction": 3, "label": "main-audio", "mediaType": 0, "odataType": "#microsoft.graph.mediaStream", "serverMuted": false, "sourceId": "402" } ], "metadata": null, "recordingInfo": null, "removedState": null, "restrictedExperience": null, "rosterSequenceNumber": 2, "additionalData": { "publishedStates": [], "meetingRole": "none", "replacementLink": "https://cc-euno-05-prod-aks.cc.skype.com/cc/v1/callParticipant/44fa855e-85bf-463a-863f-c522b8941888/27/k2/441/replacement?rt=c50a0e4ac3d44bcfa232834d2fbd01fd&rc=eyJydGlkIjoiMjg6b3JnaWQ6ZDBmZWI2ZTItMzY2ZC00M2ZlLWE1NGUtNWQ1ZWVmODIwNmQ4In0%253D&i=10-60-1-10&e=638449233857537186", "isIdentityAnonymized": "False" }, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "id": "c50a0e4a-c3d4-4bcf-a232-834d2fbd01fd", "odataType": "#microsoft.graph.participant" } ], "sequenceNumber": 9, "additionalData": {}, "backingStore": { "initializationCompleted": true, "returnOnlyChangedValues": false }, "id": null, "odataType": "#microsoft.graph.deltaParticipants" } ] } ] },REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.0062276+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:,REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.0108930+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Updating collection notification with resource data, NotificationId: 9ee3fa12-83b3-4ce6-8a74-298a478cd49e, Notification ResourceUrl: /communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants,REQID:,TS:3/6/2024 10:07:49 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.0161743+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:{ "clientContext": "7a4f5731-d739-4f34-82cb-6a78b39145e6", "resultInfo": null, "status": 1, "additionalData": { "@odata.context": "https://graph.microsoft.com/v1.0/$metadata#startHoldMusicOperation" }, "backingStore": { "initializationCompleted": true, "returnOnlyChangedValues": false }, "id": "2e449342-c490-4fd5-9e24-ca957e41dc17", "odataType": "#microsoft.graph.startHoldMusicOperation" },REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.0330033+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Microsoft.Graph.Communications.Calls.IParticipant collection notification with 3 elements received for Updated: /communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.0447089+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.0502675+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.0580291+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Found Location Header: https://graph.microsoft.com/v1.0/communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/operations/2e449342-c490-4fd5-9e24-ca957e41dc17,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 06 10:07:50,050: 188 : CtiTeamsServiceCore.ProcessCallNotificationAsync: Callback response: StatusCode: 202, ReasonPhrase: 'Accepted', Version: 1.1, Content: System.Net.Http.EmptyContent, Headers: { Client-Request-Id: 303ca39d-6e20-46c2-8100-85a83ab905fc Scenario-Id: 7581aca0-768a-4fde-abce-127eb83311e6 } 2024-03-06T10:07:50.0756509+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Running status response, operation running asyncronously: 05d9886f-6419-46c3-b16a-9dc58b5137d8,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:00000000-0000-0000-0000-000000000000 2024-03-06T10:07:50.0940252+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Scenario ID extracted from Scenario-Id is: 7581aca0-768a-4fde-abce-127eb83311e6,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.0986734+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Request ID extracted from X-Microsoft-Skype-Message-ID is: d4cf6cd3-fe6d-4b5d-9ff6-36a2ccb32e0a,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.1042719+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Authenticating inbound request (d4cf6cd3-fe6d-4b5d-9ff6-36a2ccb32e0a): https://teamscti.kordina.cz/callnotify,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.1123949+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Queue found for resource communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants, current queue size 7 with id communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.1222120+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:{ "value": [ { "changeType": "Updated", "resourceUrl": "/communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants", "resource": "/app/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants", "resourceData": [ { "participants": [ { "info": { "additionalData": { "endpointId": "7e13418e-ccbb-4623-a96f-7a192b783a1f", "clientVersion": "pstn (Teams/)", "replacementLink": "https://cc-euno-05-prod-aks.cc.skype.com/cc/v1/callParticipant/44fa855e-85bf-463a-863f-c522b8941888/13/k3/414/replacement?rt=6225db03cbab46d8adbb8268db8a249f&rc=eyJydGxicmMiOnsiZXRpZCI6ImYzYmEwMzcyLTJmYTEtNDRlMy05ZmUzLTQ2MGMxMmU3NjEzMiIsImlwZGl0YyI6ZmFsc2UsInBzdCI6IkRpcmVjdFJvdXRpbmcifSwicnRpZCI6IjQ6KzQyMDcyNTIxMDM0NCIsInJ0cGZzIjp7fX0%253D&i=10-60-1-10&e=638449233857537186" }, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "countryCode": null, "endpointType": 0, "identity": { "additionalData": { "phone": { "@odata.type": "#microsoft.graph.identity", "id": "+420725210344", "displayName": "+420725210344", "identityProvider": "None" } }, "application": null, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "device": null, "odataType": "#microsoft.graph.identitySet", "user": null }, "languageId": null, "odataType": "#microsoft.graph.participantInfo", "participantId": "6225db03-cbab-46d8-adbb-8268db8a249f", "region": null }, "isInLobby": false, "isMuted": false, "mediaStreams": [ { "additionalData": {}, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "direction": 3, "label": "main-audio", "mediaType": 0, "odataType": "#microsoft.graph.mediaStream", "serverMuted": false, "sourceId": "201" } ], "metadata": null, "recordingInfo": null, "removedState": null, "restrictedExperience": null, "rosterSequenceNumber": 10, "additionalData": { "publishedStates": [], "meetingRole": "none", "replacementLink": "https://cc-euno-05-prod-aks.cc.skype.com/cc/v1/callParticipant/44fa855e-85bf-463a-863f-c522b8941888/13/k3/414/replacement?rt=6225db03cbab46d8adbb8268db8a249f&rc=eyJydGxicmMiOnsiZXRpZCI6ImYzYmEwMzcyLTJmYTEtNDRlMy05ZmUzLTQ2MGMxMmU3NjEzMiIsImlwZGl0YyI6ZmFsc2UsInBzdCI6IkRpcmVjdFJvdXRpbmcifSwicnRpZCI6IjQ6KzQyMDcyNTIxMDM0NCIsInJ0cGZzIjp7fX0%253D&i=10-60-1-10&e=638449233857537186", "isIdentityAnonymized": "False" }, "backingStore": { "initializationCompleted": false, "returnOnlyChangedValues": false }, "id": "6225db03-cbab-46d8-adbb-8268db8a249f", "odataType": "#microsoft.graph.participant" } ], "sequenceNumber": 10, "additionalData": {}, "backingStore": { "initializationCompleted": true, "returnOnlyChangedValues": false }, "id": null, "odataType": "#microsoft.graph.deltaParticipants" } ] } ] },REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.1269536+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Updating collection notification with resource data, NotificationId: 8b9baa1c-30b0-47f9-9ffc-86c9e93f66d2, Notification ResourceUrl: /communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.1318277+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:50.1366612+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Microsoft.Graph.Communications.Calls.IParticipant collection notification with 3 elements received for Updated: /communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/participants,REQID:,TS:3/6/2024 10:07:50 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 06 10:07:50,131: 186 : CtiTeamsServiceCore.ProcessCallNotificationAsync: Callback response: StatusCode: 202, ReasonPhrase: 'Accepted', Version: 1.1, Content: System.Net.Http.EmptyContent, Headers: { Client-Request-Id: d4cf6cd3-fe6d-4b5d-9ff6-36a2ccb32e0a Scenario-Id: 7581aca0-768a-4fde-abce-127eb83311e6 } 06 10:07:50,139: 190 : CallSession.OnParticipantChanged: Participant changed. ID:6225db03-cbab-46d8-adbb-8268db8a249f, OID:+420725210344, DN:+420725210344 06 10:07:50,148: 190 : CallSession.UpdateConnectionsParticipant: P:ID:6225db03-cbab-46d8-adbb-8268db8a249f, OID:+420725210344, DN:+420725210344 2024-03-06T10:07:51.2805552+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Scenario ID extracted from Scenario-Id is: 7581aca0-768a-4fde-abce-127eb83311e6,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.2869066+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Request ID extracted from X-Microsoft-Skype-Message-ID is: a7964abc-8f49-4dcd-b825-c9e24293b6e3,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.2919166+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Scenario ID extracted from Scenario-Id is: 7581aca0-768a-4fde-abce-127eb83311e6,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.2972753+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Authenticating inbound request (a7964abc-8f49-4dcd-b825-c9e24293b6e3): https://teamscti.kordina.cz/callnotify,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.3020221+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Request ID extracted from X-Microsoft-Skype-Message-ID is: 492dcb12-4c2b-4268-a8e9-e60a9315625c,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.3076926+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Authenticating inbound request (492dcb12-4c2b-4268-a8e9-e60a9315625c): https://teamscti.kordina.cz/callnotify,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.3147465+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Queue found for resource communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3, current queue size 7 with id communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.3394665+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Processing operation notification with id: 2e449342-c490-4fd5-9e24-ca957e41dc17,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.3533760+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:{ "value": [ { "changeType": "Updated", "resourceUrl": "/communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3", "resource": "/app/calls/491f6000-df6f-4347-a750-8249c52fe3f3", "resourceData": { "audioRoutingGroups": null, "callbackUri": null, "callChainId": "7581aca0-768a-4fde-abce-127eb83311e6", "callOptions": null, "callRoutes": null, "chatInfo": null, "contentSharingSessions": null, "direction": null, "incomingContext": null, "mediaConfig": null, "mediaState": { "additionalData": {}, "audio": 0, "backingStore": { "initializationCompleted": true, "returnOnlyChangedValues": false }, "odataType": "#microsoft.graph.callMediaState" }, "meetingInfo": { "additionalData": {}, "backingStore": { "initializationCompleted": true, "returnOnlyChangedValues": false }, "odataType": "#microsoft.graph.tokenMeetingInfo" }, "myParticipantId": null, "operations": null, "participants": null, "requestedModalities": null, "resultInfo": null, "source": null, "state": 2, "subject": null, "targets": null, "tenantId": null, "toneInfo": null, "transcription": null, "additionalData": { "meetingCapability": { "@odata.type": "#microsoft.graph.meetingCapability", "allowTranslatedCaptions": true, "allowTranslatedTranscriptions": true, "recorderAllowed": [ "Attendee" ] }, "meetingProperties": { "@odata.type": "#microsoft.graph.meetingProperties", "meetingLabel": "00000000-0000-0000-0000-000000000000" }, "coOrganizers": [], "replacesContext": "aHR0cHM6Ly9jYy1ldW5vLTA1LXByb2QtYWtzLmNjLnNreXBlLmNvbS9jYy92MS9jYWxsUGFydGljaXBhbnQvNDRmYTg1NWUtODViZi00NjNhLTg2M2YtYzUyMmI4OTQxODg4LzEzL2szLzQxNC9yZXBsYWNlbWVudD9ydD02MjI1ZGIwM2NiYWI0NmQ4YWRiYjgyNjhkYjhhMjQ5ZiZyYz1leUp5ZEd4aWNtTWlPbnNpWlhScFpDSTZJbVl6WW1Fd016Y3lMVEptWVRFdE5EUmxNeTA1Wm1VekxUUTJNR014TW1VM05qRXpNaUlzSW1sd1pHbDBZeUk2Wm1Gc2MyVXNJbkJ6ZENJNklrUnBjbVZqZEZKdmRYUnBibWNpZlN3aWNuUnBaQ0k2SWpRNkt6UXlNRGN5TlRJeE1ETTBOQ0lzSW5KMGNHWnpJanA3ZlgwJTI1M0QmaT0xMC02MC0xLTEwJmU9NjM4NDQ5MjMzODU3NTM3MTg2" }, "backingStore": { "initializationCompleted": true, "returnOnlyChangedValues": false }, "id": null, "odataType": "#microsoft.graph.call" } } ] },REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.3626055+00:00 WARN [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Operation with context 7a4f5731-d739-4f34-82cb-6a78b39145e6 is not found. Ignoring request.,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.3668352+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.3739597+00:00 WARN [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:No queue or registered callbacks for Deleted: /communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/operations/2e449342-c490-4fd5-9e24-ca957e41dc17,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 06 10:07:51,373: 188 : CtiTeamsServiceCore.ProcessCallNotificationAsync: Callback response: StatusCode: 202, ReasonPhrase: 'Accepted', Version: 1.1, Content: System.Net.Http.EmptyContent, Headers: { Client-Request-Id: a7964abc-8f49-4dcd-b825-c9e24293b6e3 Scenario-Id: 7581aca0-768a-4fde-abce-127eb83311e6 } 2024-03-06T10:07:51.3858951+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:{ "value": [ { "changeType": "Deleted", "resourceUrl": "/communications/calls/491f6000-df6f-4347-a750-8249c52fe3f3/operations/2e449342-c490-4fd5-9e24-ca957e41dc17", "resource": "/app/calls/491f6000-df6f-4347-a750-8249c52fe3f3/operations/2e449342-c490-4fd5-9e24-ca957e41dc17", "resourceData": { "clientContext": "7a4f5731-d739-4f34-82cb-6a78b39145e6", "resultInfo": null, "status": 2, "additionalData": {}, "backingStore": { "initializationCompleted": true, "returnOnlyChangedValues": false }, "id": "2e449342-c490-4fd5-9e24-ca957e41dc17", "odataType": "#microsoft.graph.startHoldMusicOperation" } } ] },REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 2024-03-06T10:07:51.3916325+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:,REQID:,TS:3/6/2024 10:07:51 AM,PID:7176,CORRID:7581aca0-768a-4fde-abce-127eb83311e6 06 10:07:51,392: 200 : CtiTeamsServiceCore.ProcessCallNotificationAsync: Callback response: StatusCode: 202, ReasonPhrase: 'Accepted', Version: 1.1, Content: System.Net.Http.EmptyContent, Headers: { Client-Request-Id: 492dcb12-4c2b-4268-a8e9-e60a9315625c Scenario-Id: 7581aca0-768a-4fde-abce-127eb83311e6 } 06 10:07:54,333: 101 : StorageProvider.TokenValidation: Authenticated 31-70-AA-A4-49-EA-DF-0A-7A-8C-A4-BC-61-79-DF-4F-AE-0A-14-0F-0D-DE-27-48-59-4D-F4-9D-64-C9-74-33/8D-00-48-F0-25-A2-CA-E1-74-5E-AB-E4-6C-03-BF-03-CB-43-E7-5B-7C-EF-44-D4-26-5E-78-2F-03-4F-DF-D6/4A-0A-D6-E7-D2-AE-71-55-7B-30-7A-CF-39-F2-54-1F-B5-B2-CE-08-5F-B8-3B-72-12-9D-63-C0-7C-12-B6-EB form IP:::1 2024-03-06T10:07:54.3458983+00:00 INFO [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Pro.CtiTeamsService CallProcessor.TokenValidation SIM2-7182877886603146212-::1 V220 Proxy_Pro.Utils.Cti.Toolkit 161300f6-ed95-4f2b-af24-39e90ec7e855 'CtiTeamsServiceAgent' K=?,REQID:,TS:3/6/2024 10:07:54 AM,PID:7176,CORRID:00000000-0000-0000-0000-000000000000 2024-03-06T10:08:19.5977485+00:00 CRIT [Atlantis.Pro.CtiTeamsServiceCore.Common.GraphObserver] [0] MSG:Timed out while waiting for the operation with context 05d9886f-6419-46c3-b16a-9dc58b5137d8 to complete,REQID:,TS:3/6/2024 10:08:19 AM,PID:7176,CORRID:00000000-0000-0000-0000-000000000000