Aug 19 2022 05:16 AM - edited Aug 19 2022 05:17 AM
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.
Aug 19 2022 10:56 PM
Aug 22 2022 08:07 AM
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...].