search

microsoftgraph / microsoft-graph-comms-samples

Microsoft Graph Communications Samples
MIT License
208 stars 225 forks source link

AksSamples - Server Internal Error. DiagCode: 500#3003.@ #632

Closed joaquinodz closed 1 year ago

joaquinodz commented 1 year ago

Describe the issue I cannot add a bot to a meeting using /joinCall

Call ID 671f6600-14dc-4bb2-924e-24226a414f9d

Grapg SDK 1.2.0.5304

Logs If required, please add logs from the SDK. (Please remove any PII from the logs before uploading)

RecordingBot: booting
$>2023-03-20T15:43:53.9846974Z Info: CommunicationsClientBuilder.cs:134 Build
AppId: (redacted)
AppName: RecordingBot.Services
Assembly: GraphCommunicationsClient
$>2023-03-20T15:43:54.0394301Z Info: CommunicationsClientBuilder.cs:137 Build
AppId: (redacted)
AppName: RecordingBot.Services
Application: RecordingBot.Services
$>2023-03-20T15:43:54.0404286Z Info: CommunicationsClientBuilder.cs:140 Build
AppId: (redacted)
AppName: RecordingBot.Services
Product: GraphCommunicationsClient-RecordingBot.Services
$>2023-03-20T15:43:54.0404286Z Info: CommunicationsClientBuilder.cs:143 Build
AppId: (redacted)
AppName: RecordingBot.Services
Version: 1.2.0.5304
$>2023-03-20T15:43:54.0404286Z Info: CommunicationsClientBuilder.cs:147 Build
AppId: (redacted)
AppName: RecordingBot.Services
UserAgent: GraphCommunicationsClient-RecordingBot.Services/1.2.0.5304
$>2023-03-20T15:43:54.0874270Z Info: DispatcherContainer.cs:126 RegisterDispatcherGetOrAdd
AppId: (redacted)
AppName: RecordingBot.Services
Dispatcher Container registering notification dispatcher for: communications/calls
$>2023-03-20T15:43:54.1324285Z Info: NotificationDispatcher.cs:250 Subscribe
AppId: (redacted)
AppName: RecordingBot.Services
Subscribed: communications/calls
To queue: communications/calls
$>2023-03-20T15:43:54.1354320Z Info: AppHost.cs:124 StartServer
Listening on: https://+:443/
$>2023-03-20T15:43:54.1364280Z Info: AppHost.cs:124 StartServer
Listening on: https://+:443/0/
$>2023-03-20T15:43:54.1364280Z Info: AppHost.cs:124 StartServer
Listening on: http://+:444/
RecordingBot: running
$>2023-03-20T15:44:08.2386627Z Info: MediaSession.cs:243 CreateAudioSocket
AppId: (redacted)
AppName: RecordingBot.Services
MediaSessionId: fdb4551b-0fa9-4520-8fd8-b08ed740cca6
Creating audio socket for media session: fdb4551b-0fa9-4520-8fd8-b08ed740cca6
$>2023-03-20T15:44:08.8307982Z Info: MediaSession.cs:263 CreateVideoSocket
AppId: (redacted)
AppName: RecordingBot.Services
MediaSessionId: fdb4551b-0fa9-4520-8fd8-b08ed740cca6
Creating video socket for media session: fdb4551b-0fa9-4520-8fd8-b08ed740cca6
$>2023-03-20T15:44:09.0049800Z Info: MediaSession.cs:118 .ctor
AppId: (redacted)
AppName: RecordingBot.Services
Adding audio modality to media session.
$>2023-03-20T15:44:09.0069777Z Info: MediaSession.cs:124 .ctor
AppId: (redacted)
AppName: RecordingBot.Services
Adding video modality to media session.
$>2023-03-20T15:44:09.0099814Z Info: CallCollectionExtensions.cs:39 AddAsync
AppId: (redacted)
AppName: RecordingBot.Services
Creating a new call with Join Call Parameters
$>2023-03-20T15:44:09.0119797Z Info: StatefulCallCollection.cs:87 AddAsync
AppId: (redacted)
AppName: RecordingBot.Services
Media session was provided, starting as app hosted media call.
$>2023-03-20T15:44:09.0139789Z Info: MediaSession.cs:188 GetMediaConfiguration
AppId: (redacted)
AppName: RecordingBot.Services
MediaSessionId: fdb4551b-0fa9-4520-8fd8-b08ed740cca6
Getting media configuration:
AudioSocket=True
VideoSockets=1
VbssSocket=False
DataSocket=False
$>2023-03-20T15:44:09.1170445Z Info: MediaSession.cs:196 GetMediaConfiguration
AppId: (redacted)
AppName: RecordingBot.Services
MediaSessionId: fdb4551b-0fa9-4520-8fd8-b08ed740cca6
{
  "mpUri": "net.tcp://(redactedPublicFqdn):46441/MediaProcessor",
  "audioRenderContexts": [
    "908175f5-bc06-4276-85bc-2a6afd5cb6b2"
  ],
  "videoRenderContexts": [
    null
  ],
  "audioSourceContexts": [
    null
  ],
  "videoSourceContexts": [],
  "dataRenderContexts": null,
  "dataSourceContexts": null,
  "supportedAudioFormat": "Pcm16K",
  "videoSinkEncodingFormats": [
    "Yuv"
  ],
  "mpMediaSessionId": "9d1695bf-5c0e-465d-9213-3dadbd34b706",
  "regionAffinity": null,
  "skypeMediaBotsVersion": "1.20.0.0348",
  "mediaStackVersion": "2021.1.1.15",
  "mpVersion": "7.2.1.1483",
  "callId": "fdb4551b-0fa9-4520-8fd8-b08ed740cca6",
  "receiveUnmixedMeetingAudio": true
}
$>2023-03-20T15:44:09.1401011Z Info: StatefulCallCollection.cs:234 GetGraphClient
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Created new GraphLoggerShim for StatefulCall.
$>2023-03-20T15:44:09.1411105Z Info: StatefulCallCollection.cs:237 GetGraphClient
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Created new GraphAuthClient for StatefulCall.
$>2023-03-20T15:44:09.3925012Z Info: GraphAuthClient.cs:86 SendHttpRequestAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Authenticating request for tenant: (redacted)
$>2023-03-20T15:44:09.3925012Z Info: AuthenticationProvider.cs:112 AuthenticateOutboundRequestAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AuthenticationProvider: Generating OAuth token.
$>2023-03-20T15:44:10.2886438Z Info: AuthenticationProvider.cs:125 AuthenticateOutboundRequestAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AuthenticationProvider: Generated OAuth token. Expires in 59.9831167133333 minutes.
$>2023-03-20T15:44:10.4266924Z Info: GraphAuthClient.cs:108 SendHttpRequestAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
TransactionDirection: Outgoing
TraceType: HttpRequest
request: POST https://graph.microsoft.com/v1.0/communications/calls
headers:
  Scenario-Id: d3ca775c-487a-421c-a4bd-d64c914f84ab
  Client-Request-Id: 62c8c530-d17b-4757-8dc2-1a8d79eb6612
  User-Agent: GraphCommunicationsClient-RecordingBot.Services/1.2.0.5304
  Authorization: (redacted)
  Content-Type: application/json; charset=utf-8
{
  "@odata.type": "#microsoft.graph.call",
  "callOptions": {
    "$id": "1",
    "@odata.type": "#microsoft.graph.outgoingCallOptions",
    "allowGuestToBypassLobby": null
  },
  "callbackUri": "https://(redactedPublicFqdn)/0/api/calling/notification",
  "chatInfo": {
    "@odata.type": "#microsoft.graph.chatInfo",
    "messageId": "0",
    "threadId": "(redactedMeetingThreadId)"
  },
  "direction": "Outgoing",
  "mediaConfig": {
    "@odata.type": "#microsoft.graph.appHostedMediaConfig",
    "blob": "{\"mpUri\":\"net.tcp://(redactedPublicFqdn):46441/MediaProcessor\",\"audioRenderContexts\":[\"908175f5-bc06-4276-85bc-2a6afd5cb6b2\"],\"videoRenderContexts\":[null],\"audioSourceContexts\":[null],\"videoSourceContexts\":[],\"dataRenderContexts\":null,\"dataSourceContexts\":null,\"supportedAudioFormat\":\"Pcm16K\",\"videoSinkEncodingFormats\":[\"Yuv\"],\"mpMediaSessionId\":\"9d1695bf-5c0e-465d-9213-3dadbd34b706\",\"regionAffinity\":null,\"skypeMediaBotsVersion\":\"1.20.0.0348\",\"mediaStackVersion\":\"2021.1.1.15\",\"mpVersion\":\"7.2.1.1483\",\"callId\":\"fdb4551b-0fa9-4520-8fd8-b08ed740cca6\",\"receiveUnmixedMeetingAudio\":true}"
  },
  "meetingInfo": {
    "@odata.type": "#microsoft.graph.organizerMeetingInfo",
    "organizer": {
      "@odata.type": "#microsoft.graph.identitySet",
      "user": {
        "@odata.type": "#microsoft.graph.identity",
        "tenantId": "(redacted)",
        "id": "(redacted)"
      }
    }
  },
  "requestedModalities": [
    "Audio",
    "Video"
  ],
  "source": {
    "@odata.type": "#microsoft.graph.participantInfo",
    "identity": {
      "@odata.type": "#microsoft.graph.identitySet",
      "application": {
        "@odata.type": "#microsoft.graph.identity",
        "displayName": "(Identity: rm67GHEa)",
        "id": "(redacted)"
      }
    }
  },
  "tenantId": "(redacted)"
}
$>2023-03-20T15:44:10.5364830Z Info: PlatformCallController.cs:99 OnNotificationRequestAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
Received HTTP POST, https://(redactedPublicFqdn)/0/api/calling/notification
Skipped publishing NotificationHTTPPOST events to Event Grid topic recordingbotevents - No topic key specified
$>2023-03-20T15:44:10.6605043Z Info: GraphLoggerExtensions.cs:59 ParseScenarioId
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Scenario ID extracted from Scenario-Id is: d3ca775c-487a-421c-a4bd-d64c914f84ab
$>2023-03-20T15:44:10.6625041Z Info: GraphLoggerExtensions.cs:37 ParseRequestId
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Request ID extracted from X-Microsoft-Skype-Message-ID is: 8ca0b8ae-f414-454f-9412-64038f5d9ef0
$>2023-03-20T15:44:10.6625041Z Info: CommunicationsClientExtensions.cs:50 ProcessNotificationAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Authenticating inbound request (8ca0b8ae-f414-454f-9412-64038f5d9ef0): https://(redactedPublicFqdn)/0/api/calling/notification
$>2023-03-20T15:44:10.7475009Z Info: GraphHttpClient.cs:316 LogResponse
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Namespace: HTTPMetrics
Name: PartnerHttpRequests
RawData: 76
Dimensions: System.Collections.Generic.Dictionary`2[System.String,System.String]
AddDefaultDimension: True
$>2023-03-20T15:44:10.7655691Z Info: GraphAuthClient.cs:161 SendHttpRequestAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
TransactionDirection: Outgoing
TraceType: HttpResponse
ResponseTime: 76
request: POST https://graph.microsoft.com/v1.0/communications/calls
response: 201 Created
headers:
  Transfer-Encoding: chunked
  Strict-Transport-Security: max-age=31536000
  request-id: 6d5901fc-44fa-4990-8f01-7b4ab0711fc4
  client-request-id: 62c8c530-d17b-4757-8dc2-1a8d79eb6612
  x-ms-ags-diagnostic: {"ServerInfo":{"DataCenter":"East US","Slice":"E","Ring":"5","ScaleUnit":"001","RoleInstance":"MN1PEPF0000C131"}}
  scenario-id: d3ca775c-487a-421c-a4bd-d64c914f84ab
  OData-Version: 4.0
  Date: Mon, 20 Mar 2023 15:44:09 GMT
  Location: https://graph.microsoft.com/v1.0/communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d
  Content-Type: application/json; odata.metadata=minimal; odata.streaming=true; IEEE754Compatible=false; charset=utf-8
{
  "@odata.type": "#microsoft.graph.call",
  "@odata.context": "https://graph.microsoft.com/v1.0/$metadata#communications/calls/$entity",
  "transferState": "",
  "terminationReason": "",
  "ringingTimeoutInSeconds": "",
  "activeModalities": [],
  "routingPolicies": [],
  "mediaHostedRegion": "",
  "answeredBy": "",
  "meetingCapability": "",
  "meetingProperties": "",
  "breakoutDetails": "",
  "pstnDetails": "",
  "coOrganizers": [],
  "terminationSender": "",
  "mediaGroupDetails": "",
  "recordingConsentDetails": "",
  "callbackUri": "https://(redactedPublicFqdn)/0/api/calling/notification",
  "callChainId": "58b6e2d6-2bf9-4f64-aa1e-ab5e0ec8cfb4",
  "callOptions": {
    "@odata.type": "#microsoft.graph.outgoingCallOptions",
    "allowGuestToBypassLobby": ""
  },
  "callRoutes": [],
  "chatInfo": {
    "@odata.type": "#microsoft.graph.chatInfo",
    "messageId": "0",
    "threadId": "(redactedMeetingThreadId)"
  },
  "direction": "Outgoing",
  "mediaConfig": {
    "@odata.type": "#microsoft.graph.appHostedMediaConfig",
    "blob": "{\"mpUri\":\"net.tcp://(redactedPublicFqdn):46441/MediaProcessor\",\"audioRenderContexts\":[\"908175f5-bc06-4276-85bc-2a6afd5cb6b2\"],\"videoRenderContexts\":[null],\"audioSourceContexts\":[null],\"videoSourceContexts\":[],\"dataRenderContexts\":null,\"dataSourceContexts\":null,\"supportedAudioFormat\":\"Pcm16K\",\"videoSinkEncodingFormats\":[\"Yuv\"],\"mpMediaSessionId\":\"9d1695bf-5c0e-465d-9213-3dadbd34b706\",\"regionAffinity\":null,\"skypeMediaBotsVersion\":\"1.20.0.0348\",\"mediaStackVersion\":\"2021.1.1.15\",\"mpVersion\":\"7.2.1.1483\",\"callId\":\"fdb4551b-0fa9-4520-8fd8-b08ed740cca6\",\"receiveUnmixedMeetingAudio\":true}"
  },
  "meetingInfo": {
    "@odata.type": "#microsoft.graph.organizerMeetingInfo",
    "organizer": {
      "@odata.type": "#microsoft.graph.identitySet",
      "user": {
        "@odata.type": "#microsoft.graph.identity",
        "tenantId": "(redacted)",
        "id": "(redacted)"
      },
      "phone": "",
      "guest": "",
      "encrypted": "",
      "onPremises": "",
      "applicationInstance": ""
    },
    "allowConversationWithoutHost": "",
    "lobbyBypass": "",
    "joinGreenRoom": "",
    "organizerInfo": ""
  },
  "myParticipantId": "df8a43cb-fa19-407d-9951-842ed17640eb",
  "requestedModalities": [
    "Audio",
    "Video"
  ],
  "source": {
    "@odata.type": "#microsoft.graph.participantInfo",
    "identity": {
      "@odata.type": "#microsoft.graph.identitySet",
      "application": {
        "@odata.type": "#microsoft.graph.identity",
        "tenantId": "",
        "displayName": "(Identity: rm67GHEa)",
        "id": "(redacted)"
      },
      "phone": "",
      "guest": "",
      "encrypted": "",
      "onPremises": "",
      "applicationInstance": ""
    }
  },
  "state": "Establishing",
  "targets": [],
  "tenantId": "(redacted)",
  "id": "671f6600-14dc-4bb2-924e-24226a414f9d"
}
$>2023-03-20T15:44:10.7695774Z Info: GraphHttpClient.cs:342 LogResponse
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Namespace: HTTPMetrics
Name: PartnerConnectionsClosed
RawData: 1
Dimensions: System.Collections.Generic.Dictionary`2[System.String,System.String]
AddDefaultDimension: True
$>2023-03-20T15:44:10.7695774Z Info: GraphAuthClient.cs:173 SendHttpRequestAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Found Location Header: https://graph.microsoft.com/v1.0/communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d
$>2023-03-20T15:44:10.7695774Z Info: StatefulCallCollection.cs:234 GetGraphClient
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
CallId: 671f6600-14dc-4bb2-924e-24226a414f9d
TenantId: (redacted)
AppId: (redacted)
AppName: RecordingBot.Services
Created new GraphLoggerShim for StatefulCall.
$>2023-03-20T15:44:10.7695774Z Info: StatefulCallCollection.cs:237 GetGraphClient
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
CallId: 671f6600-14dc-4bb2-924e-24226a414f9d
TenantId: (redacted)
AppId: (redacted)
AppName: RecordingBot.Services
Created new GraphAuthClient for StatefulCall.
$>2023-03-20T15:44:10.8012428Z Info: NotificationDispatcher.cs:250 Subscribe
AppId: (redacted)
AppName: RecordingBot.Services
Subscribed: communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d
To queue: communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d
$>2023-03-20T15:44:10.8012428Z Info: NotificationDispatcher.cs:250 Subscribe
AppId: (redacted)
AppName: RecordingBot.Services
Subscribed: communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d/participants
To queue: communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d
$>2023-03-20T15:44:10.8012428Z Info: NotificationDispatcher.cs:250 Subscribe
AppId: (redacted)
AppName: RecordingBot.Services
Subscribed: communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d/audioRoutingGroups
To queue: communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d
$>2023-03-20T15:44:10.8012428Z Info: NotificationDispatcher.cs:250 Subscribe
AppId: (redacted)
AppName: RecordingBot.Services
Subscribed: communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d/contentSharingSessions
To queue: communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d
$>2023-03-20T15:44:10.8012428Z Info: BotService.cs:186 JoinCallAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
CallId: 671f6600-14dc-4bb2-924e-24226a414f9d
TenantId: (redacted)
AppId: (redacted)
AppName: RecordingBot.Services
Call creation complete: 671f6600-14dc-4bb2-924e-24226a414f9d
Skipped publishing JoinCall events to Event Grid topic recordingbotevents - No topic key specified
$>2023-03-20T15:44:10.9602116Z Info: AuthenticationProvider.cs:151 ValidateInboundRequestAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
Updating OpenID configuration
$>2023-03-20T15:44:11.6417535Z Info: NotificationDispatcher.cs:187 ProcessNotification
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Queue found for resource communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d, current queue size 4 with id communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d
$>2023-03-20T15:44:11.6573966Z Info: CommunicationsClientExtensions.cs:156 LogAndCreateResponse
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
TransactionDirection: Incoming
TraceType: HttpRequest
request: POST https://(redactedPublicFqdn)/0/api/calling/notification
headers:
  X-Microsoft-Skype-Chain-ID: 58b6e2d6-2bf9-4f64-aa1e-ab5e0ec8cfb4
  Scenario-Id: d3ca775c-487a-421c-a4bd-d64c914f84ab
  X-Microsoft-Skype-Message-ID: 8ca0b8ae-f414-454f-9412-64038f5d9ef0
  Connection: Keep-Alive
  Accept: application/json
  Authorization: (redacted)
  Host: (redactedPublicFqdn)
  User-Agent: Microsoft-Skype/3.0,(Calling/1.0)
  Content-Length: 834
  Content-Type: application/json
{
  "@odata.type": "#microsoft.graph.commsNotifications",
  "value": [
    {
      "@odata.type": "#microsoft.graph.commsNotification",
      "changeType": "Updated",
      "resourceUrl": "/communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d",
      "resource": "/app/calls/671f6600-14dc-4bb2-924e-24226a414f9d",
      "resourceData": {
        "@odata.type": "#microsoft.graph.call",
        "callChainId": "58b6e2d6-2bf9-4f64-aa1e-ab5e0ec8cfb4",
        "chatInfo": {
          "@odata.type": "#microsoft.graph.chatInfo",
          "messageId": "0",
          "threadId": "(redactedMeetingThreadId)"
        },
        "meetingInfo": {
          "@odata.type": "#microsoft.graph.organizerMeetingInfo",
          "organizer": {
            "@odata.type": "#microsoft.graph.identitySet",
            "user": {
              "@odata.type": "#microsoft.graph.identity",
              "tenantId": "(redacted)",
              "id": "(redacted)"
            }
          }
        },
        "state": "Establishing"
      }
    }
  ]
}
$>2023-03-20T15:44:11.6573966Z Info: CommunicationsClientExtensions.cs:158 LogAndCreateResponse
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
TransactionDirection: Incoming
TraceType: HttpResponse
ResponseTime: 1002
request: POST https://(redactedPublicFqdn)/0/api/calling/notification
response: 202 Accepted
headers:
  Client-Request-Id: 8ca0b8ae-f414-454f-9412-64038f5d9ef0
  Scenario-Id: d3ca775c-487a-421c-a4bd-d64c914f84ab
