Teams IP phone provisioning

[Petri X]

Hi,

Has anyone faced the following error during the Teams IP phone provisioning:

...local6.warn W/ActivityManager( 596): Permission Denial: opening provider com.microsoft.skype.teams.devices.providers.DeviceStateProvider from ProcessRecord{fd06fad 1732:com.microsoft.teams.ipphone.admin.agent/u0a59} (pid=1732, uid=10059) requ
...local6.error E/DeviceStateResolver( 1732): Error starting device state provider
...local6.error E/DeviceStateResolver( 1732): java.lang.SecurityException: Permission Denial: opening provider com.microsoft.skype.teams.devices.providers.DeviceStateProvider from ProcessRecord{fd06fad 1732:com.microsoft.teams.ipphone.admin.agent/
...local6.error E/DeviceStateResolver( 1732): at android.os.Parcel.createException(Parcel.java:1950)
...local6.error E/DeviceStateResolver( 1732): at android.os.Parcel.readException(Parcel.java:1918)
...local6.error E/DeviceStateResolver( 1732): at android.os.Parcel.readException(Parcel.java:1868)
...local6.error E/DeviceStateResolver( 1732): at android.content.IContentService$Stub$Proxy.registerContentObserver(IContentService.java:779)
...local6.error E/DeviceStateResolver( 1732): at android.content.ContentResolver.registerContentObserver(ContentResolver.java:1973)
...local6.error E/DeviceStateResolver( 1732): at android.content.ContentResolver.registerContentObserver(ContentResolver.java:1962)
...local6.error E/DeviceStateResolver( 1732): at com.microsoft.teams.ipphone.admin.agent.device.DeviceStateContentProviderClient.start(DeviceStateContentProviderClient.java:64)
...local6.error E/DeviceStateResolver( 1732): at com.microsoft.teams.ipphone.admin.agent.operations.DeviceStateResolver.onExecute(DeviceStateResolver.java:37)
...local6.error E/DeviceStateResolver( 1732): at com.microsoft.teams.ipphone.admin.agent.operations.AgentOperation.RunProc(AgentOperation.java:87)
...local6.error E/DeviceStateResolver( 1732): at com.microsoft.teams.ipphone.admin.agent.operations.AgentOperation.access$000(AgentOperation.java:7)
...local6.error E/DeviceStateResolver( 1732): at com.microsoft.teams.ipphone.admin.agent.operations.AgentOperation$1.run(AgentOperation.java:59)
...local6.error E/DeviceStateResolver( 1732): at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:458)
...local6.error E/DeviceStateResolver( 1732): at java.util.concurrent.FutureTask.run(FutureTask.java:266)
...local6.error E/DeviceStateResolver( 1732): at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
...local6.error E/DeviceStateResolver( 1732): at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
...local6.error E/DeviceStateResolver( 1732): at java.lang.Thread.run(Thread.java:764)
...local6.error E/DeviceStateResolver( 1732): Caused by: android.os.RemoteException: Remote stack trace:
...local6.error E/DeviceStateResolver( 1732): at com.android.server.content.ContentService.registerContentObserver(ContentService.java:342)
...local6.error E/DeviceStateResolver( 1732): at android.content.IContentService$Stub.onTransact(IContentService.java:76)
...local6.error E/DeviceStateResolver( 1732): at com.android.server.content.ContentService.onTransact(ContentService.java:263)
...local6.error E/DeviceStateResolver( 1732): at android.os.Binder.execTransact(Binder.java:731)

On the TAC it is only rolling the following view;

It may take several minutes to set up the device.

Feel free to continue working. We'll let you know when the steps are ready.

