Error when communicating with Google API(20): Timing out service connection

aurisnoctis commented 4 years ago

Describe the bug

Opening the CWA for the first time today resulted in display of error message Fehler bei Kommunikation mit Google API(20), Error when communicating with Google API(20). Every opening of the app shows this error until Google API(39508) error kicks in (too many calls to API).

Today would have been the first time to have 14/14 active days. Now it stays at 13/14 days.

Sorry I cannot provide more details right now. Unfortunately I had USB debugging off and after switching it on, only the Google API(39508) error was captured in the log. Also, it is a massive hindrance to documentation not being able to take screenshots. I didn't have another smartphone or a computer nearby when the error happened. Users of CWA on iOS apparently can take screenshots without hindrance. Friends on iPhones share screenshots of CWA with me all the time. Frustrating.

Expected behaviour

The app opens without any errors.

Steps to reproduce the issue

Install the app on 16 June 2020 via Google Play Store, keep it updated, version is 1.0.4 before any error in CWA appears
Use app with bluetooth and location on for some time every day; see days active incremented every day
On 25 June 2020 see the Google API(10) error appearing for the first time; error does not appear again that days and days active are incremented when opening the app again
See Google API(10) error appearing and disappearing on other days after that (I don't have an exact log which days); days active are still incremented every day
On 30 June 2020 see Google API(20) error appearing when opening CWA for the first time. Appears again every time the app is opened / put into foreground until no more calls to Google API possible (Google API(39508) error).

Technical details

Device

App Version: 1.0.4
Android Version: 6.0.1 (stock Android of device, not rooted)
Device Model and Manufacturer: Samsung S5 (SM-G900F)
Latest OS Update: can't determine date, Android menu says all available updates installed
- Kernel: 3.4.0-14131106 from 06 Aug 2018
- Build number: MMB29M.G900FXXU1CRH1
Latest Update of CWA: update to 1.0.4
Installation Date of CWA: 16 June 2020
Date/Time of the error: 30 June 2020 about 9:00 local time Germany
Google Play Services version: 20.21.17 (040308-316502805)
Google Mobile Services (GMS) version:
- com.google.android.gms [202117018] [20.21.17 (040308-316502805)] [Container]
- com.google.android.gms.nearby_en [v202304013]

Additional context

This error happened for me after several days of seeing Fehler bei Kommunikation mit Google API(10) (Error when communicating with Google API(10)) when starting the app, as described in #771. Today, only the Google API(20) error appeared. The Google API(10) error so far seemed to "resolve itself" on my device, not reaching Google API(39508) error (too many calls to API); days active was incremented in those cases after the error was not displayed anymore. Google API(20) in contrast reappeared until no more calls to the API were possible. Days active was not incremented today.

jakobmoellerdev commented 4 years ago

Could you click the error away and try to reenable the API by manually switching it on / off. We want to make sure that the API is able to connect properly to CWA

aurisnoctis commented 4 years ago

@jakobmoellersap

We want to make sure that the API is able to connect properly to CWA

Thank you, much appreciated. I will write in English but my OS is in German so translations might not match English version exactly.

(A) CWA App, starting the app for the first time today

App shows start screen (all white with app logo in the middle) for about 20 seconds - extremely long waiting time I never had before
then the app switches to the main screen, is showing the grey plate with undetermined risk
then a timeout error appears, java.net.SocketTimeoutException: timeout [1]
clicking Okay reverts view to main app screen, with green low risk plate and that latest update was today 11:34, which is indeed when I opened the app, but still showing 13 out of 14 days active, so no increment since the day before yesterday :arrow_right: last successful increment was the day before the day that the Google API(20) error appeared (yesterday)

(B) Switching API on and off

try to reenable the API by manually switching it on / off.

Here, I wasn't sure if you meant deactivating and activating the tracing in CWA or if you meant the Android Google setting for the Google Exposure Notification framework so I tried both, eventually ending up in the same CWA settings, see below:

(B1) Switching CWA tracing off and back on

switching off by slider in submenu for tracing status
switching slider on again
- pop-up activate COVID-19 EN? :arrow_right: I click Activate :arrow_right: risk assessment is active, slider stays on
going back to main screen: still only 13/14 days active
putting app in background
bringing app back to foreground :arrow_right: no change: update time is still 11:34, still 13 of 14 days active

(B2) Google Exposure Notification framework

Android settings menu > Google > COVID-19 menu item :arrow_right: looks like in its usual active state [2]
clicking deactivate notifications about possible ... (Benachrichtigungen zu möglicher Begegnung mit Infizierten deaktivieren)
- :arrow_right: this field is now greyed out, at the top is a message that I should open the corresponding app to activate
- clicking Öffnen to the right of Corona-Warn
back in CWA the main plate shows risk assessment stopped: Activate risk assessment to calculate your risk status for today (instead of the usual grey or green plates)
click on switch on risk assessment :arrow_right: app switches to the setting for activating risk assessment
switching slider on for risk assessment :arrow_right: pop-up activate COVID-19 EN? :arrow_right: I click Activate :arrow_right: risk assessment is active, slider stays on (as already done above in (B1), doing everything inside CWA)
closing risk assessment settings view :arrow_right: no change: update time is still 11:34, still 13 of 14 days active

Details

[1] timeout error


error page 1	error page 2	error page 3
error page 4	error page 5	error page 6

Really sorry for the quality - my partner and his smartphone are not here and I don't have a camera except for my own smartphone so I only have a webcam here to take pictures of my phone screen. Had to install an app for taking pics with the webcam. Webcam always focuses on the background and I had to crop all the pics by hand - took all in all 20 Minutes just to get the pics here without Android screenshots :fearful:

[2] What my Google COVID-19 EN framework settings look like in 'normal state'


Google COVID-19 EN in 'normal state', ignore notes included for other github issue

Much better quality when I had my partner's phone to take pics of my phone screen.

jakobmoellerdev commented 4 years ago

Okay, so as far as I understand you have multiple error cases at once that match to different errors.

Regarding your original Issue 20: This seemed to be sporadically and does not seem to reappear, correct? Regarding your issue 10: This is confirmed and is being resolved in #737 Regarding the timeout: Did you maybe lose internet connection in the meantime? This only happens when we have issues connecting to the internet in a stable way. Regarding the issue of 13/14 days active: We have confirmed this issue and are working on it in #796

aurisnoctis commented 4 years ago

@jakobmoellersap The sequence of errors was

CWA installed, everything fine, CWA updated to 1.0.4, still runs fine for 1 or 2 days
on 25 June 2020 Google API(10) error appears for the first time https://github.com/corona-warn-app/cwa-app-android/issues/737#issuecomment-649702108, date of last update of risk assessment (Aktualisiert) and days active in main windows increments
on 26 June 2020 Google API(10) error appears again https://github.com/corona-warn-app/cwa-app-android/issues/737#issuecomment-650144069, date of last update of risk assessment and days active in main windows increments as before
cannot recollect if Goole API(10) error appeared after that -- but days active increased steadily every day
on 30 June 2020 Google API(20) appears :arrow_right: for the first time days active is not incremented and stays at 13 of 14 days active
on 31 July 2020 no Google API errors, but long waiting time just showing the app symbol on white background when starting CWA, then timeout error and still only 13 of 14 days active

I was at home with a stable internet connection. I could surf the internet with the same phone before and after. I think it is unlikely that it was a short gap in internet connection for just that half minute.

The days not incrementing started the day when the Google API(20) error happened to me. Yes, it might be that it coincided with #796 by chance and that it's two separate issues.

I'd like to keep this open until I didn't have any errors starting the app for a couple of days and to see if others start seeing the Google API (20) error these days.

aurisnoctis commented 4 years ago

Google API(20) error appeared again today. Brief summary:

bring app to foreground first time today: Google API(20 error)
then Google API(10) error is displayed #737
the two error pop-ups alternate until Google API(39508) timeout error appears

Today is the first day in CWA version 1.0.4 that the last update (Aktualisiert:) was not incremented.

This time I had a second phone to take pics:

As mentioned in the brief summary, the errors started with the Google API(20)error as a pop-up. After the smartphone screen was off and I switched it on again, it was already overlaid with the Google API(10) error pop-up. This is when the pictures below start.


Google API(10) error over Google API(20) error	Google API(10) error details 1	Google API(10) error details 2
Google API(20) error details 1	Google API(20) error details 2	Google API(10) error again over Google API(20) error

after clicking Okay to dismiss the errors, the main CWA screen is visible for some time, showing 13 of 14 days active (see also #796) and last update Yesterday, 11:34
then the Google API(10) error pops up again
still 13 of 14 days active and last update Yesterday, 11:34
putting app in background
opening app again: Google API(39508) error appears
still 13 of 14 days active and last update Yesterday, 11:34

aurisnoctis commented 4 years ago

Additional info for https://github.com/corona-warn-app/cwa-app-android/issues/788#issuecomment-652871916 from Android debug log file - maybe that helps to sort out problems with Google GMS:

Click here to expand

``` 07-02 08:33:05.113 1673 1673 I Timeline: Timeline: Activity_launch_request id:de.rki.coronawarnapp time:88153752 [...] 07-02 08:33:05.573 3738 3847 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/files/reporting-log [...] 07-02 08:33:05.853 3865 3865 D TimaKeyStoreProvider: TimaSignature is unavailable 07-02 08:33:05.853 3865 3865 D ActivityThread: Added TimaKeyStore provider 07-02 08:33:06.083 3865 3865 W DynamiteModule: Local module descriptor class for providerinstaller not found. 07-02 08:33:06.093 3865 3865 W ProviderHelper: Unknown dynamite feature providerinstaller 07-02 08:33:06.093 3865 3865 I DynamiteModule: Considering local module providerinstaller:0 and remote module providerinstaller:0 07-02 08:33:06.093 3865 3865 W ProviderInstaller: Failed to load providerinstaller module: No acceptable module found. Local version is 0 and remote version is 0. 07-02 08:33:06.113 3865 3865 V NativeCrypto: Registering com/google/android/gms/org/conscrypt/NativeCrypto's 286 native methods... 07-02 08:33:06.123 3865 3865 W : Unable to open '/system/framework/com.qti.location.sdk.jar': No such file or directory 07-02 08:33:06.123 3865 3865 W art : Failed to open zip archive '/system/framework/com.qti.location.sdk.jar': I/O Error 07-02 08:33:06.173 3865 3865 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:06.173 3865 3865 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:06.173 3865 3865 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:06.173 3865 3865 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:06.203 3865 3865 I ProviderInstaller: Installed default security provider GmsCore_OpenSSL 07-02 08:33:06.203 3865 3865 I Safeboot: Checking safeboot... 07-02 08:33:06.233 3865 3865 I Safeboot Condition: No need to enter Safeboot. 07-02 08:33:06.233 3865 3865 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/files/CFN.now 07-02 08:33:06.293 3865 3865 I beit : Primes not initialized, returning default (no-op) Primes instance which will ignore all calls. Please call Primes.initialize(...) before using any Primes API. 07-02 08:33:06.353 3865 3865 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/databases/playlog.db 07-02 08:33:06.353 3865 3865 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/databases/playlog.db-journal 07-02 08:33:06.353 3865 3865 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/databases/playlog.db-shm 07-02 08:33:06.353 3865 3865 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/databases/playlog.db-wal 07-02 08:33:06.433 3891 3891 E TZ_CCM_SERVER: Only 'CCM' are supported 07-02 08:33:06.433 3891 3891 I TZ_CCM_SERVER: ###Exit TZ CCM Server 07-02 08:33:06.453 3865 3875 I DynamiteModule: Considering local module com.google.android.gms.googlecertificates:4 and remote module com.google.android.gms.googlecertificates:4 07-02 08:33:06.453 3865 3875 I DynamiteModule: Selected local version of com.google.android.gms.googlecertificates 07-02 08:33:06.473 813 902 D LocationProviderProxy: applying state to connected service 07-02 08:33:06.613 3865 3890 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:06.613 3865 3890 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:06.613 3865 3890 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:06.613 3865 3890 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:06.663 3865 3890 I NetworkScheduler: Using standalone scheduling engine [CONTEXT service_id=218 ] 07-02 08:33:06.673 813 902 D LocationProviderProxy: applying state to connected service 07-02 08:33:06.803 3865 3865 D ConnectivityManager: requestNetwork; getAppId(CallingUid) : 10014, CallingPid : 3865 [...] 07-02 08:33:06.863 3865 3865 W System : ClassLoader referenced unknown path: /data/data/com.google.android.gms/app_chimera/m/00000102/n/armeabi 07-02 08:33:06.923 3865 3908 W ProviderHelper: Unknown dynamite feature providerinstaller 07-02 08:33:06.923 3865 3908 I DynamiteModule: Considering local module providerinstaller:0 and remote module providerinstaller:0 07-02 08:33:06.923 3865 3908 I DynamiteModule: Considering local module providerinstaller:0 and remote module providerinstaller:0 07-02 08:33:06.923 3865 3908 I qtaguid : Tagging socket -1 with tag 2000040700000000{536871943,0} uid -1, pid: 3865, getuid(): 10014 07-02 08:33:06.923 3865 3908 I qtaguid : Failed write_ctrl(t -1 2305847437324976128 -1) res=-1 errno=9 07-02 08:33:06.923 3865 3908 I qtaguid : Tagging socket -1 with tag 2000040700000000(536871943) for uid -1 failed errno=-9 07-02 08:33:06.923 3865 3908 I NetworkManagementSocketTagger: tagSocketFd(-1, 536871943, -1) failed with errno-9 07-02 08:33:06.923 3865 3908 I qtaguid : Tagging socket 136 with tag 2000040700000000{536871943,0} uid -1, pid: 3865, getuid(): 10014 07-02 08:33:06.953 315 315 D libEGL : eglInitialize EGLDisplay = 0xbea1a364 07-02 08:33:06.973 3865 3865 I ExposureNotification: ExposureNotificationInternalService.onCreate, com.google.android.gms.nearby.exposurenotification.service.ExposureNotificationInternalChimeraService@c8e7c 07-02 08:33:07.023 3865 3865 I ExposureNotification: ExposureServiceInternalService.onStartCommand, null [CONTEXT service_id=236 ] 07-02 08:33:07.123 3865 3865 D BluetoothAdapter: STATE_BLE_ON 07-02 08:33:07.123 3865 3865 I ExposureNotification: Utils#isSupported enabled=true, isDeviceSupported=true, isBluetoothSupported=true, BluetoothAdapter.isMultipleAdvertisementSupported=false [CONTEXT servi 07-02 08:33:07.123 3865 3865 I ExposureNotification: ExposureNotificationInternalService.onStartCommand received intent, action=null [CONTEXT service_id=236 ] 07-02 08:33:07.143 3865 3865 I ExposureNotification: Active client found, start tracing. [CONTEXT service_id=236 ] 07-02 08:33:07.143 3865 3865 I ExposureNotification: ExposureServiceInternalService.startTracing [CONTEXT service_id=236 ] 07-02 08:33:07.163 3865 3865 I ExposureNotification: AlarmManagerImpl.opportunisticSchedule called, delay=1800000ms [CONTEXT service_id=236 ] 07-02 08:33:07.183 3865 3865 I ExposureNotification: AlarmManagerImpl.opportunisticSchedule called, delay=1800000ms [CONTEXT service_id=236 ] [... some errors repeat, e.g., 07-02 08:33:07.773 3865 3919 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/files/nlp_state] 07-02 08:33:08.563 3971 3971 E Zygote : v2 07-02 08:33:08.573 3971 3971 I SELinux : Function: selinux_compare_spd_ram, index[1], SPD-policy is existed. and_ver=SEPF_SECMOBILE_6.0.1 ver=11 07-02 08:33:08.573 3971 3971 W SELinux : Function: selinux_compare_spd_ram, index[1], priority [2], priority version is VE=SEPF_SECMOBILE_6.0.1_0035 07-02 08:33:08.573 3971 3971 E Zygote : accessInfo : 0 07-02 08:33:08.573 3971 3971 W SELinux : SELinux: seapp_context_lookup: seinfo=gmscore, level=s0:c512,c768, pkgname=com.google.android.gms.persistent 07-02 08:33:08.593 3971 3971 D TimaKeyStoreProvider: TimaSignature is unavailable 07-02 08:33:08.593 3971 3971 D ActivityThread: Added TimaKeyStore provider 07-02 08:33:08.613 813 1073 E Sensors : SigMotionSensor old sensor_state 8705, new sensor_state : 513 en : 0 07-02 08:33:08.853 3971 3971 W DynamiteModule: Local module descriptor class for providerinstaller not found. 07-02 08:33:08.863 3971 3971 W ProviderHelper: Unknown dynamite feature providerinstaller 07-02 08:33:08.873 3971 3971 I DynamiteModule: Considering local module providerinstaller:0 and remote module providerinstaller:0 07-02 08:33:08.873 3971 3971 W ProviderInstaller: Failed to load providerinstaller module: No acceptable module found. Local version is 0 and remote version is 0. 07-02 08:33:08.883 3971 3971 V NativeCrypto: Registering com/google/android/gms/org/conscrypt/NativeCrypto's 286 native methods... 07-02 08:33:08.893 3971 3971 W : Unable to open '/system/framework/com.qti.location.sdk.jar': No such file or directory 07-02 08:33:08.893 3971 3971 W art : Failed to open zip archive '/system/framework/com.qti.location.sdk.jar': I/O Error 07-02 08:33:08.943 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:08.943 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:08.943 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:08.943 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:08.963 3971 3971 I ProviderInstaller: Installed default security provider GmsCore_OpenSSL 07-02 08:33:08.963 3971 3971 I Safeboot: Checking safeboot... 07-02 08:33:08.993 3971 3971 I Safeboot Condition: No need to enter Safeboot. 07-02 08:33:09.003 3971 3971 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/files/CFN.now 07-02 08:33:09.063 3971 3971 I beit : Primes not initialized, returning default (no-op) Primes instance which will ignore all calls. Please call Primes.initialize(...) before using any Primes API. 07-02 08:33:09.133 3971 3971 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/databases/playlog.db 07-02 08:33:09.133 3971 3971 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/databases/playlog.db-journal 07-02 08:33:09.133 3971 3971 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/databases/playlog.db-shm 07-02 08:33:09.133 3971 3971 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/databases/playlog.db-wal 07-02 08:33:09.243 3971 3981 I DynamiteModule: Considering local module com.google.android.gms.googlecertificates:4 and remote module com.google.android.gms.googlecertificates:4 07-02 08:33:09.243 3971 3981 I DynamiteModule: Selected local version of com.google.android.gms.googlecertificates 07-02 08:33:09.263 813 902 D LocationProviderProxy: applying state to connected service 07-02 08:33:09.423 813 902 D LocationProviderProxy: applying state to connected service 07-02 08:33:09.473 1234 1234 D StatusBar.NetworkController: refreshViews connected={ wifi } level=4 combinedSignalIconId=0x7f020643/com.android.systemui:drawable/stat_sys_wifi_signal_2 mobileLabel=BLAU wifi 07-02 08:33:09.473 1234 1234 D StatusBar.NetworkController: refreshNwBoosterIndicator - setNWBoosterIndicators(false) 07-02 08:33:09.473 1234 1234 D StatusBar.NetworkController: refreshNwBoosterIndicator - setNWBoosterIndicators(false) 07-02 08:33:09.473 1234 1234 D StatusBar.NetworkController: refreshNwBoosterIndicator - setNWBoosterIndicators(false) 07-02 08:33:09.473 1234 1234 D StatusBar.NetworkController: refreshNwBoosterIndicator - setNWBoosterIndicators(false) 07-02 08:33:09.523 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:09.523 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:09.533 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:09.533 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:09.613 3971 3971 D ConnectivityManager: requestNetwork; getAppId(CallingUid) : 10014, CallingPid : 3971 07-02 08:33:09.643 330 1052 D EnterpriseController: netId is 0 07-02 08:33:09.643 330 1052 D Netd : getNetworkForDns: using netid 514 for uid 10014 07-02 08:33:09.643 3971 4015 W ProviderHelper: Unknown dynamite feature providerinstaller 07-02 08:33:09.643 3971 4015 I DynamiteModule: Considering local module providerinstaller:0 and remote module providerinstaller:0 07-02 08:33:09.653 3971 4015 I DynamiteModule: Considering local module providerinstaller:0 and remote module providerinstaller:0 07-02 08:33:09.653 3971 4015 I qtaguid : Tagging socket -1 with tag 2000040700000000{536871943,0} uid -1, pid: 3971, getuid(): 10014 07-02 08:33:09.653 3971 4015 I qtaguid : Failed write_ctrl(t -1 2305847437324976128 -1) res=-1 errno=9 07-02 08:33:09.653 3971 4015 I qtaguid : Tagging socket -1 with tag 2000040700000000(536871943) for uid -1 failed errno=-9 07-02 08:33:09.653 3971 4015 I NetworkManagementSocketTagger: tagSocketFd(-1, 536871943, -1) failed with errno-9 07-02 08:33:09.653 3971 4015 I qtaguid : Tagging socket 120 with tag 2000040700000000{536871943,0} uid -1, pid: 3971, getuid(): 10014 07-02 08:33:09.673 3971 3971 W System : ClassLoader referenced unknown path: /data/data/com.google.android.gms/app_chimera/m/00000102/n/armeabi 07-02 08:33:09.773 3971 3971 I ExposureNotification: ExposureNotificationInternalService.onCreate, com.google.android.gms.nearby.exposurenotification.service.ExposureNotificationInternalChimeraService@44921 07-02 08:33:09.823 3971 3971 I ExposureNotification: ExposureServiceInternalService.onStartCommand, null [CONTEXT service_id=236 ] 07-02 08:33:09.853 3971 4015 I NetworkScheduler: Using standalone scheduling engine [CONTEXT service_id=218 ] 07-02 08:33:09.913 3971 3971 D BluetoothAdapter: STATE_BLE_ON 07-02 08:33:09.913 3971 3971 I ExposureNotification: Utils#isSupported enabled=true, isDeviceSupported=true, isBluetoothSupported=true, BluetoothAdapter.isMultipleAdvertisementSupported=false [CONTEXT servi 07-02 08:33:09.913 3971 3971 I ExposureNotification: ExposureNotificationInternalService.onStartCommand received intent, action=null [CONTEXT service_id=236 ] 07-02 08:33:09.933 3971 3971 I ExposureNotification: Active client found, start tracing. [CONTEXT service_id=236 ] 07-02 08:33:09.933 3971 3971 I ExposureNotification: ExposureServiceInternalService.startTracing [CONTEXT service_id=236 ] 07-02 08:33:09.953 3971 3971 I ExposureNotification: AlarmManagerImpl.opportunisticSchedule called, delay=1800000ms [CONTEXT service_id=236 ] 07-02 08:33:09.963 3971 3971 I ExposureNotification: AlarmManagerImpl.opportunisticSchedule called, delay=1800000ms [CONTEXT service_id=236 ] 07-02 08:33:10.003 3971 3971 I ExposureNotification: scheduling daily task dispatcher [CONTEXT service_id=236 ] 07-02 08:33:10.063 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.063 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.063 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.073 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.073 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.073 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.083 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.083 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.083 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.083 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class 07-02 08:33:10.083 3971 3971 I art : Rejecting re-init on previously-failed class java.lang.Class [...] 07-02 08:33:10.503 3971 4030 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/files/nlp_state 07-02 08:33:10.503 3971 4030 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/cache/nlp_devices 07-02 08:33:10.503 3971 4030 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/files/nlp_ioh 07-02 08:33:10.503 3971 4030 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/cache/nlp_s 07-02 08:33:10.503 3971 4030 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/com.google.android.gms/cache/nlp_GlsPlatformKey 07-02 08:33:10.783 1394 1394 E TranscodeReceiver: onReceive : android.intent.action.CHECK_SIOP_LEVEL 07-02 08:33:10.783 1394 1394 D TranscodeReceiver: SIOP_LEVEL: 1 07-02 08:33:10.823 813 1669 D LocationManagerService: request c3af7d0 passive Request[POWER_NONE passive fastest=0] from com.google.android.gms(10014) 07-02 08:33:10.823 813 1669 D LocationManagerService: provider request: passive ProviderRequest[ON interval=0] 07-02 08:33:10.863 3971 3971 I Backup : [GmsBackupAccountManager] Backup account not found in gmscore. 07-02 08:33:10.883 3971 3982 W DynamiteModule: Local module descriptor class for com.google.android.gms.googlecertificates not found. 07-02 08:33:10.893 3971 3982 I DynamiteModule: Considering local module com.google.android.gms.googlecertificates:0 and remote module com.google.android.gms.googlecertificates:4 07-02 08:33:10.893 3971 3982 I DynamiteModule: Selected remote version of com.google.android.gms.googlecertificates, version >= 4 07-02 08:33:10.893 3971 3982 W System : ClassLoader referenced unknown path: /data/data/com.google.android.gms/app_chimera/m/00000109/n/armeabi-v7a 07-02 08:33:10.893 3971 3982 W System : ClassLoader referenced unknown path: /data/data/com.google.android.gms/app_chimera/m/00000109/n/armeabi 07-02 08:33:10.963 3971 3971 I ExposureNotification: Settings updated bluetooth enabled: false location enabled false [CONTEXT service_id=236 ] 07-02 08:33:10.983 3971 3971 I ExposureNotification: User turn off scanning. [CONTEXT service_id=236 ] 07-02 08:33:10.983 3971 3971 I ExposureNotification: Not scanning! [CONTEXT service_id=236 ] 07-02 08:33:10.983 3971 3982 D BluetoothAdapter: STATE_BLE_ON 07-02 08:33:10.993 3971 3982 I ExposureNotification: Utils#isSupported enabled=true, isDeviceSupported=true, isBluetoothSupported=true, BluetoothAdapter.isMultipleAdvertisementSupported=false [CONTEXT servi 07-02 08:33:11.003 3971 3971 I ExposureNotification: ThreadSafeLevelDbWrapper: do open LevelDb contact-tracing-self-tracing-key-db [CONTEXT service_id=236 ] 07-02 08:33:11.003 3971 3971 I ExposureNotification: ThreadSafeLevelDbWrapper:contact-tracing-self-tracing-key-db instance btz@cf87b78 created [CONTEXT service_id=236 ] 07-02 08:33:11.033 813 2141 D ApplicationPolicy: isStatusBarNotificationAllowedAsUser: packageName = com.google.android.gms,userId = 0 07-02 08:33:11.033 18663 4081 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/de.rki.coronawarnapp/cache/http_cache/9fe44808aba26677253c42a986222fcd.0 07-02 08:33:11.033 18663 4081 W System.err: remove failed: ENOENT (No such file or directory) : /data/user/0/de.rki.coronawarnapp/cache/http_cache/9fe44808aba26677253c42a986222fcd.1 [...] ```

aurisnoctis commented 4 years ago

Additional info for https://github.com/corona-warn-app/cwa-app-android/issues/788#issuecomment-652871916: Internet connection was working on phone before during and after these errors occurred (WiFi at home).

jakobmoellerdev commented 4 years ago

Thanks for the detailed info. I will update once we investigated more closely and can give more input

aurisnoctis commented 4 years ago

Update to https://github.com/corona-warn-app/cwa-app-android/issues/788#issuecomment-652871916

Today is the first day in CWA version 1.0.4 that the last update (Aktualisiert:) was not incremented.

Today I 'only' had the Google API(10) error [1], mentioned in the Update of https://github.com/corona-warn-app/cwa-app-android/issues/737#issuecomment-652873704, but no Google API(20) error. Aktualisiert: changed from day before yesterday, 11:34 to Today, 8:48.

So it was presumably indeed the Google API(20) error causing the Aktualisiert: not to be updated.

[1] Have to take that back. While writing this comment another error came up that I have never seen before. Will report elsewhere.

Edit: This time I made sure not only internet is working, but also that bluetooth and location are switched on even though that shouldn't be necessary for the risk update. As with BT and location on, API(20) didn't appear today, I will keep an eye on that.

Edit 2: Today (05 Jul 2020) only the Google API(10) error appeared when opening the app and this time it also caused Aktualisiert: not to be updated. So that behavior is not unique to the Google API(20) error.

morkro commented 4 years ago

I just want add that I am also encountering this error today. I haven't opened the app in 2-3 days and never changed anything after the initial setup 14 days ago.

I closed the app two times and on the third try the error message went away and the app updated again.

Kaveh01 commented 4 years ago

I observed the same error on my LG g7. "Cause 3 something went wrong" ...

aurisnoctis commented 4 years ago

Haven't encountered this error recently. It seems CWA version 1.1.1 either avoids the Google API errors or the notifications, so I close this issue.

corona-warn-app / cwa-app-android