Hold operation throw timeout exception

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

Start with IParticipant.StartHoldMusicAsync and the supplied timeout log, then compare the reported behavior between Microsoft.Graph.Communications.Calls 1.2.0.10115 and the older 1.2.7270 libraries. Done means the timeout can be reproduced or ruled out with the stated PSTN call setup and its cause or next actionable finding is recorded.