Latest huebot samples fail to work

[mahimsd7]

I was able to successfully run the samples locally using ngrok untill the commit 48afbc9745bc5326fe96964b8fa5e977a6901cfc

Now i tried to run the latest sample, on the same environment , the call never gets to established state.

the result info says "resultInfo": { "@odata.type": "#microsoft.graph.resultInfo", "code": 500, "subCode": 7112

Can you please let me know what could be the issue.

Please see the logs $>2019-07-02T10:36:31.1054096Z Info: CommunicationsClientExtensions.cs:230 LogRequestAndCreateResponse ScenarioId: 8f2ccf1d-0882-477c-b486-03a8eff290a0 AppId: 3a6fc5cc-fc36-4fcd-b84e-8978b475b3a7 AppName: HueBot TransactionDirection: Incoming TraceType: HttpResponse ResponseTime: 10 request: POST http://bc0c86c4.ngrok.io/api/calls response: 202 Accepted headers: client-request-id: b5cb158e-6cf1-429c-a7e6-67f360012b99 scenario-id: 8f2ccf1d-0882-477c-b486-03a8eff290a0 $>2019-07-02T10:36:31.1044090Z Info: CommunicationsClientExtensions.cs:220 LogRequestAndCreateResponse ScenarioId: 8f2ccf1d-0882-477c-b486-03a8eff290a0 AppId: 3a6fc5cc-fc36-4fcd-b84e-8978b475b3a7 AppName: HueBot TransactionDirection: Incoming TraceType: HttpRequest request: POST http://bc0c86c4.ngrok.io/api/calls headers: Accept: application/json Authorization: (redacted) Host: bc0c86c4.ngrok.io User-Agent: Microsoft-Skype/3.0,(Calling/1.0) X-Microsoft-Skype-Chain-ID: 68e885fa-0d10-4917-bb81-15c1d3da9b64 Scenario-ID: 8f2ccf1d-0882-477c-b486-03a8eff290a0 X-Microsoft-Skype-Message-ID: b5cb158e-6cf1-429c-a7e6-67f360012b99 X-Forwarded-Proto: (redacted) X-Forwarded-For: (redacted) { "@odata.type": "#microsoft.graph.commsNotifications", "value": [ { "@odata.type": "#microsoft.graph.commsNotification", "changeType": "deleted", "resource": "/app/calls/411f0f00-8e47-4280-8c7f-dcda16183005", "resourceData": { "@odata.type": "#microsoft.graph.call", "state": "terminated", "resultInfo": { "@odata.type": "#microsoft.graph.resultInfo", "code": 500, "subCode": 7112 }, "chatInfo": { "@odata.type": "#microsoft.graph.chatInfo", "threadId": "19:6134969b58b8489b8aa929a0ac742124@thread.skype", "messageId": "1559125740176" }, "meetingInfo": { "@odata.type": "#microsoft.graph.organizerMeetingInfo", "organizer": { "@odata.type": "#microsoft.graph.identitySet", "user": { "@odata.type": "#microsoft.graph.identity", "id": "56948aac-f602-4ee7-945c-bdc8005e020c", "tenantId": "(Generic: AXnyF/e8)" } } }, "tenantId": "14949186-abf3-4496-aff3-68cd6465815a", "id": "411f0f00-8e47-4280-8c7f-dcda16183005" } } ] } $>2019-07-02T10:35:48.9118905Z Info: CommunicationsClientExtensions.cs:230 LogRequestAndCreateResponse ScenarioId: 8f2ccf1d-0882-477c-b486-03a8eff290a0 AppId: 3a6fc5cc-fc36-4fcd-b84e-8978b475b3a7 AppName: HueBot TransactionDirection: Incoming TraceType: HttpResponse ResponseTime: 1295 request: POST http://bc0c86c4.ngrok.io/api/calls response: 202 Accepted headers: client-request-id: 8e624543-9149-4641-bb90-1cf502e48f26 scenario-id: 8f2ccf1d-0882-477c-b486-03a8eff290a0 $>2019-07-02T10:35:48.9108891Z Info: CommunicationsClientExtensions.cs:220 LogRequestAndCreateResponse ScenarioId: 8f2ccf1d-0882-477c-b486-03a8eff290a0 AppId: 3a6fc5cc-fc36-4fcd-b84e-8978b475b3a7 AppName: HueBot TransactionDirection: Incoming TraceType: HttpRequest request: POST http://bc0c86c4.ngrok.io/api/calls headers: Accept: application/json Authorization: (redacted) Host: bc0c86c4.ngrok.io User-Agent: Microsoft-Skype/3.0,(Calling/1.0) X-Microsoft-Skype-Chain-ID: 68e885fa-0d10-4917-bb81-15c1d3da9b64 Scenario-ID: 8f2ccf1d-0882-477c-b486-03a8eff290a0 X-Microsoft-Skype-Message-ID: 8e624543-9149-4641-bb90-1cf502e48f26 X-Forwarded-Proto: (redacted) X-Forwarded-For: (redacted) { "@odata.type": "#microsoft.graph.commsNotifications", "value": [ { "@odata.type": "#microsoft.graph.commsNotification", "changeType": "updated", "resource": "/app/calls/411f0f00-8e47-4280-8c7f-dcda16183005", "resourceData": { "@odata.type": "#microsoft.graph.call", "state": "establishing", "chatInfo": { "@odata.type": "#microsoft.graph.chatInfo", "threadId": "19:6134969b58b8489b8aa929a0ac742124@thread.skype", "messageId": "1559125740176" }, "meetingInfo": { "@odata.type": "#microsoft.graph.organizerMeetingInfo", "organizer": { "@odata.type": "#microsoft.graph.identitySet", "user": { "@odata.type": "#microsoft.graph.identity", "id": "56948aac-f602-4ee7-945c-bdc8005e020c", "tenantId": "(Generic: AXnyF/e8)" } } }, "id": "411f0f00-8e47-4280-8c7f-dcda16183005" } } ] } $>2019-07-02T10:35:48.9088914Z Info: GraphAuthClient.cs:144 SendHttpRequestAsync ScenarioId: 8f2ccf1d-0882-477c-b486-03a8eff290a0 AppId: 3a6fc5cc-fc36-4fcd-b84e-8978b475b3a7 AppName: HueBot TransactionDirection: Outgoing TraceType: HttpResponse ResponseTime: 1153 request: POST https://graph.microsoft.com/beta/app/calls response: 201 Created headers: Transfer-Encoding: chunked request-id: 940b848e-fb58-4627-a775-15aba7eeecf9 client-request-id: e88a12f9-fbcd-4f32-accb-2518603b975f x-ms-ags-diagnostic: {"ServerInfo":{"DataCenter":"West Europe","Slice":"SliceC","Ring":"5","ScaleUnit":"001","RoleInstance":"AGSFE_IN_46","ADSiteName":"WEU"}} scenario-id: 8f2ccf1d-0882-477c-b486-03a8eff290a0 OData-Version: 4.0 Duration: 998.6455 Strict-Transport-Security: max-age=31536000 Cache-Control: private Date: Tue, 02 Jul 2019 10:35:50 GMT Location: https://graph.microsoft.com/beta/app/calls/411f0f00-8e47-4280-8c7f-dcda16183005 Content-Type: application/json; odata.metadata=minimal; odata.streaming=true; IEEE754Compatible=false; charset=utf-8 { "@odata.type": "#microsoft.graph.call", "state": "establishing", "direction": "outgoing", "callbackUri": "https://bc0c86c4.ngrok.io/api/calls", "callRoutes": [], "source": { "@odata.type": "#microsoft.graph.participantInfo", "identity": { "@odata.type": "#microsoft.graph.identitySet", "application": { "@odata.type": "#microsoft.graph.identity", "displayName": "(Identity: yEEtim1j)", "id": "3a6fc5cc-fc36-4fcd-b84e-8978b475b3a7" }, "user": null, "device": null, "phone": null, "guest": { "@odata.type": "#microsoft.graph.identity", "displayName": "(Identity: qi35OirF)", "id": "4b3ec17d-16e7-465f-a1e4-f3121eee79d0" } }, "region": null, "languageId": null }, "targets": [], "requestedModalities": [ "audio", "video" ], "activeModalities": [], "mediaConfig": { "@odata.type": "#microsoft.graph.appHostedMediaConfig", "blob": "{\"mpUri\":\"net.tcp://0.poc.tmsxe0365.com:14204/MediaProcessor\",\"audioRenderContexts\":[\"12c90bc4-a9b0-452a-a78e-3cbd298e5735\"],\"videoRenderContexts\":[\"e244ca54-c68a-42ed-b8a1-2c94b407c6e7\"],\"audioSourceContexts\":[null],\"videoSourceContexts\":[\"67c840ee-04e7-4401-8d72-d609b1e6d080\"],\"dataRenderContexts\":null,\"dataSourceContexts\":null,\"supportedAudioFormat\":\"Pcm16K\",\"videoSinkEncodingFormats\":[\"Yuv\"],\"mpMediaSessionId\":\"9787d92b-3f7b-4278-a843-ac44d63b24d8\",\"regionAffinity\":null,\"skypeMediaBotsVersion\":\"1.13.1.0098\",\"mediaStackVersion\":\"2019.15.1.9\",\"mpVersion\":\"7.2.0.4679\",\"callId\":\"0d88ed05-6718-43a8-ad52-5f5812650bf0\"}", "removeFromDefaultAudioGroup": false }, "chatInfo": { "@odata.type": "#microsoft.graph.chatInfo", "threadId": "19:6134969b58b8489b8aa929a0ac742124@thread.skype", "messageId": "1559125740176", "replyChainMessageId": null }, "meetingInfo": { "@odata.type": "#microsoft.graph.organizerMeetingInfo", "organizer": { "@odata.type": "#microsoft.graph.identitySet", "user": { "@odata.type": "#microsoft.graph.identity", "id": "56948aac-f602-4ee7-945c-bdc8005e020c", "displayName": null, "tenantId": "(Generic: AXnyF/e8)" }, "application": null, "device": null, "phone": null }, "allowConversationWithoutHost": null, "lobbyBypass": null }, "routingPolicies": [], "tenantId": "14949186-abf3-4496-aff3-68cd6465815a", "myParticipantId": "2e3c48d6-432f-4a61-b623-f87ace840190", "id": "411f0f00-8e47-4280-8c7f-dcda16183005", "@odata.context": "https://graph.microsoft.com/beta/$metadata#app/calls/$entity", "transferState": null, "terminationReason": null, "ringingTimeoutInSeconds": null, "subject": null, "mediaState": null, "resultInfo": null, "answeredBy": null, "meetingCapability": null, "toneInfo": null } $>2019-07-02T10:35:48.9028863Z Warning: NotificationDispatcher.cs:171 ProcessNotification ScenarioId: 8f2ccf1d-0882-477c-b486-03a8eff290a0 AppId: 3a6fc5cc-fc36-4fcd-b84e-8978b475b3a7 AppName: HueBot No queue or registered callbacks for Updated: /app/calls/411f0f00-8e47-4280-8c7f-dcda16183005 $>2019-07-02T10:35:45.9259399Z Info: GraphAuthClient.cs:101 SendHttpRequestAsync ScenarioId: 8f2ccf1d-0882-477c-b486-03a8eff290a0 AppId: 3a6fc5cc-fc36-4fcd-b84e-8978b475b3a7 AppName: HueBot TransactionDirection: Outgoing TraceType: HttpRequest request: POST https://graph.microsoft.com/beta/app/calls headers: scenario-id: 8f2ccf1d-0882-477c-b486-03a8eff290a0 client-request-id: e88a12f9-fbcd-4f32-accb-2518603b975f User-Agent: GraphCommunicationsClient-HueBot/1.1.0.581 Authorization: (redacted) Content-Type: application/json; charset=utf-8 { "@odata.type": "#microsoft.graph.call", "direction": "outgoing", "callbackUri": "https://bc0c86c4.ngrok.io/api/calls", "source": { "@odata.type": "#microsoft.graph.participantInfo", "identity": { "@odata.type": "#microsoft.graph.identitySet", "application": { "@odata.type": "#microsoft.graph.identity", "displayName": "(Identity: yEEtim1j)", "id": "3a6fc5cc-fc36-4fcd-b84e-8978b475b3a7" }, "guest": { "@odata.type": "#microsoft.graph.identity", "displayName": "(Identity: qi35OirF)", "id": "4b3ec17d-16e7-465f-a1e4-f3121eee79d0" } } }, "requestedModalities": [ "audio", "video" ], "mediaConfig": { "@odata.type": "#microsoft.graph.appHostedMediaConfig", "blob": "{\"mpUri\":\"net.tcp://0.poc.tmsxe0365.com:14204/MediaProcessor\",\"audioRenderContexts\":[\"12c90bc4-a9b0-452a-a78e-3cbd298e5735\"],\"videoRenderContexts\":[\"e244ca54-c68a-42ed-b8a1-2c94b407c6e7\"],\"audioSourceContexts\":[null],\"videoSourceContexts\":[\"67c840ee-04e7-4401-8d72-d609b1e6d080\"],\"dataRenderContexts\":null,\"dataSourceContexts\":null,\"supportedAudioFormat\":\"Pcm16K\",\"videoSinkEncodingFormats\":[\"Yuv\"],\"mpMediaSessionId\":\"9787d92b-3f7b-4278-a843-ac44d63b24d8\",\"regionAffinity\":null,\"skypeMediaBotsVersion\":\"1.13.1.0098\",\"mediaStackVersion\":\"2019.15.1.9\",\"mpVersion\":\"7.2.0.4679\",\"callId\":\"0d88ed05-6718-43a8-ad52-5f5812650bf0\"}", "removeFromDefaultAudioGroup": false }, "chatInfo": { "@odata.type": "#microsoft.graph.chatInfo", "threadId": "19:6134969b58b8489b8aa929a0ac742124@thread.skype", "messageId": "1559125740176" }, "meetingInfo": { "@odata.type": "#microsoft.graph.organizerMeetingInfo", "organizer": { "@odata.type": "#microsoft.graph.identitySet", "user": { "@odata.type": "#microsoft.graph.identity", "id": "56948aac-f602-4ee7-945c-bdc8005e020c", "tenantId": "(Generic: AXnyF/e8)" } } }, "tenantId": "14949186-abf3-4496-aff3-68cd6465815a" }

[ksikorsk]

Our service cannot connect to your media endpoint: HTTP/1.1 500 X-Microsoft-Skype-Processing-Instance: Microsoft.Skype.MediaController.FrontEnd_IN_1 X-Microsoft-Skype-Message-ID: bb6c8bef-146f-4204-b8a6-9348bb485ebe X-Microsoft-Skype-Chain-ID: 68e885fa-0d10-4917-bb81-15c1d3da9b64 Date: Tue, 02 Jul 2019 10:36:29 GMT Server: Microsoft-HTTPAPI/2.0 Content-Length: 205 Content-Type: application/json; charset=utf-8 { "addParticipantResponse": null, "debugContent": { "correlationId": "68e885fa-0d10-4917-bb81-15c1d3da9b64" }, "reason": "MP resources creation failure", "mpUri": "net.tcp://0.poc.tmsxe0365.com:14204/MediaProcessor" }

Can you check if your media port matches the ngrok media port?

[mahimsd7]

@ksikorsk Thank you , I didnt realized the port changed from 8000 in the previous sample to 8445 in the new one

[ksikorsk]

Yes, sorry about this. I made all the samples try to use consistent ports for media/http/https.

[MickH3]

@ksikorsk I'm getting the same 500 error with code 7112

Logs below... I'm seeing the TCP connection being attempted about 3-4 times. It connects and drops 3-4 times before sending the below payload telling me the call was terminated.

I've confirmed the ports are lined up on the media port. I'm using a self-signed cert with the domain *.tcp.ngrok.io. Could that be my problem?

{ "Timestamp": "2019-08-02T12:28:26.5026818-04:00", "ProviderName": "Sample.HueBot", "Id": 1, "Message": "CommunicationsClient(CommunicationsClientExtensions.cs:220 LogRequestAndCreateResponse) 2019-08-02T16:28:26.5017637Z: { \"@odata.type\": \"#microsoft.graph.commsNotifications\", \"value\": [ { \"@odata.type\": \"#microsoft.graph.commsNotification\", \"changeType\": \"deleted\", \"resource\": \"/app/calls/441f0400-431f-4c8c-b261-09e03ff64374\", \"resourceData\": { \"@odata.type\": \"#microsoft.graph.call\", \"state\": \"terminated\", \"resultInfo\": { \"@odata.type\": \"#microsoft.graph.resultInfo\", \"code\": 500, \"subCode\": 7112 }, \"chatInfo\": { \"@odata.type\": \"#microsoft.graph.chatInfo\", \"threadId\": \"19:meeting_ZjY5OTlhODgtNWRkZS00N2Y0LWIzNGUtZGQ1MmNmZTJjOGRj@thread.v2\", \"messageId\": \"0\" }, \"meetingInfo\": { \"@odata.type\": \"#microsoft.graph.organizerMeetingInfo\", \"organizer\": { \"@odata.type\": \"#microsoft.graph.identitySet\", \"user\": { \"@odata.type\": \"#microsoft.graph.identity\", \"id\": \"f0da7321-fee6-479c-ba65-cddf644e9ab2\", \"tenantId\": \"(Generic: hu191w/n)\" } } }, \"tenantId\": \"213b22cd-d6b7-48b3-bb23-decd68521256\", \"id\": \"441f0400-431f-4c8c-b261-09e03ff64374\" } } ] }, Properties: {}", "ProcessId": 23116, "Level": "Informational", "Keywords": "0x0000F00000000000", "EventName": "Message", "ActivityID": "0000058e-0001-0000-4c5a-0000ffdcd7b5", "RelatedActivityID": null, "Payload": { "message": "CommunicationsClient(CommunicationsClientExtensions.cs:220 LogRequestAndCreateResponse) 2019-08-02T16:28:26.5017637Z: { \"@odata.type\": \"#microsoft.graph.commsNotifications\", \"value\": [ { \"@odata.type\": \"#microsoft.graph.commsNotification\", \"changeType\": \"deleted\", \"resource\": \"/app/calls/441f0400-431f-4c8c-b261-09e03ff64374\", \"resourceData\": { \"@odata.type\": \"#microsoft.graph.call\", \"state\": \"terminated\", \"resultInfo\": { \"@odata.type\": \"#microsoft.graph.resultInfo\", \"code\": 500, \"subCode\": 7112 }, \"chatInfo\": { \"@odata.type\": \"#microsoft.graph.chatInfo\", \"threadId\": \"19:meeting_ZjY5OTlhODgtNWRkZS00N2Y0LWIzNGUtZGQ1MmNmZTJjOGRj@thread.v2\", \"messageId\": \"0\" }, \"meetingInfo\": { \"@odata.type\": \"#microsoft.graph.organizerMeetingInfo\", \"organizer\": { \"@odata.type\": \"#microsoft.graph.identitySet\", \"user\": { \"@odata.type\": \"#microsoft.graph.identity\", \"id\": \"f0da7321-fee6-479c-ba65-cddf644e9ab2\", \"tenantId\": \"(Generic: hu191w/n)\" } } }, \"tenantId\": \"213b22cd-d6b7-48b3-bb23-decd68521256\", \"id\": \"441f0400-431f-4c8c-b261-09e03ff64374\" } } ] }, Properties: {}" } }