C# application with MIP SDK fails creating the FileEngine

Hi!

I have a C# application which tries to create a FileEngine to unprotect AIP protected files.

The application runs in Azure. Network connectivity is available.

The MIP SDK logs look like this:

Info 2024-06-05 11:49:15.652 common/api_utils.h:195 w3wp (6324) “Start calling success callback for API: protection_profile_load_async” mipns::TryExecuteSuccessCallback::<lambda_aa4c0887fcc47f487d59891ccfa0eff4>::operator () 5396
Info 2024-06-05 11:49:15.652 common/api_utils.h:197 w3wp (6324) “Ended calling success callback for API: protection_profile_load_async” mipns::TryExecuteSuccessCallback::<lambda_aa4c0887fcc47f487d59891ccfa0eff4>::operator () 5396
Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:522 w3wp (6324) “Starting API call: profile_add_engine_async scenarioId=55a8c9cb-bbe6-40bb-992f-10b54066f182” mipns::ProfileImpl::AddEngineAsync 1048
Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:522 w3wp (6324) “Ended API call: profile_add_engine_async” mipns::ProfileImpl::AddEngineAsync 1048
Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:522 w3wp (6324) “Starting API task: profile_add_engine_async” mipns::ProfileImpl::AddEngineAsync 1700
Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:522 w3wp (6324) “Starting API task: profile_add_engine_async scenarioId=55a8c9cb-bbe6-40bb-992f-10b54066f182” mipns::ProfileImpl::AddEngineAsync 1700
Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:244 w3wp (6324) “Starting to add policy engine with engine id: 09342290-3990-4ef9-bdeb-611113bcccee” `anonymous-namespace’::CreateEngineAsync 1700
Warning 2024-06-05 11:49:15.652 policy_engine_manager_impl.cpp:275 w3wp (6324) “Inconsistent label & sensitivity policy detected. Removing both from cache if it exists.” mipns::PolicyEngineManagerImpl::DeletePolicyFromStorage 1700
Info 2024-06-05 11:49:15.652 policy_engine_manager_impl.cpp:358 w3wp (6324) “Loading new policy engine (requires fetch): 09342290-3990-4ef9-bdeb-611113bcccee” mipns::PolicyEngineManagerImpl::LoadNewEngineAsync 1700
Warning 2024-06-05 11:49:15.652 policy_engine_manager_impl.cpp:361 w3wp (6324) “New PolicyEngine was created without an identity. Dynamic content marking will be partially disabled, and URL redirect caching will be fully disabled.” mipns::PolicyEngineManagerImpl::LoadNewEngineAsync 1700
Info 2024-06-05 11:49:15.652 auth_request_transformer.cpp:155 w3wp (6324) “Requesting auth token from app. Resource: ‘https://syncservice.o365syncservice.com/‘, Authority: ‘https://login.windows.net/common‘, Scope: ”, Claims: ”” mipns::AuthRequestTransformer::GetAuthToken 1700
Info 2024-06-05 11:49:15.917 auth_request_transformer.cpp:169 w3wp (6324) “Authentication response time (seconds): 0.264937” mipns::AuthRequestTransformer::GetAuthToken 1700
Info 2024-06-05 11:49:15.932 http_director_impl.cpp:141 w3wp (6324) “Sending HTTP request: ID: {C3D930DE-50B3-40A8-8C44-0ED22007A6FB}, Type: GET, Url: https://dataservice.protection.outlook.com/PsorWebService/v1/ClientSyncFile/MipPolicies?supportedMaxVersion=1.0.50.0, Body Size: 0, Headers[‘ClientInfo’] = ‘mip_ver=1.14.128;os_name=win;os_ver=10-0-20348;runtime=msvc-1929;arch=x86’, Headers[‘Capabilities’] = ‘BestEffortEntityMatch,BestEffortCCSIMatch,SchematizedDataContentType’, Headers[‘Content-Type’] = ‘application/xml;charset=utf-8’, Headers[‘Authorization’] = ‘UOID:2d3ea670-a6d7-4a66-85fe-0bcc9b5f563a;Tenant:tenant id;Audience:https://syncservice.o365syncservice.com/;Roles:UnifiedPolicy.Tenant.Read;” mipns::HttpDirectorImpl::DoSendHttp 1700
Info 2024-06-05 11:49:16.104 http_client_base.cpp:44 w3wp (6324) “HTTP response time (seconds): 0.185885 ID: {C3D930DE-50B3-40A8-8C44-0ED22007A6FB}” mipns::HttpClientBase::SendAsync::<lambda_b2b0e837acbc3dca3dadb2856c35cf30>::operator () 5756
Info 2024-06-05 11:49:16.120 oneds_helper.cpp:532 w3wp (6324) “OneDsHelper::WriteTelemetryEvent(policy_sync_acquire_policy)” mipns::OneDSHelper::WriteTelemetryEvent 5756
Info 2024-06-05 11:49:16.120 diagnostic_utils.cpp:80 w3wp (6324) “Send Telemetry. Event Name : [policy_sync_acquire_policy]
App.ApplicationId: [application id], Pii: [None]
App.ApplicationName: [AR_COSI_TEST_AIP], Pii: [None]
App.ApplicationVersion: [1.0.0], Pii: [None]
App.SessionId: [], Pii: [None]
Engine.SessionId: [], Pii: [None]
Event.CorrelationId: [3f4d9f3a-a5a1-40fc-bbdb-049f4d40889f], Pii: [None]
Event.CorrelationIdDescription: [HttpDirector], Pii: [None]
Event.Duration: [0.187074], Pii: [None]
Event.ErrorType: [NetworkError], Pii: [None]
Event.Failed.File: [srccoreapi_implhttphttp_director_impl.cpp], Pii: [None]
Event.Failed.Func: [mipns::HttpTelemetryHelper::NotifyOperationComplete], Pii: [None]
Event.Failed.Line: [374], Pii: [None]
Event.Failed.Message: [No HTTP response. Failed with: [NetworkError: ‘HTTP connection failure Inner exception: [http_exception: ‘WinHttpSendRequest: 12029: A connection with the server could not be established’], NetworkError.Category=NoConnection, HttpRequest.SanitizedUrl=https://dataservice.protection.outlook.com/PsorWebService/v1/ClientSyncFile/MipPolicies, HttpRequest.Id={C3D930DE-50B3-40A8-8C44-0ED22007A6FB}’]], Pii: [None]
Event.ParentCorrelationId: [948d1c35-91a9-47be-af1f-6d6a241125e5], Pii: [None]
Event.ParentCorrelationIdDescription: [PolicyProfile], Pii: [None]
Event.UniqueId: [eacab4b6-2048-4cf0-8d5c-cba215bcb6a0], Pii: [None]
EventInfo.Level: [10], Pii: [None]
EventInfo.PrivTags: [33554432], Pii: [None]
MIP.Version: [1.14.128], Pii: [None]
Request.CorrelationId: [{C3D930DE-50B3-40A8-8C44-0ED22007A6FB}], Pii: [None]
Request.IsAsynchronous: [true], Pii: [None]
Request.RequestBodySize: [0], Pii: [None]
Request.TokenTenantId: [tenant id], Pii: [None]
Request.Url: [https://dataservice.protection.outlook.com/PsorWebService/v1/ClientSyncFile/MipPolicies], Pii: [None]
iKey: [ce9aa5fb5a414ecebb15af10715bd8ff-831d197e-fc97-4df6-b998-c8c13a0fc3ce-6768], Pii: [None]
” mipns::WriteTelemetryEventToLog 5756
Info 2024-06-05 11:49:16.120 http_director_impl.cpp:38 w3wp (6324) “Received HTTP response: ” `anonymous-namespace’::LogHttpOperationDetails 5756
Error 2024-06-05 11:49:16.120 http_director_impl.cpp:42 w3wp (6324) “HTTP operation {C3D930DE-50B3-40A8-8C44-0ED22007A6FB} failed: Failed with: [NetworkError: ‘HTTP connection failure Inner exception: [http_exception: ‘WinHttpSendRequest: 12029: A connection with the server could not be established’], NetworkError.Category=NoConnection, HttpRequest.SanitizedUrl=https://dataservice.protection.outlook.com/PsorWebService/v1/ClientSyncFile/MipPolicies, HttpRequest.Id={C3D930DE-50B3-40A8-8C44-0ED22007A6FB}’]” `anonymous-namespace’::LogHttpOperationDetails 5756

This error does not occur on every tenant!

Does anyone have a clue why this error occurs?

Hi!I have a C# application which tries to create a FileEngine to unprotect AIP protected files.The application runs in Azure. Network connectivity is available.The MIP SDK logs look like this:Info 2024-06-05 11:49:15.652 common/api_utils.h:195 w3wp (6324) “Start calling success callback for API: protection_profile_load_async” mipns::TryExecuteSuccessCallback::<lambda_aa4c0887fcc47f487d59891ccfa0eff4>::operator () 5396Info 2024-06-05 11:49:15.652 common/api_utils.h:197 w3wp (6324) “Ended calling success callback for API: protection_profile_load_async” mipns::TryExecuteSuccessCallback::<lambda_aa4c0887fcc47f487d59891ccfa0eff4>::operator () 5396Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:522 w3wp (6324) “Starting API call: profile_add_engine_async scenarioId=55a8c9cb-bbe6-40bb-992f-10b54066f182” mipns::ProfileImpl::AddEngineAsync 1048Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:522 w3wp (6324) “Ended API call: profile_add_engine_async” mipns::ProfileImpl::AddEngineAsync 1048Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:522 w3wp (6324) “Starting API task: profile_add_engine_async” mipns::ProfileImpl::AddEngineAsync 1700Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:522 w3wp (6324) “Starting API task: profile_add_engine_async scenarioId=55a8c9cb-bbe6-40bb-992f-10b54066f182” mipns::ProfileImpl::AddEngineAsync 1700Info 2024-06-05 11:49:15.652 policy_profile_impl.cpp:244 w3wp (6324) “Starting to add policy engine with engine id: 09342290-3990-4ef9-bdeb-611113bcccee” `anonymous-namespace’::CreateEngineAsync 1700Warning 2024-06-05 11:49:15.652 policy_engine_manager_impl.cpp:275 w3wp (6324) “Inconsistent label & sensitivity policy detected. Removing both from cache if it exists.” mipns::PolicyEngineManagerImpl::DeletePolicyFromStorage 1700Info 2024-06-05 11:49:15.652 policy_engine_manager_impl.cpp:358 w3wp (6324) “Loading new policy engine (requires fetch): 09342290-3990-4ef9-bdeb-611113bcccee” mipns::PolicyEngineManagerImpl::LoadNewEngineAsync 1700Warning 2024-06-05 11:49:15.652 policy_engine_manager_impl.cpp:361 w3wp (6324) “New PolicyEngine was created without an identity. Dynamic content marking will be partially disabled, and URL redirect caching will be fully disabled.” mipns::PolicyEngineManagerImpl::LoadNewEngineAsync 1700Info 2024-06-05 11:49:15.652 auth_request_transformer.cpp:155 w3wp (6324) “Requesting auth token from app. Resource: ‘https://syncservice.o365syncservice.com/’, Authority: ‘https://login.windows.net/common’, Scope: ”, Claims: ”” mipns::AuthRequestTransformer::GetAuthToken 1700Info 2024-06-05 11:49:15.917 auth_request_transformer.cpp:169 w3wp (6324) “Authentication response time (seconds): 0.264937” mipns::AuthRequestTransformer::GetAuthToken 1700Info 2024-06-05 11:49:15.932 http_director_impl.cpp:141 w3wp (6324) “Sending HTTP request: ID: {C3D930DE-50B3-40A8-8C44-0ED22007A6FB}, Type: GET, Url: https://dataservice.protection.outlook.com/PsorWebService/v1/ClientSyncFile/MipPolicies?supportedMaxVersion=1.0.50.0, Body Size: 0, Headers[‘ClientInfo’] = ‘mip_ver=1.14.128;os_name=win;os_ver=10-0-20348;runtime=msvc-1929;arch=x86’, Headers[‘Capabilities’] = ‘BestEffortEntityMatch,BestEffortCCSIMatch,SchematizedDataContentType’, Headers[‘Content-Type’] = ‘application/xml;charset=utf-8’, Headers[‘Authorization’] = ‘UOID:2d3ea670-a6d7-4a66-85fe-0bcc9b5f563a;Tenant:tenant id;Audience:https://syncservice.o365syncservice.com/;Roles:UnifiedPolicy.Tenant.Read;” mipns::HttpDirectorImpl::DoSendHttp 1700Info 2024-06-05 11:49:16.104 http_client_base.cpp:44 w3wp (6324) “HTTP response time (seconds): 0.185885 ID: {C3D930DE-50B3-40A8-8C44-0ED22007A6FB}” mipns::HttpClientBase::SendAsync::<lambda_b2b0e837acbc3dca3dadb2856c35cf30>::operator () 5756Info 2024-06-05 11:49:16.120 oneds_helper.cpp:532 w3wp (6324) “OneDsHelper::WriteTelemetryEvent(policy_sync_acquire_policy)” mipns::OneDSHelper::WriteTelemetryEvent 5756Info 2024-06-05 11:49:16.120 diagnostic_utils.cpp:80 w3wp (6324) “Send Telemetry. Event Name : [policy_sync_acquire_policy]App.ApplicationId: [application id], Pii: [None]App.ApplicationName: [AR_COSI_TEST_AIP], Pii: [None]App.ApplicationVersion: [1.0.0], Pii: [None]App.SessionId: [], Pii: [None]Engine.SessionId: [], Pii: [None]Event.CorrelationId: [3f4d9f3a-a5a1-40fc-bbdb-049f4d40889f], Pii: [None]Event.CorrelationIdDescription: [HttpDirector], Pii: [None]Event.Duration: [0.187074], Pii: [None]Event.ErrorType: [NetworkError], Pii: [None]Event.Failed.File: [srccoreapi_implhttphttp_director_impl.cpp], Pii: [None]Event.Failed.Func: [mipns::HttpTelemetryHelper::NotifyOperationComplete], Pii: [None]Event.Failed.Line: [374], Pii: [None]Event.Failed.Message: [No HTTP response. Failed with: [NetworkError: ‘HTTP connection failure Inner exception: [http_exception: ‘WinHttpSendRequest: 12029: A connection with the server could not be established’], NetworkError.Category=NoConnection, HttpRequest.SanitizedUrl=https://dataservice.protection.outlook.com/PsorWebService/v1/ClientSyncFile/MipPolicies, HttpRequest.Id={C3D930DE-50B3-40A8-8C44-0ED22007A6FB}’]], Pii: [None]Event.ParentCorrelationId: [948d1c35-91a9-47be-af1f-6d6a241125e5], Pii: [None]Event.ParentCorrelationIdDescription: [PolicyProfile], Pii: [None]Event.UniqueId: [eacab4b6-2048-4cf0-8d5c-cba215bcb6a0], Pii: [None]EventInfo.Level: [10], Pii: [None]EventInfo.PrivTags: [33554432], Pii: [None]MIP.Version: [1.14.128], Pii: [None]Request.CorrelationId: [{C3D930DE-50B3-40A8-8C44-0ED22007A6FB}], Pii: [None]Request.IsAsynchronous: [true], Pii: [None]Request.RequestBodySize: [0], Pii: [None]Request.TokenTenantId: [tenant id], Pii: [None]Request.Url: [https://dataservice.protection.outlook.com/PsorWebService/v1/ClientSyncFile/MipPolicies], Pii: [None]iKey: [ce9aa5fb5a414ecebb15af10715bd8ff-831d197e-fc97-4df6-b998-c8c13a0fc3ce-6768], Pii: [None]” mipns::WriteTelemetryEventToLog 5756Info 2024-06-05 11:49:16.120 http_director_impl.cpp:38 w3wp (6324) “Received HTTP response: ” `anonymous-namespace’::LogHttpOperationDetails 5756Error 2024-06-05 11:49:16.120 http_director_impl.cpp:42 w3wp (6324) “HTTP operation {C3D930DE-50B3-40A8-8C44-0ED22007A6FB} failed: Failed with: [NetworkError: ‘HTTP connection failure Inner exception: [http_exception: ‘WinHttpSendRequest: 12029: A connection with the server could not be established’], NetworkError.Category=NoConnection, HttpRequest.SanitizedUrl=https://dataservice.protection.outlook.com/PsorWebService/v1/ClientSyncFile/MipPolicies, HttpRequest.Id={C3D930DE-50B3-40A8-8C44-0ED22007A6FB}’]” `anonymous-namespace’::LogHttpOperationDetails 5756This error does not occur on every tenant!Does anyone have a clue why this error occurs? Read More

Cart

Cart