$>2023-03-20T15:44:11.6734287Z Info: CallHandler.cs:179 CallOnUpdated
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
CallId: 671f6600-14dc-4bb2-924e-24226a414f9d
TenantId: (redacted)
AppId: (redacted)
AppName: RecordingBot.Services
Call status updated to Establishing -
Skipped publishing CallEstablishing events to Event Grid topic recordingbotevents - No topic key specified
$>2023-03-20T15:44:12.8806707Z Info: PlatformCallController.cs:99 OnNotificationRequestAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
Received HTTP POST, https://(redactedPublicFqdn)/0/api/calling/notification
Skipped publishing NotificationHTTPPOST events to Event Grid topic recordingbotevents - No topic key specified
$>2023-03-20T15:44:12.8816729Z Info: GraphLoggerExtensions.cs:59 ParseScenarioId
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Scenario ID extracted from Scenario-Id is: d3ca775c-487a-421c-a4bd-d64c914f84ab
$>2023-03-20T15:44:12.8826707Z Info: GraphLoggerExtensions.cs:37 ParseRequestId
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Request ID extracted from X-Microsoft-Skype-Message-ID is: b0e1da6b-22c0-44c3-844b-694ce579c4c0
$>2023-03-20T15:44:12.8826707Z Info: CommunicationsClientExtensions.cs:50 ProcessNotificationAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Authenticating inbound request (b0e1da6b-22c0-44c3-844b-694ce579c4c0): https://(redactedPublicFqdn)/0/api/calling/notification
$>2023-03-20T15:44:12.9209886Z Info: NotificationDispatcher.cs:187 ProcessNotification
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Queue found for resource communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d, current queue size 4 with id communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d
$>2023-03-20T15:44:12.9310012Z Info: CallHandler.cs:179 CallOnUpdated
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
CallId: 671f6600-14dc-4bb2-924e-24226a414f9d
TenantId: (redacted)
AppId: (redacted)
AppName: RecordingBot.Services
Call status updated to Terminated - Server Internal Error. DiagCode: 500#3003.@
$>2023-03-20T15:44:12.9310012Z Info: CommunicationsClientExtensions.cs:156 LogAndCreateResponse
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
TransactionDirection: Incoming
TraceType: HttpRequest
request: POST https://(redactedPublicFqdn)/0/api/calling/notification
headers:
  X-Microsoft-Skype-Chain-ID: 58b6e2d6-2bf9-4f64-aa1e-ab5e0ec8cfb4
  Scenario-Id: d3ca775c-487a-421c-a4bd-d64c914f84ab
  X-Microsoft-Skype-Message-ID: b0e1da6b-22c0-44c3-844b-694ce579c4c0
  Accept: application/json
  Authorization: (redacted)
  Host: (redactedPublicFqdn)
  User-Agent: Microsoft-Skype/3.0,(Calling/1.0)
  Content-Length: 973
  Content-Type: application/json
{
  "@odata.type": "#microsoft.graph.commsNotifications",
  "value": [
    {
      "@odata.type": "#microsoft.graph.commsNotification",
      "changeType": "Deleted",
      "resourceUrl": "/communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d",
      "resource": "/app/calls/671f6600-14dc-4bb2-924e-24226a414f9d",
      "resourceData": {
        "@odata.type": "#microsoft.graph.call",
        "callChainId": "58b6e2d6-2bf9-4f64-aa1e-ab5e0ec8cfb4",
        "chatInfo": {
          "@odata.type": "#microsoft.graph.chatInfo",
          "messageId": "0",
          "threadId": "(redactedMeetingThreadId)"
        },
        "meetingInfo": {
          "@odata.type": "#microsoft.graph.organizerMeetingInfo",
          "organizer": {
            "@odata.type": "#microsoft.graph.identitySet",
            "user": {
              "@odata.type": "#microsoft.graph.identity",
              "tenantId": "(redacted)",
              "id": "(redacted)"
            }
          }
        },
        "resultInfo": {
          "@odata.type": "#microsoft.graph.resultInfo",
          "code": 500,
          "message": "Server Internal Error. DiagCode: 500#3003.@",
          "subcode": 3003
        },
        "state": "Terminated",
        "tenantId": "(redacted)"
      }
    }
  ]
}
Skipped publishing CallTerminated events to Event Grid topic recordingbotevents - No topic key specified
$>2023-03-20T15:44:12.9310012Z Info: CommunicationsClientExtensions.cs:158 LogAndCreateResponse
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
TransactionDirection: Incoming
TraceType: HttpResponse
ResponseTime: 56
request: POST https://(redactedPublicFqdn)/0/api/calling/notification
response: 202 Accepted
headers:
  Client-Request-Id: b0e1da6b-22c0-44c3-844b-694ce579c4c0
  Scenario-Id: d3ca775c-487a-421c-a4bd-d64c914f84ab