[#2]

Hello Petri,

Can you please try to connect device directly to the LAN Network and try to sign-in to the Teams Application and check if you are able to sign into the devices seamlessly?

Based on the errors seems like the backend server where the devices are getting registered is having updates on-going at the time when you were trying to provision the device.

With Regards,
Satish Upadhyaya

[#3]

Hi @RealTime_M365 

Thanks to your effort. I'm not sure if something has change recently, but looks like I need to discuss with our proxy team, as I found from the logs an odd HTTP request:

. . .local6.info I/ac_infra ( 1343): [uHTTPEngine.cpp:CurlDebugCallback:176] Received HTTP code 407 from proxy after CONNECT
. . .local6.info I/ac_infra ( 1343): [uHTTPEngine.cpp:CurlDebugCallback:176] CONNECT phase completed!
. . .local6.debug D/ac_infra ( 1343): [uHTTPEngine.cpp:handleIO:77] still_running:0
. . .local6.debug D/ac_infra ( 1343): [uHTTPEngine.cpp:check_multi_info:332] effective url is: https://redirect.audiocodes.com/***MAC-Address***
. . .local6.info I/ac_infra ( 1343): [uHTTPEngine.cpp:CurlDebugCallback:176] Closing connection 0
. . .local6.debug D/ac_infra ( 1343): [uHTTPEngine.cpp:multi_timer_cb:96] timeout_ms:-1
. . .local6.debug D/ac_infra ( 1343): [uHTTPClient.cpp:HandleResponse:573] Finished transfer, sending response back
. . .local6.error E/ac_infra ( 1343): [uHTTPClient.cpp:EnqueueErrorResponse:683] In: void uHTTPClient::HandleResponse(CURLcode), Failure when receiving data from the peer

Connection attempt to AudioCodes was really I did not expected to see. But I believe that is not requirement to get the device provisioned, even that redirect.audiocodes.com is their provisioning system.

The more interesting log lines were the followings:

. . .local6.debug D/ac_configDB( 1343): getString: result was too long, HAD TO CROP. original: co.client-proxy.domain.local, cropped: fi.client-swg.
. . .local6.info I/ac_libs ( 1343): [ac_cfgutils.c:acCfgGetParam:230] returned from getString(), got: co.client-proxy.
. . .local6.debug D/ac_infra ( 1343): [uHTTPClient.cpp:SetProxy:505] Forward Proxy IP from the configuration file: co.client-proxy.
. . .local6.debug D/ac_infra ( 610): [AcCfgUtils.cpp:Java_com_android_audiocodes_cfgutils_AcCfgUtils_isPersist:126] Jni acCfgIsPersist http_client/fwd_proxy/direct/ip
. . .local6.debug D/ac_infra ( 610): [AcCfgUtils.cpp:Java_com_android_audiocodes_cfgutils_AcCfgUtils_getParamBackwardsCompatible:90] Jni acCfgGetParamBackwardsCompatible http_client/fwd_proxy/direct/ip
. . .local6.info I/ac_libs ( 1343): [ac_cfgutils.c:acCfgGetParam:230] returned from getString(), got: 0.0.0.0

In my mind this either is not a major issue, as DNS search suffixes is solving issue with this when reading the logs more further.

But if you do have any tips for the following, we might be closer to understand what is on-going:

. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): [2022-08-22 13:53:33 - thread_name : pool-23-thread-2, correlation_id : UNSET] Failed to load instance discovery metadata Android 28
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): javax.net.ssl.SSLHandshakeException: java.security.cert.CertPathValidatorException: Trust anchor for certification path not found.
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.org.conscrypt.ConscryptFileDescriptorSocket.startHandshake(ConscryptFileDescriptorSocket.java:229)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.io.RealConnection.connectTls(RealConnection.java:192)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.io.RealConnection.connectSocket(RealConnection.java:149)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.io.RealConnection.connect(RealConnection.java:112)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.http.StreamAllocation.findConnection(StreamAllocation.java:184)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.http.StreamAllocation.findHealthyConnection(StreamAllocation.java:126)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.http.StreamAllocation.newStream(StreamAllocation.java:95)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.http.HttpEngine.connect(HttpEngine.java:281)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.http.HttpEngine.sendRequest(HttpEngine.java:224)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.huc.HttpURLConnectionImpl.execute(HttpURLConnectionImpl.java:461)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.huc.HttpURLConnectionImpl.getResponse(HttpURLConnectionImpl.java:407)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.huc.HttpURLConnectionImpl.getInputStream(HttpURLConnectionImpl.java:244)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.huc.DelegatingHttpsURLConnection.getInputStream(DelegatingHttpsURLConnection.java:210)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.okhttp.internal.huc.HttpsURLConnectionImpl.getInputStream(HttpsURLConnectionImpl.java:26)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.net.UrlConnectionHttpClient.executeHttpSend(UrlConnectionHttpClient.java:263)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.net.UrlConnectionHttpClient.access$100(UrlConnectionHttpClient.java:73)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.net.UrlConnectionHttpClient$2.call(UrlConnectionHttpClient.java:176)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.net.UrlConnectionHttpClient$2.call(UrlConnectionHttpClient.java:174)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.net.NoRetryPolicy.attempt(NoRetryPolicy.java:42)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.net.NoRetryPolicy.attempt(NoRetryPolicy.java:36)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.net.UrlConnectionHttpClient.method(UrlConnectionHttpClient.java:174)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.net.AbstractHttpClient.get(AbstractHttpClient.java:47)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.providers.microsoft.azureactivedirectory.AzureActiveDirectory.performCloudDiscovery(AzureActiveDirectory.java:188)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.migration.AdalMigrationAdapter.loadCloudDiscoveryMetadata(AdalMigrationAdapter.java:238)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.migration.AdalMigrationAdapter.adapt(AdalMigrationAdapter.java:119)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.microsoft.identity.common.internal.migration.TokenMigrationUtility$1.run(TokenMigrationUtility.java:67)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at java.lang.Thread.run(Thread.java:764)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): Caused by: java.security.cert.CertificateException: java.security.cert.CertPathValidatorException: Trust anchor for certification path not found.
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.org.conscrypt.TrustManagerImpl.verifyChain(TrustManagerImpl.java:661)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.org.conscrypt.TrustManagerImpl.checkTrustedRecursive(TrustManagerImpl.java:539)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.org.conscrypt.TrustManagerImpl.checkTrustedRecursive(TrustManagerImpl.java:605)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.org.conscrypt.TrustManagerImpl.checkTrustedRecursive(TrustManagerImpl.java:605)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.org.conscrypt.TrustManagerImpl.checkTrusted(TrustManagerImpl.java:495)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.org.conscrypt.TrustManagerImpl.checkTrusted(TrustManagerImpl.java:418)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at com.android.org.conscrypt.TrustManagerImpl.getTrustedChainForServer(TrustManagerImpl.java:339)
. . .local6.error E/AdalMigrationAdapter:loadCloudDiscoveryMetadata( 1621): at android.security.net.config.N

It is super shame, that I have no logs from earlier version when I success to sign-in. Neither I do not know if my provisioning issues are related to the general O365 incident: [MO414814 - Some users may be unable to sign into Microsoft 365...].