Skipped publishing MediaStreamDispose events to Event Grid topic recordingbotevents - No topic key specified
Skipped publishing CallDisposedOK events to Event Grid topic recordingbotevents - No topic key specified
RecordingBot.Console.exe Information: 0 : RecordBatcher configuration maximalRecordBatchSizeInBytes: 2935622, MaxRecordsInBatchNum: 10000, MaxBatchingWaitingThreshold: 00:00:15, SingleWaitingOnDequeueDurationWhenBatching: 00:00:00.0500000
$>2023-03-20T15:44:13.1107372Z Info: NotificationQueue.cs:378 StartLoopAsync
ScenarioId: d3ca775c-487a-421c-a4bd-d64c914f84ab
AppId: (redacted)
AppName: RecordingBot.Services
Notification queue communications/calls/671f6600-14dc-4bb2-924e-24226a414f9d is stopped.

Additional context I've set up a lab Azure VM with firewall disabled and a nsg rule that allows any ip of any port to any protocol (i know it is not recommended but i'm trying to rule out firewall connectivity issues) I also have my domain with a CNAME record pointing to my VMs fwdn I also have a valid SSL certificate imported to the Local Computer Store

joaquinodz commented 1 year ago

I made it work by setting the PublicInstancePort = PrivateInstancePort