Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

'Unable to connect to service' phone change/registration #12839

Closed
Striker789 opened this issue Mar 11, 2023 · 35 comments
Closed

'Unable to connect to service' phone change/registration #12839

Striker789 opened this issue Mar 11, 2023 · 35 comments

Comments

@Striker789
Copy link

Striker789 commented Mar 11, 2023

Hello everyone,

I was changing my phone and after doing a backup and was trying to register my phone I received the following message:
'Unable to connect to service. Please check network connection and try again'.

I have tried several times but unfortunately I keep getting blocked for a set amount of time after trying to register.

I very much hope that this issue can be fixed as fast as possible.

Thank you in advance.
Edit: I use a Samsung device SM-S918 and Signal is downloaded from the playstore.

@axl303
Copy link

axl303 commented Mar 12, 2023

Same thing here,
I am a new user to Signal (never used it before) trying to register with the latest from Playstore
"'Unable to connect to service. Please check network connection and try again'."
Tried with latest version also "6.14.2", still no luck.
Can this get fixed or how can I register somehow?
Using Android Phone P30 Pro Huawei Latest updates, permissions everything enabled and tried - no luck.

@vlntnzl
Copy link

vlntnzl commented Mar 12, 2023

The same problem also with me with a new number.

Signal version: 6.13.6
OS: Android 13

I have to confirm after entering the new number two times (!) that I am a human.
The first time it is just a checkbox and then the second time I am redirected to hCaptcha.
Then I confirm this captcha successfully and want to continue, but now I am blocked for registering too often.

Workaround: Use another random number and select the correct number after the second hCaptcha requests.

Then I receive the registration SMS and can enter the code, and get the message: Error connecting to service. After entering the Signal security code correctly several times, I get blocked here as well.

@alex-signal
Copy link
Contributor

Hi guys! Unfortunately we're currently in Play Store release purgatory. This issue is fixed in 6.14.

@yarsiemanym
Copy link

I'm encountering this too. Will 6.14 fix this for me too or am I encountering something else?

Signal Version: 6.13.6
OS: GrapheneOS (Android 13)
Google Play Services: Sandboxed

adb logcat shows the following exception during registration.

03-13 10:10:05.668 12933 12933 I AccountValues: Setting push registered: false
03-13 10:10:05.668 12933 12933 I AccountValues: java.lang.Throwable
03-13 10:10:05.668 12933 12933 I AccountValues: at org.thoughtcrime.securesms.keyvalue.AccountValues.setRegistered(AccountValues.kt:303)
03-13 10:10:05.668 12933 12933 I AccountValues: at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.lambda$requestVerificationCode$10(EnterPhoneNumberFragment.java:280)
03-13 10:10:05.668 12933 12933 I AccountValues: at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.$r8$lambda$WGT27_vWweIdZZJAosa47SPLeug(Unknown Source:0)
03-13 10:10:05.668 12933 12933 I AccountValues: at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment$$ExternalSyntheticLambda17.accept(Unknown Source:2)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSubscribe$DoOnSubscribeSingleObserver.onSubscribe(SingleDoOnSubscribe.java:60)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess$DoOnSuccess.onSubscribe(SingleDoOnSuccess.java:48)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn$ObserveOnSingleObserver.onSubscribe(SingleObserveOn.java:57)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleMap$MapSingleObserver.onSubscribe(SingleMap.java:51)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleJust.subscribeActual(SingleJust.java:29)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleMap.subscribeActual(SingleMap.java:35)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess.subscribeActual(SingleDoOnSuccess.java:35)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSubscribe.subscribeActual(SingleDoOnSubscribe.java:41)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4799)
03-13 10:10:05.668 12933 12933 I AccountValues: at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4768)
03-13 10:10:05.668 12933 12933 I AccountValues: at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.requestVerificationCode(EnterPhoneNumberFragment.java:282)
03-13 10:10:05.668 12933 12933 I AccountValues: at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.lambda$handleRequestVerification$8(EnterPhoneNumberFragment.java:240)
03-13 10:10:05.668 12933 12933 I AccountValues: at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.$r8$lambda$pGuOcF6EfwS85Ol4ZxNoz3kSSt0(Unknown Source:0)
03-13 10:10:05.668 12933 12933 I AccountValues: at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment$$ExternalSyntheticLambda8.onSuccess(Unknown Source:8)
03-13 10:10:05.668 12933 12933 I AccountValues: at com.google.android.gms.tasks.zzm.run(com.google.android.gms:play-services-tasks@@18.0.1:1)
03-13 10:10:05.668 12933 12933 I AccountValues: at android.os.Handler.handleCallback(Handler.java:942)
03-13 10:10:05.668 12933 12933 I AccountValues: at android.os.Handler.dispatchMessage(Handler.java:99)
03-13 10:10:05.668 12933 12933 I AccountValues: at android.os.Looper.loopOnce(Looper.java:201)
03-13 10:10:05.668 12933 12933 I AccountValues: at android.os.Looper.loop(Looper.java:288)
03-13 10:10:05.668 12933 12933 I AccountValues: at android.app.ActivityThread.main(ActivityThread.java:7879)
03-13 10:10:05.668 12933 12933 I AccountValues: at java.lang.reflect.Method.invoke(Native Method)
03-13 10:10:05.668 12933 12933 I AccountValues: at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:548)
03-13 10:10:05.668 12933 12933 I AccountValues: at com.android.internal.os.ExecInit.main(ExecInit.java:49)
03-13 10:10:05.668 12933 12933 I AccountValues: at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
03-13 10:10:05.668 12933 12933 I AccountValues: at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:355)

@cody-signal
Copy link
Contributor

I'm encountering this too. Will 6.14 fix this for me too or am I encountering something else?

@yarsiemanym yes, it's highly likely

@Floessie
Copy link

Same problem here. When trying to change the phone, I first got the "Unable to connect to service. Please check network connection and try again" after entering the verification code. So I tried again, got a second code which failed with the same message. Now I always get a time penalty after the two hCaptchas, no matter how long I wait (e.g. it says "Try again after 04:31:15" and I try again after 10 hours the next morning). I've already reached out to Signal support, but they only replied with a standard message and kept silent from then on. :unamused:

Fun fact: Trying to restore the Signal registration on the old phone yields the same error (two hCaptchas, immediate time penalty), so I'm basically locked out from Signal and have to resort to worse alternatives.

It's great to see that I'm not alone in the boat.

Fresh LineageOS 19.1 on beyond1lte with Signal 6.13.6 installed via browser from https://signal.org/android/apk/. Migrating other messengers went smoothly (as did Signal about a year ago), so I'd rule out the OS.

Best,
Flössie

@cody-signal
Copy link
Contributor

cody-signal commented Mar 14, 2023

For everyone encountering this issue, we've pushed a 6.13.7 to our website. Please try with that build as it has the hopefully fixes for this problem. It'll also be available via Play Store once it's approved.

@Floessie
Copy link

I can confirm that 6.13.7 fixes the registration problem. 🎉 Thanks a lot. 👍

There were still two oddities:

  1. After the first round of Captchas I got the "no service" message. I tried again and the second time I got the verification SMS.
  2. There was a disturbing message saying I had used up all of my PIN entries (and that my account would be cleared for security reasons) and was forced to set a new PIN. Fact is, I didn't have a Signal PIN in the first place. My backup was applied as the very first installation step, so why force a PIN later? After the forced PIN step I regained access to my account with my local backup in place and could disable the PIN again.

HTH,
Flössie

@yarsiemanym
Copy link

yarsiemanym commented Mar 14, 2023

I updated to version 6.13.7 from the Google Play Store and it didn't solve the problem for me. I do not see a Captcha. I click Continue, it attempts to send me a verification code, and fails with "Unable to connect to service." I do not receive a verification code. After a couple of attempts, it puts me in timeout for a few hours.

Here are the logs from adb logcat --pid=<pid>

03-14 14:34:39.924 23553 23553 W Zygote  : Can't access app profile directory: /data_mirror/cur_profiles/0/org.thoughtcrime.securesms
03-14 14:34:39.944 23553 23553 E crime.securesms: Not starting debugger since process cannot load the jdwp agent.
03-14 14:34:40.067 23553 23553 D AndroidRuntime: >>>>>> START com.android.internal.os.RuntimeInit uid 10278 <<<<<<
03-14 14:34:40.071 23553 23553 I AndroidRuntime: Using default boot image
03-14 14:34:40.071 23553 23553 I AndroidRuntime: Leaving lock profiling enabled
03-14 14:34:40.071 23553 23553 W libc    : Access denied finding property "odsign.verification.success"
03-14 14:34:40.066 23553 23553 W crime.securesms: type=1400 audit(0.0:121772): avc: denied { read } for name="u:object_r:odsign_prop:s0" dev="tmpfs" ino=9738 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:odsign_prop:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 14:34:40.075 23553 23553 W org.thoughtcrime.securesms: ART APEX data files are untrusted.
03-14 14:34:40.195 23553 23553 D org.thoughtcrime.securesms: Time zone APEX ICU file found: /apex/com.android.tzdata/etc/icu/icu_tzdata.dat
03-14 14:34:40.195 23553 23553 D org.thoughtcrime.securesms: I18n APEX ICU file found: /apex/com.android.i18n/etc/icu/icudt70l.dat
03-14 14:34:40.227 23553 23553 D AndroidRuntime: Calling main entry com.android.internal.os.ExecInit
03-14 14:34:40.227 23553 23553 D Zygote  : begin preload
03-14 14:34:40.228 23553 23553 I Zygote  : Calling ZygoteHooks.beginPreload()
03-14 14:34:40.230 23553 23553 D libEGL  : loaded /vendor/lib64/egl/libEGL_adreno.so
03-14 14:34:40.233 23553 23553 D libEGL  : loaded /vendor/lib64/egl/libGLESv1_CM_adreno.so
03-14 14:34:40.237 23553 23553 D libEGL  : loaded /vendor/lib64/egl/libGLESv2_adreno.so
03-14 14:34:40.260 23553 23553 I Zygote  : Preloading shared libraries...
03-14 14:34:40.263 23553 23553 E org.thoughtcrime.securesms: Unable to find pattern file or unable to map it for am
03-14 14:34:40.263 23553 23553 I Zygote  : Called ZygoteHooks.endPreload()
03-14 14:34:40.277 23553 23553 I Zygote  : Installed AndroidKeyStoreProvider in 14ms.
03-14 14:34:40.277 23553 23553 D Zygote  : end preload
03-14 14:34:40.283 23553 23553 W binder:23553_2: type=1400 audit(0.0:121786): avc: denied { read } for name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=9756 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 14:34:40.286 23553 23553 W binder:23553_2: type=1400 audit(0.0:121787): avc: denied { read } for name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=9756 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 14:34:40.288 23553 23605 W libc    : Access denied finding property "qemu.sf.lcd_density"
03-14 14:34:40.288 23553 23605 W libc    : Access denied finding property "qemu.sf.lcd_density"
03-14 14:34:40.302 23553 23553 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10278; state: ENABLED
03-14 14:34:40.302 23553 23553 I Typeface: Preloading /system/fonts/Roboto-Regular.ttf
03-14 14:34:40.302 23553 23553 I Typeface: Preloading /system/fonts/RobotoStatic-Regular.ttf
03-14 14:34:40.336 23553 23553 W crime.securesms: type=1400 audit(0.0:121788): avc: denied { read } for name="apex-info-list.xml" dev="tmpfs" ino=11315 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:apex_info_file:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 14:34:40.350 23553 23553 W org.thoughtcrime.securesms: unable to execute idmap2: Permission denied
03-14 14:34:40.350 23553 23553 W OverlayConfig: 'idmap2 create-multiple' failed: no mutable="false" overlays targeting "android" will be loaded
03-14 14:34:40.346 23553 23553 W crime.securesms: type=1400 audit(0.0:121789): avc: denied { execute } for name="idmap2" dev="dm-8" ino=1398 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:idmap_exec:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 14:34:40.567 23553 23553 V GraphicsEnvironment: ANGLE Developer option for 'org.thoughtcrime.securesms' set to: 'default'
03-14 14:34:40.567 23553 23553 V GraphicsEnvironment: ANGLE GameManagerService for org.thoughtcrime.securesms: false
03-14 14:34:40.567 23553 23553 V GraphicsEnvironment: Updatable production driver is not supported on the device.
03-14 14:34:40.570 23553 23553 D NetworkSecurityConfig: No Network Security Config specified, using platform default
03-14 14:34:40.571 23553 23553 D NetworkSecurityConfig: No Network Security Config specified, using platform default
03-14 14:34:40.577 23553 23553 I MultiDex: VM with version 2.1.0 has multidex support
03-14 14:34:40.578 23553 23553 I MultiDex: Installing application
03-14 14:34:40.578 23553 23553 I MultiDex: VM has multidex support, MultiDex support library is disabled.
03-14 14:34:40.588 23553 23553 D CompatibilityChangeReporter: Compat change id reported: 183155436; UID 10278; state: DISABLED
03-14 14:34:40.588 23553 23553 I FirebaseApp: Device unlocked: initializing all Firebase APIs for app [DEFAULT]
03-14 14:34:40.596 23553 23553 I FirebaseInitProvider: FirebaseApp initialization successful
03-14 14:34:40.597 23553 23553 I PartProvider: onCreate()
03-14 14:34:40.598 23553 23553 I BlobContentProvider: onCreate()
03-14 14:34:40.636 23553 23553 I ApplicationContext: Installed AesGcmProvider: 1
03-14 14:34:40.700 23553 23553 V NativeCrypto: Registering org/conscrypt/NativeCrypto's 284 native methods...
03-14 14:34:40.706 23553 23553 I ApplicationContext: Installed Conscrypt provider: 2
03-14 14:34:40.788 23553 23553 I ApplicationContext: onCreate()
03-14 14:34:40.804 23553 23635 I CellServiceConstraintOb: [API 33] Cell service available.
03-14 14:34:40.812 23553 23553 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:40.831 23553 23553 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:40.836 23553 23553 D ApplicationMigrations: Not an update. Skipping.
03-14 14:34:40.838 23553 23637 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:40.842 23553 23630 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:40.847 23553 23553 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:40.863 23553 23637 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:40.864 23553 23630 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:40.865 23553 23637 I JobDatabase: onOpen()
03-14 14:34:40.866 23553 23553 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:40.867 23553 23553 I KeyValueDatabase: onOpen()
03-14 14:34:40.871 23553 23639 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:40.884 23553 23553 I RegistrationUtil: Registration is not yet complete.
03-14 14:34:40.884 23553 23553 I RegistrationUtil: java.lang.Throwable
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at org.thoughtcrime.securesms.registration.RegistrationUtil.maybeMarkRegistrationComplete(RegistrationUtil.java:37)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at org.thoughtcrime.securesms.ApplicationContext.lambda$onCreate$3(ApplicationContext.java:165)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at org.thoughtcrime.securesms.ApplicationContext.$r8$lambda$wIlBIGBTTiiajQBAE1x8xR74OLI(Unknown Source:0)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at org.thoughtcrime.securesms.ApplicationContext$$ExternalSyntheticLambda55.run(Unknown Source:0)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at org.thoughtcrime.securesms.util.AppStartup.execute(AppStartup.java:145)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at org.thoughtcrime.securesms.ApplicationContext.onCreate(ApplicationContext.java:215)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at android.app.Instrumentation.callApplicationOnCreate(Instrumentation.java:1282)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at android.app.ActivityThread.handleBindApplication(ActivityThread.java:6766)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at android.app.ActivityThread.-$$Nest$mhandleBindApplication(Unknown Source:0)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2134)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at android.os.Handler.dispatchMessage(Handler.java:106)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at android.os.Looper.loopOnce(Looper.java:201)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at android.os.Looper.loop(Looper.java:288)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at android.app.ActivityThread.main(ActivityThread.java:7879)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at java.lang.reflect.Method.invoke(Native Method)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:548)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at com.android.internal.os.ExecInit.main(ExecInit.java:49)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
03-14 14:34:40.884 23553 23553 I RegistrationUtil: 	at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:355)
03-14 14:34:40.889 23553 23553 I IncomingMessageObserver: Initializing! (132507479)
03-14 14:34:40.890 23553 23641 I IncomingMessageObserver: Waiting for websocket state change....
03-14 14:34:40.892 23553 23553 D DynamicTheme: Setting to follow system expecting: 32
03-14 14:34:40.893 23553 23553 D CachedInflater: Clearing view cache.
03-14 14:34:40.894 23553 23641 D IncomingMessageObserver: Network: true, Foreground: false, FCM: true, Stay open requests: [], Censored: false, Registered: false, Proxy: false, Force websocket: false
03-14 14:34:40.901 23553 23553 I FeatureFlags: init() {}
03-14 14:34:40.901 23553 23553 D CallManager: Loading ringrtc library
03-14 14:34:40.903 23553 23642 D BlobProvider: No attachment drafts exist. Skipping.
03-14 14:34:40.903 23553 23642 I BlobProvider: Initialized.
03-14 14:34:40.919 23553 23553 I CallManager: CallManager.initialize(): (release build, field trials = )
03-14 14:34:40.920 23553 23553 I org.webrtc.Logging: NativeLibrary: Loading native library: jingle_peerconnection_so
03-14 14:34:40.923 23553 23553 I CallManager: CallManager.initialize() returned
03-14 14:34:40.924 23553 23553 D AppStartup: [init] security-provider: 72  sqlcipher-init: 80  logging: 2  crash-handling: 0  rx-init: 1  event-bus: 0  app-dependencies: 0  first-launch: 0  app-migrations: 47  mark-registration: 48  lifecycle-observer: 0  message-retriever: 7  dynamic-theme: 2  proxy-init: 0  blob-provider: 7  feature-flags: 1  ring-rtc: 22  glide: 1  schedule-non-blocking: 0  total: 290
03-14 14:34:40.924 23553 23553 D ApplicationContext: onCreate() took 293 ms
03-14 14:34:40.934 23553 23642 I PreKeysSyncJob: Some signed prekeys aren't registered yet. Enqueuing a job. ACI: false PNI: false
03-14 14:34:40.934 23553 23639 I PersistentAlarmManagerL: [RotateSignedPreKeyListener] onReceive(signal.ACTION_SCHEDULE)
03-14 14:34:40.936 23553 23639 D CompatibilityChangeReporter: Compat change id reported: 160794467; UID 10278; state: ENABLED
03-14 14:34:40.937 23553 23644 I ViewOnceMessageManager: No messages to schedule.
03-14 14:34:40.938 23553 23639 I PersistentAlarmManagerL: [RotateSignedPreKeyListener] scheduling alarm for: 1678937474087
03-14 14:34:40.941 23553 23648 D PendingRetryReceiptMana: No pending receipts to schedule.
03-14 14:34:40.942 23553 23639 I PersistentAlarmManagerL: [DirectoryRefreshListener] onReceive(signal.ACTION_SCHEDULE)
03-14 14:34:40.943 23553 23639 I PersistentAlarmManagerL: [DirectoryRefreshListener] scheduling alarm for: 1678937474090
03-14 14:34:40.943 23553 23638 D StorageSyncHelper: Scheduling a sync. Last sync was 1678818880943 ms ago.
03-14 14:34:40.943 23553 23638 D StorageSyncHelper: Registration still ongoing. Ignore sync request.
03-14 14:34:40.945 23553 23635 I ApplicationContext: Deleted 0 abandoned attachments.
03-14 14:34:40.946 23553 23639 I PersistentAlarmManagerL: [RotateSenderCertificateListener] onReceive(signal.ACTION_SCHEDULE)
03-14 14:34:40.946 23553 23639 I PersistentAlarmManagerL: [RotateSenderCertificateListener] scheduling alarm for: 1678851074169
03-14 14:34:40.950 23553 23639 I MessageProcessReceiver: Alarm scheduled to repeat at interval 21600000
03-14 14:34:40.952 23553 23553 I AppStartup: Received first critical render event.
03-14 14:34:40.955 23553 23553 D DynamicTheme: Previous night mode has changed previous: 0 now: 32
03-14 14:34:40.955 23553 23553 D CachedInflater: Clearing view cache.
03-14 14:34:40.962 23553 23553 D PassphraseRequiredActiv: routeApplicationState(), state: 4
03-14 14:34:40.976 23553 23642 I Job     : [JOB::c6b5d24c-71ce-49cf-bc1a-dd34181d3e7d][PreKeysSyncJob] onSubmit() (Time Since Submission: 42 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: PreKeysSyncJob)
03-14 14:34:40.977 23553 23553 D BaseActivity: [MainActivity] onCreate()
03-14 14:34:40.978 23553 23639 I JobSchedulerScheduler: JobScheduler enqueue of NETWORK (-1733499378)
03-14 14:34:40.980 23553 23639 D CompatibilityChangeReporter: Compat change id reported: 194532703; UID 10278; state: DISABLED
03-14 14:34:40.993 23553 23553 I AppStartup: First render has finished. Cold Start: 363 ms, Render Time: 41 ms
03-14 14:34:41.006 23553 23629 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:41.015 23553 23629 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:41.019 23553 23639 D EmojiSearchIndexDownloa: Do not need to check. It's been 54204342 ms since the last check.
03-14 14:34:41.021 23553 23639 I RetrieveRemoteAnnouncem: Not registered, skipping.
03-14 14:34:41.021 23553 23639 I AndroidTelecomUtil: Registering phone account
03-14 14:34:41.022 23553 23652 I JobRunner: [JOB::c6b5d24c-71ce-49cf-bc1a-dd34181d3e7d][PreKeysSyncJob][1] Running job. (Time Since Submission: 82 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: PreKeysSyncJob)
03-14 14:34:41.023 23553 23553 I JobManager: onConstraintMet(NetworkConstraintObserv)
03-14 14:34:41.023 23553 23642 W GroupV2UpdateSelfProfil: Not yet registered!
03-14 14:34:41.023 23553 23638 I Job     : [JOB::1f248209-b738-426f-a1d4-488bfdeddadf][FontDownloaderJob] onSubmit() (Time Since Submission: 1 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: null)
03-14 14:34:41.023 23553 23642 D StoryOnboardingDownload: Attempting to enqueue StoryOnboardingDownloadJob...
03-14 14:34:41.025 23553 23652 W PreKeysSyncJob: [JOB::c6b5d24c-71ce-49cf-bc1a-dd34181d3e7d][PreKeysSyncJob] Not yet registered (Time Since Submission: 85 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: PreKeysSyncJob)
03-14 14:34:41.026 23553 23654 I JobRunner: [JOB::1f248209-b738-426f-a1d4-488bfdeddadf][FontDownloaderJob][2] Running job. (Time Since Submission: 4 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: null)
03-14 14:34:41.026 23553 23652 I JobRunner: [JOB::c6b5d24c-71ce-49cf-bc1a-dd34181d3e7d][PreKeysSyncJob][1] Job finished with result SUCCESS in 4 ms. (Time Since Submission: 86 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: PreKeysSyncJob)
03-14 14:34:41.027 23553 23629 D LocalMetrics: [cold-start-other] total: 362 | application-create: 293, start-activity: 28, render: 41
03-14 14:34:41.027 23553 23641 D IncomingMessageObserver: Network: true, Foreground: false, FCM: true, Stay open requests: [], Censored: false, Registered: false, Proxy: false, Force websocket: false
03-14 14:34:41.028 23553 23642 I Job     : [JOB::31291d15-dee8-470a-be49-1f38d9a32af9][CreateReleaseChannelJob] onSubmit() (Time Since Submission: 4 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: CreateReleaseChannelJob)
03-14 14:34:41.028 23553 23642 I Job     : [JOB::a38efea9-1c0b-4fca-bfcc-e4023a2974ad][StoryOnboardingDownloadJob] onSubmit() (Time Since Submission: 4 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: StoryOnboardingDownloadJob)
03-14 14:34:41.028 23553 23631 D LogDatabase: [trim] keepers-size: 9  binary-search: 94  delete: 0  total: 103
03-14 14:34:41.029 23553 23655 I JobRunner: [JOB::31291d15-dee8-470a-be49-1f38d9a32af9][CreateReleaseChannelJob][3] Running job. (Time Since Submission: 2 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: CreateReleaseChannelJob)
03-14 14:34:41.041 23553 23655 I CreateReleaseChannelJob: Not registered, skipping.
03-14 14:34:41.044 23553 23655 I JobRunner: [JOB::31291d15-dee8-470a-be49-1f38d9a32af9][CreateReleaseChannelJob][3] Job finished with result SUCCESS in 15 ms. (Time Since Submission: 17 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: CreateReleaseChannelJob)
03-14 14:34:41.044 23553 23654 D Fonts   : Getting Script for Latn
03-14 14:34:41.063 23553 23655 I JobRunner: [JOB::a38efea9-1c0b-4fca-bfcc-e4023a2974ad][StoryOnboardingDownloadJob][3] Running job. (Time Since Submission: 36 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: StoryOnboardingDownloadJob)
03-14 14:34:41.065 23553 23639 I AndroidTelecomUtil: Phone account registered successfully
03-14 14:34:41.066 23553 23639 I JobSchedulerScheduler: JobScheduler enqueue of  (0)
03-14 14:34:41.069 23553 23655 W StoryOnboardingDownload: Cannot create story onboarding without release channel recipient.
03-14 14:34:41.069 23553 23655 W BaseJob : [JOB::a38efea9-1c0b-4fca-bfcc-e4023a2974ad][StoryOnboardingDownloadJob] Encountered a failing exception. (Time Since Submission: 42 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: StoryOnboardingDownloadJob)
03-14 14:34:41.069 23553 23655 W BaseJob : java.lang.Exception: No release channel recipient.
03-14 14:34:41.069 23553 23655 W BaseJob : 	at org.thoughtcrime.securesms.jobs.StoryOnboardingDownloadJob.onRun(StoryOnboardingDownloadJob.kt:78)
03-14 14:34:41.069 23553 23655 W BaseJob : 	at org.thoughtcrime.securesms.jobs.BaseJob.run(BaseJob.java:32)
03-14 14:34:41.069 23553 23655 W BaseJob : 	at org.thoughtcrime.securesms.jobmanager.JobRunner.run(JobRunner.java:86)
03-14 14:34:41.069 23553 23655 W BaseJob : 	at org.thoughtcrime.securesms.jobmanager.JobRunner.run(JobRunner.java:49)
03-14 14:34:41.070 23553 23655 W JobRunner: [JOB::a38efea9-1c0b-4fca-bfcc-e4023a2974ad][StoryOnboardingDownloadJob][3] Job failed. (Time Since Submission: 43 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: StoryOnboardingDownloadJob)
03-14 14:34:41.085 23553 23553 D LoggingFragment: [WelcomeFragment] onCreate()
03-14 14:34:41.088 23553 23553 D SmsRetrieverReceiver: Registering SMS retriever receiver
03-14 14:34:41.119 23553 23654 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 14:34:41.119 23553 23654 I FontVersion: Deleting 0 files
03-14 14:34:41.131 23553 23642 D MtpDeviceJNI: register_android_mtp_MtpDevice
03-14 14:34:41.139 23553 23553 D CompatibilityChangeReporter: Compat change id reported: 210923482; UID 10278; state: DISABLED
03-14 14:34:41.140 23553 23553 D CompatibilityChangeReporter: Compat change id reported: 37756858; UID 10278; state: ENABLED
03-14 14:34:41.161 23553 23642 W VideoCapabilities: Unrecognized level 1879048707 for video/x-vnd.on2.vp8
03-14 14:34:41.161 23553 23642 W VideoCapabilities: Unrecognized profile 1879048704 for video/x-vnd.on2.vp8
03-14 14:34:41.161 23553 23642 W VideoCapabilities: Unrecognized level 1879048707 for video/x-vnd.on2.vp8
03-14 14:34:41.161 23553 23642 W VideoCapabilities: Unrecognized profile 1879048704 for video/x-vnd.on2.vp8
03-14 14:34:41.163 23553 23642 W VideoCapabilities: Unsupported mime image/vnd.android.heic
03-14 14:34:41.163 23553 23642 W VideoCapabilities: Unrecognized level 1879048707 for video/x-vnd.on2.vp8
03-14 14:34:41.163 23553 23642 W VideoCapabilities: Unrecognized profile 1879048704 for video/x-vnd.on2.vp8
03-14 14:34:41.163 23553 23642 W VideoCapabilities: Unrecognized level 1879048707 for video/x-vnd.on2.vp8
03-14 14:34:41.163 23553 23642 W VideoCapabilities: Unrecognized profile 1879048704 for video/x-vnd.on2.vp8
03-14 14:34:41.165 23553 23654 D Fonts   : Loaded manifest.
03-14 14:34:41.166 23553 23654 D Fonts   : Getting Script for LATIN
03-14 14:34:41.166 23553 23654 D Fonts   : Loaded script for locale.
03-14 14:34:41.166 23553 23635 D EmojiFiles: Verifying all name files exist.
03-14 14:34:41.175 23553 23654 D Fonts   : Local font version found, returning immediate.
03-14 14:34:41.182 23553 23635 D EmojiFiles: All names exist? true
03-14 14:34:41.184 23553 23654 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 14:34:41.184 23553 23654 I FontVersion: Deleting 0 files
03-14 14:34:41.186 23553 23654 D Fonts   : Loaded manifest.
03-14 14:34:41.186 23553 23654 D Fonts   : Getting Script for LATIN
03-14 14:34:41.186 23553 23654 D Fonts   : Loaded script for locale.
03-14 14:34:41.187 23553 23654 D Fonts   : Local font version found, returning immediate.
03-14 14:34:41.189 23553 23553 D LoggingFragment: [WelcomeFragment] onStart()
03-14 14:34:41.190 23553 23553 I ApplicationContext: App is now visible.
03-14 14:34:41.191 23553 23553 D FrameRateTracker: Beginning frame rate tracking. Screen refresh rate: 60.00 hz, or 16.67 ms per frame.
03-14 14:34:41.193 23553 23553 D DeadlockDetector: Beginning deadlock monitoring.
03-14 14:34:41.193 23553 23553 D ApplicationContext: onStart() took 3 ms
03-14 14:34:41.194 23553 23639 I FeatureFlags: Scheduling remote config refresh.
03-14 14:34:41.194 23553 23641 D IncomingMessageObserver: Network: true, Foreground: true, FCM: true, Stay open requests: [], Censored: false, Registered: false, Proxy: false, Force websocket: false
03-14 14:34:41.199 23553 23639 I Job     : [JOB::579afcd9-1a99-4907-907f-837d20a2755d][RemoteConfigRefreshJob] onSubmit() (Time Since Submission: 2 ms, Lifespan: 86400000 ms, Run Attempt: 1/Unlimited, Queue: RemoteConfigRefreshJob)
03-14 14:34:41.201 23553 23654 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 14:34:41.201 23553 23654 I FontVersion: Deleting 0 files
03-14 14:34:41.205 23553 23656 I JobRunner: [JOB::579afcd9-1a99-4907-907f-837d20a2755d][RemoteConfigRefreshJob][4] Running job. (Time Since Submission: 11 ms, Lifespan: 86400000 ms, Run Attempt: 1/Unlimited, Queue: RemoteConfigRefreshJob)
03-14 14:34:41.207 23553 23639 I RetrieveProfileJob: Registration not complete. Skipping.
03-14 14:34:41.207 23553 23654 D Fonts   : Loaded manifest.
03-14 14:34:41.208 23553 23654 D Fonts   : Getting Script for LATIN
03-14 14:34:41.208 23553 23654 D Fonts   : Loaded script for locale.
03-14 14:34:41.208 23553 23677 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:41.209 23553 23654 D Fonts   : Local font version found, returning immediate.
03-14 14:34:41.210 23553 23656 W RemoteConfigRefreshJob: Not registered. Skipping.
03-14 14:34:41.212 23553 23656 I JobRunner: [JOB::579afcd9-1a99-4907-907f-837d20a2755d][RemoteConfigRefreshJob][4] Job finished with result SUCCESS in 7 ms. (Time Since Submission: 18 ms, Lifespan: 86400000 ms, Run Attempt: 1/Unlimited, Queue: RemoteConfigRefreshJob)
03-14 14:34:41.219 23553 23677 I SQLiteConnection: Database keying operation returned:0
03-14 14:34:41.219 23553 23642 D LiveRecipientCache: Warming up 0 thread recipients.
03-14 14:34:41.221 23553 23654 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 14:34:41.221 23553 23642 D LiveRecipientCache: [recipient-warm-up] thread: 20  
03-14 14:34:41.221 23553 23654 I FontVersion: Deleting 0 files
03-14 14:34:41.222 23553 23654 D Fonts   : Loaded manifest.
03-14 14:34:41.222 23553 23654 D Fonts   : Getting Script for LATIN
03-14 14:34:41.222 23553 23654 D Fonts   : Loaded script for locale.
03-14 14:34:41.222 23553 23654 D Fonts   : Local font version found, returning immediate.
03-14 14:34:41.224 23553 23677 I MegaphoneDatabase: onOpen()
03-14 14:34:41.225 23553 23553 I JobSchedulerScheduler: Waking due to job: -1733499378
03-14 14:34:41.226 23553 23654 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 14:34:41.226 23553 23654 I FontVersion: Deleting 0 files
03-14 14:34:41.226 23553 23654 D Fonts   : Loaded manifest.
03-14 14:34:41.226 23553 23654 D Fonts   : Getting Script for LATIN
03-14 14:34:41.226 23553 23654 D Fonts   : Loaded script for locale.
03-14 14:34:41.226 23553 23654 D Fonts   : Local font version found, returning immediate.
03-14 14:34:41.227 23553 23553 I JobSchedulerScheduler: Waking due to job: 0
03-14 14:34:41.234 23553 23654 I FontDownloaderJob: Already downloaded fonts for locale.
03-14 14:34:41.237 23553 23654 I JobRunner: [JOB::1f248209-b738-426f-a1d4-488bfdeddadf][FontDownloaderJob][2] Job finished with result SUCCESS in 211 ms. (Time Since Submission: 215 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: null)
03-14 14:34:41.272 23553 23646 I AdrenoGLES-0: QUALCOMM build                   : 4783c89, I46ff5fc46f
03-14 14:34:41.272 23553 23646 I AdrenoGLES-0: Build Date                       : 11/30/20
03-14 14:34:41.272 23553 23646 I AdrenoGLES-0: OpenGL ES Shader Compiler Version: EV031.31.04.01
03-14 14:34:41.272 23553 23646 I AdrenoGLES-0: Local Branch                     : QPR2
03-14 14:34:41.272 23553 23646 I AdrenoGLES-0: Remote Branch                    : 
03-14 14:34:41.272 23553 23646 I AdrenoGLES-0: Remote Branch                    : 
03-14 14:34:41.272 23553 23646 I AdrenoGLES-0: Reconstruct Branch               : 
03-14 14:34:41.272 23553 23646 I AdrenoGLES-0: Build Config                     : S P 10.0.4 AArch64
03-14 14:34:41.272 23553 23646 I AdrenoGLES-0: Driver Path                      : /vendor/lib64/egl/libGLESv2_adreno.so
03-14 14:34:41.294 23553 23646 I AdrenoGLES-0: PFP: 0x016dd093, ME: 0x00000000
03-14 14:34:41.307 23553 23646 W AdrenoUtils: <ReadGpuID_from_sysfs:197>: Failed to open /sys/class/kgsl/kgsl-3d0/gpu_model
03-14 14:34:41.307 23553 23646 W AdrenoUtils: <ReadGpuID:221>: Failed to read chip ID from gpu_model. Fallback to use the GSL path
03-14 14:34:41.325 23553 23646 E OpenGLRenderer: Unable to match the desired swap behavior.
03-14 14:34:41.376 23553 23646 W Parcel  : Expecting binder but got null!
03-14 14:34:41.377 23553 23553 V DeviceToDeviceTransferS: onCreate
03-14 14:34:41.392 23553 23553 I WifiDirect: Fine location permission required
03-14 14:34:41.392 23553 23553 I DeviceToDeviceTransferS: Shutdown
03-14 14:34:41.396 23553 23553 V DeviceToDeviceTransferS: onDestroy
03-14 14:34:41.418 23553 23553 D BaseActivity: [MainActivity] onDestroy()
03-14 14:34:46.757 23553 23721 D ProfileInstaller: Installing profile for org.thoughtcrime.securesms
03-14 14:35:22.542 23553 23754 I BackupUtil: Backup directory is not set. Returning an empty list.
03-14 14:35:22.565 23553 23553 I WelcomeFragment: Phone number detected
03-14 14:35:22.566 23553 23553 I WelcomeFragment: Skipping backup. No backup found, or no permission to look.
03-14 14:35:22.568 23553 23553 D LoggingFragment: [WelcomeFragment] onStop()
03-14 14:35:22.569 23553 23553 D LoggingFragment: [EnterPhoneNumberFragmen] onCreate()
03-14 14:35:22.573 23553 23553 D CompatibilityChangeReporter: Compat change id reported: 171228096; UID 10278; state: ENABLED
03-14 14:35:22.639 23553 23553 D CompatibilityChangeReporter: Compat change id reported: 163400105; UID 10278; state: DISABLED
03-14 14:35:22.645 23553 23553 D InputMethodManager: showSoftInput() view=com.google.android.material.textfield.TextInputEditText{30cc61c VFED..CL. .F....I. 0,0-0,0 aid=1073741824} flags=1 reason=SHOW_SOFT_INPUT
03-14 14:35:22.650 23553 23553 D LoggingFragment: [EnterPhoneNumberFragmen] onStart()
03-14 14:35:22.710 23553 23553 I AssistStructure: Flattened final assist data: 3436 bytes, containing 1 windows, 23 views
03-14 14:35:22.790 23553 23553 W OnBackInvokedCallback: OnBackInvokedCallback is not enabled for the application.
03-14 14:35:22.790 23553 23553 W OnBackInvokedCallback: Set 'android:enableOnBackInvokedCallback="true"' in the application manifest.
03-14 14:35:22.841 23553 23553 D InsetsController: show(ime(), fromIme=true)
03-14 14:35:23.970 23553 23553 I PlayServicesUtil: Play Services: 0
03-14 14:35:24.022 23553 23646 E OpenGLRenderer: Unable to match the desired swap behavior.
03-14 14:35:24.035 23553 23646 W Parcel  : Expecting binder but got null!
03-14 14:35:25.097 23553 23553 I RegistrationViewModel: Checking if user has existing recovery password: false
03-14 14:35:25.114 23553 23553 I EnterPhoneNumberFragmen: Unable to collect necessary data to enter skip flow, returning to normal
03-14 14:35:25.342 23553 23553 I EnterPhoneNumberFragmen: Successfully registered SMS listener.
03-14 14:35:25.346 23553 23553 I AccountValues: Setting push registered: false
03-14 14:35:25.346 23553 23553 I AccountValues: java.lang.Throwable
03-14 14:35:25.346 23553 23553 I AccountValues: 	at org.thoughtcrime.securesms.keyvalue.AccountValues.setRegistered(AccountValues.kt:303)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.lambda$requestVerificationCode$10(EnterPhoneNumberFragment.java:280)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.$r8$lambda$WGT27_vWweIdZZJAosa47SPLeug(Unknown Source:0)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment$$ExternalSyntheticLambda17.accept(Unknown Source:2)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSubscribe$DoOnSubscribeSingleObserver.onSubscribe(SingleDoOnSubscribe.java:60)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess$DoOnSuccess.onSubscribe(SingleDoOnSuccess.java:48)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn$ObserveOnSingleObserver.onSubscribe(SingleObserveOn.java:57)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleMap$MapSingleObserver.onSubscribe(SingleMap.java:51)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleJust.subscribeActual(SingleJust.java:29)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleMap.subscribeActual(SingleMap.java:35)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess.subscribeActual(SingleDoOnSuccess.java:35)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSubscribe.subscribeActual(SingleDoOnSubscribe.java:41)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4799)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4768)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.requestVerificationCode(EnterPhoneNumberFragment.java:282)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.lambda$handleRequestVerification$8(EnterPhoneNumberFragment.java:240)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.$r8$lambda$pGuOcF6EfwS85Ol4ZxNoz3kSSt0(Unknown Source:0)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment$$ExternalSyntheticLambda8.onSuccess(Unknown Source:8)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at com.google.android.gms.tasks.zzm.run(com.google.android.gms:play-services-tasks@@18.0.1:1)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at android.os.Handler.handleCallback(Handler.java:942)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at android.os.Handler.dispatchMessage(Handler.java:99)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at android.os.Looper.loopOnce(Looper.java:201)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at android.os.Looper.loop(Looper.java:288)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at android.app.ActivityThread.main(ActivityThread.java:7879)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at java.lang.reflect.Method.invoke(Native Method)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:548)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at com.android.internal.os.ExecInit.main(ExecInit.java:49)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
03-14 14:35:25.346 23553 23553 I AccountValues: 	at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:355)
03-14 14:35:25.347 23553 23641 D IncomingMessageObserver: Network: true, Foreground: true, FCM: true, Stay open requests: [], Censored: false, Registered: false, Proxy: false, Force websocket: false
03-14 14:35:25.371 23553 23650 I libsignal: rust/bridge/jni/src/logging.rs:156: Initializing libsignal version:0.22.0
03-14 14:35:25.426 23553 23600 W org.thoughtcrime.securesms: Cleared Reference was only reachable from finalizer (only reported once)
03-14 14:35:25.655 23553 23650 D TrafficStats: tagSocket(115) with statsTag=0xffffffff, statsUid=-1
03-14 14:35:31.338 23553 23650 D BaseRegistrationViewMod: Handling challenge of type captcha
03-14 14:35:31.340 23553 23553 I EnterPhoneNumberFragmen: Unknown error during verification code request
03-14 14:35:31.392 23553 23646 E OpenGLRenderer: Unable to match the desired swap behavior.
03-14 14:35:31.402 23553 23646 W Parcel  : Expecting binder but got null!
03-14 14:35:35.485 23553 23553 D LoggingFragment: [EnterPhoneNumberFragmen] onStop()

@yarsiemanym
Copy link

yarsiemanym commented Mar 14, 2023

I just realized that the logs above are from an attempt with my VPN connected. For grins, I tried again with my VPN disconnected. This time, rather than getting the "Unable to connect to service" message, the app crashes.

03-14 15:16:26.422 26698 26698 W Zygote  : Can't access app profile directory: /data_mirror/cur_profiles/0/org.thoughtcrime.securesms
03-14 15:16:26.441 26698 26698 E crime.securesms: Not starting debugger since process cannot load the jdwp agent.
03-14 15:16:26.607 26698 26698 D AndroidRuntime: >>>>>> START com.android.internal.os.RuntimeInit uid 10278 <<<<<<
03-14 15:16:26.614 26698 26698 I AndroidRuntime: Using default boot image
03-14 15:16:26.614 26698 26698 I AndroidRuntime: Leaving lock profiling enabled
03-14 15:16:26.614 26698 26698 W libc    : Access denied finding property "odsign.verification.success"
03-14 15:16:26.612 26698 26698 W crime.securesms: type=1400 audit(0.0:122610): avc: denied { read } for name="u:object_r:odsign_prop:s0" dev="tmpfs" ino=9738 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:odsign_prop:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 15:16:26.616 26698 26698 W crime.securesms: type=1400 audit(0.0:122611): avc: denied { getattr } for path="/apex/apex-info-list.xml" dev="tmpfs" ino=11315 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:apex_info_file:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 15:16:26.618 26698 26698 W org.thoughtcrime.securesms: ART APEX data files are untrusted.
03-14 15:16:26.622 26698 26698 W crime.securesms: type=1400 audit(0.0:122612): avc: denied { lock } for path="/system/framework/arm64/boot.art" dev="dm-8" ino=2343 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:system_file:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 15:16:26.626 26698 26698 W crime.securesms: type=1400 audit(0.0:122613): avc: denied { lock } for path="/system/framework/arm64/boot-core-libart.art" dev="dm-8" ino=2319 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:system_file:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 15:16:26.626 26698 26698 W crime.securesms: type=1400 audit(0.0:122614): avc: denied { lock } for path="/system/framework/arm64/boot-okhttp.art" dev="dm-8" ino=2334 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:system_file:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 15:16:26.626 26698 26698 W crime.securesms: type=1400 audit(0.0:122615): avc: denied { lock } for path="/system/framework/arm64/boot-bouncycastle.art" dev="dm-8" ino=2313 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:system_file:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 15:16:26.626 26698 26698 W crime.securesms: type=1400 audit(0.0:122616): avc: denied { lock } for path="/system/framework/arm64/boot-apache-xml.art" dev="dm-8" ino=2310 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:system_file:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 15:16:26.632 26698 26698 W crime.securesms: type=1400 audit(0.0:122617): avc: denied { lock } for path="/system/framework/arm64/boot-framework.art" dev="dm-8" ino=2328 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:system_file:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 15:16:26.642 26698 26698 W crime.securesms: type=1400 audit(0.0:122618): avc: denied { lock } for path="/system/framework/arm64/boot-framework-graphics.art" dev="dm-8" ino=2325 scontext=u:r:untrusted_app_30:s0:c22,c257,c512,c768 tcontext=u:object_r:system_file:s0 tclass=file permissive=0 app=org.thoughtcrime.securesms
03-14 15:16:26.734 26698 26698 D org.thoughtcrime.securesms: Time zone APEX ICU file found: /apex/com.android.tzdata/etc/icu/icu_tzdata.dat
03-14 15:16:26.734 26698 26698 D org.thoughtcrime.securesms: I18n APEX ICU file found: /apex/com.android.i18n/etc/icu/icudt70l.dat
03-14 15:16:26.768 26698 26698 D AndroidRuntime: Calling main entry com.android.internal.os.ExecInit
03-14 15:16:26.768 26698 26698 D Zygote  : begin preload
03-14 15:16:26.768 26698 26698 I Zygote  : Calling ZygoteHooks.beginPreload()
03-14 15:16:26.770 26698 26698 D libEGL  : loaded /vendor/lib64/egl/libEGL_adreno.so
03-14 15:16:26.774 26698 26698 D libEGL  : loaded /vendor/lib64/egl/libGLESv1_CM_adreno.so
03-14 15:16:26.778 26698 26698 D libEGL  : loaded /vendor/lib64/egl/libGLESv2_adreno.so
03-14 15:16:26.802 26698 26698 I Zygote  : Preloading shared libraries...
03-14 15:16:26.805 26698 26698 E org.thoughtcrime.securesms: Unable to find pattern file or unable to map it for am
03-14 15:16:26.805 26698 26698 I Zygote  : Called ZygoteHooks.endPreload()
03-14 15:16:26.821 26698 26698 I Zygote  : Installed AndroidKeyStoreProvider in 15ms.
03-14 15:16:26.821 26698 26698 D Zygote  : end preload
03-14 15:16:26.831 26698 26734 W libc    : Access denied finding property "qemu.sf.lcd_density"
03-14 15:16:26.831 26698 26734 W libc    : Access denied finding property "qemu.sf.lcd_density"
03-14 15:16:26.851 26698 26698 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10278; state: ENABLED
03-14 15:16:26.852 26698 26698 I Typeface: Preloading /system/fonts/Roboto-Regular.ttf
03-14 15:16:26.852 26698 26698 I Typeface: Preloading /system/fonts/RobotoStatic-Regular.ttf
03-14 15:16:26.898 26698 26698 W org.thoughtcrime.securesms: unable to execute idmap2: Permission denied
03-14 15:16:26.898 26698 26698 W OverlayConfig: 'idmap2 create-multiple' failed: no mutable="false" overlays targeting "android" will be loaded
03-14 15:16:27.114 26698 26698 V GraphicsEnvironment: ANGLE Developer option for 'org.thoughtcrime.securesms' set to: 'default'
03-14 15:16:27.114 26698 26698 V GraphicsEnvironment: ANGLE GameManagerService for org.thoughtcrime.securesms: false
03-14 15:16:27.114 26698 26698 V GraphicsEnvironment: Updatable production driver is not supported on the device.
03-14 15:16:27.118 26698 26698 D NetworkSecurityConfig: No Network Security Config specified, using platform default
03-14 15:16:27.118 26698 26698 D NetworkSecurityConfig: No Network Security Config specified, using platform default
03-14 15:16:27.127 26698 26698 I MultiDex: VM with version 2.1.0 has multidex support
03-14 15:16:27.127 26698 26698 I MultiDex: Installing application
03-14 15:16:27.127 26698 26698 I MultiDex: VM has multidex support, MultiDex support library is disabled.
03-14 15:16:27.138 26698 26698 D CompatibilityChangeReporter: Compat change id reported: 183155436; UID 10278; state: DISABLED
03-14 15:16:27.139 26698 26698 I FirebaseApp: Device unlocked: initializing all Firebase APIs for app [DEFAULT]
03-14 15:16:27.147 26698 26698 I FirebaseInitProvider: FirebaseApp initialization successful
03-14 15:16:27.147 26698 26698 I PartProvider: onCreate()
03-14 15:16:27.147 26698 26698 I BlobContentProvider: onCreate()
03-14 15:16:27.187 26698 26698 I ApplicationContext: Installed AesGcmProvider: 1
03-14 15:16:27.252 26698 26698 V NativeCrypto: Registering org/conscrypt/NativeCrypto's 284 native methods...
03-14 15:16:27.258 26698 26698 I ApplicationContext: Installed Conscrypt provider: 2
03-14 15:16:27.389 26698 26698 I ApplicationContext: onCreate()
03-14 15:16:27.402 26698 26750 I CellServiceConstraintOb: [API 33] Cell service available.
03-14 15:16:27.410 26698 26698 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.424 26698 26698 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.425 26698 26748 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.429 26698 26698 D ApplicationMigrations: Not an update. Skipping.
03-14 15:16:27.432 26698 26752 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.434 26698 26698 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.437 26698 26752 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.438 26698 26752 I JobDatabase: onOpen()
03-14 15:16:27.441 26698 26748 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.444 26698 26698 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.445 26698 26698 I KeyValueDatabase: onOpen()
03-14 15:16:27.459 26698 26698 I RegistrationUtil: Registration is not yet complete.
03-14 15:16:27.459 26698 26698 I RegistrationUtil: java.lang.Throwable
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at org.thoughtcrime.securesms.registration.RegistrationUtil.maybeMarkRegistrationComplete(RegistrationUtil.java:37)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at org.thoughtcrime.securesms.ApplicationContext.lambda$onCreate$3(ApplicationContext.java:165)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at org.thoughtcrime.securesms.ApplicationContext.$r8$lambda$wIlBIGBTTiiajQBAE1x8xR74OLI(Unknown Source:0)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at org.thoughtcrime.securesms.ApplicationContext$$ExternalSyntheticLambda55.run(Unknown Source:0)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at org.thoughtcrime.securesms.util.AppStartup.execute(AppStartup.java:145)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at org.thoughtcrime.securesms.ApplicationContext.onCreate(ApplicationContext.java:215)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at android.app.Instrumentation.callApplicationOnCreate(Instrumentation.java:1282)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at android.app.ActivityThread.handleBindApplication(ActivityThread.java:6766)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at android.app.ActivityThread.-$$Nest$mhandleBindApplication(Unknown Source:0)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2134)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at android.os.Handler.dispatchMessage(Handler.java:106)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at android.os.Looper.loopOnce(Looper.java:201)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at android.os.Looper.loop(Looper.java:288)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at android.app.ActivityThread.main(ActivityThread.java:7879)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at java.lang.reflect.Method.invoke(Native Method)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:548)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at com.android.internal.os.ExecInit.main(ExecInit.java:49)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
03-14 15:16:27.459 26698 26698 I RegistrationUtil: 	at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:355)
03-14 15:16:27.460 26698 26752 I IncomingMessageObserver: Initializing! (180141873)
03-14 15:16:27.461 26698 26756 I IncomingMessageObserver: Waiting for websocket state change....
03-14 15:16:27.462 26698 26698 D DynamicTheme: Setting to follow system expecting: 32
03-14 15:16:27.463 26698 26756 D IncomingMessageObserver: Network: true, Foreground: false, FCM: true, Stay open requests: [], Censored: false, Registered: false, Proxy: false, Force websocket: false
03-14 15:16:27.464 26698 26698 D CachedInflater: Clearing view cache.
03-14 15:16:27.466 26698 26698 I FeatureFlags: init() {}
03-14 15:16:27.466 26698 26698 D CallManager: Loading ringrtc library
03-14 15:16:27.468 26698 26757 D BlobProvider: No attachment drafts exist. Skipping.
03-14 15:16:27.469 26698 26757 I BlobProvider: Initialized.
03-14 15:16:27.489 26698 26698 I CallManager: CallManager.initialize(): (release build, field trials = )
03-14 15:16:27.490 26698 26698 I org.webrtc.Logging: NativeLibrary: Loading native library: jingle_peerconnection_so
03-14 15:16:27.493 26698 26749 D LogDatabase: [trim] keepers-size: 0  binary-search: 31  delete: 2  total: 33
03-14 15:16:27.494 26698 26698 I CallManager: CallManager.initialize() returned
03-14 15:16:27.495 26698 26698 D AppStartup: [init] security-provider: 74  sqlcipher-init: 129  logging: 2  crash-handling: 0  rx-init: 1  event-bus: 0  app-dependencies: 0  first-launch: 0  app-migrations: 39  mark-registration: 30  lifecycle-observer: 0  message-retriever: 2  dynamic-theme: 4  proxy-init: 0  blob-provider: 0  feature-flags: 1  ring-rtc: 28  glide: 1  schedule-non-blocking: 0  total: 311
03-14 15:16:27.495 26698 26698 D ApplicationContext: onCreate() took 313 ms
03-14 15:16:27.506 26698 26763 I ViewOnceMessageManager: No messages to schedule.
03-14 15:16:27.507 26698 26753 I PreKeysSyncJob: Some signed prekeys aren't registered yet. Enqueuing a job. ACI: false PNI: false
03-14 15:16:27.512 26698 26755 I PersistentAlarmManagerL: [RotateSignedPreKeyListener] onReceive(signal.ACTION_SCHEDULE)
03-14 15:16:27.513 26698 26755 D CompatibilityChangeReporter: Compat change id reported: 160794467; UID 10278; state: ENABLED
03-14 15:16:27.514 26698 26757 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.516 26698 26755 I PersistentAlarmManagerL: [RotateSignedPreKeyListener] scheduling alarm for: 1678937474087
03-14 15:16:27.517 26698 26755 I PersistentAlarmManagerL: [DirectoryRefreshListener] onReceive(signal.ACTION_SCHEDULE)
03-14 15:16:27.520 26698 26750 D StorageSyncHelper: Scheduling a sync. Last sync was 1678821387519 ms ago.
03-14 15:16:27.520 26698 26750 D StorageSyncHelper: Registration still ongoing. Ignore sync request.
03-14 15:16:27.520 26698 26755 I PersistentAlarmManagerL: [DirectoryRefreshListener] scheduling alarm for: 1678937474090
03-14 15:16:27.522 26698 26755 I PersistentAlarmManagerL: [RotateSenderCertificateListener] onReceive(signal.ACTION_SCHEDULE)
03-14 15:16:27.524 26698 26755 I PersistentAlarmManagerL: [RotateSenderCertificateListener] scheduling alarm for: 1678851074169
03-14 15:16:27.526 26698 26755 I MessageProcessReceiver: Alarm scheduled to repeat at interval 21600000
03-14 15:16:27.534 26698 26698 I AppStartup: Received first critical render event.
03-14 15:16:27.537 26698 26698 D DynamicTheme: Previous night mode has changed previous: 0 now: 32
03-14 15:16:27.537 26698 26698 D CachedInflater: Clearing view cache.
03-14 15:16:27.546 26698 26765 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.553 26698 26698 D PassphraseRequiredActiv: routeApplicationState(), state: 4
03-14 15:16:27.553 26698 26757 I ApplicationContext: Deleted 0 abandoned attachments.
03-14 15:16:27.559 26698 26765 D PendingRetryReceiptMana: No pending receipts to schedule.
03-14 15:16:27.567 26698 26753 I Job     : [JOB::8e78b246-836b-4fd6-84ed-1348a0b786d5][PreKeysSyncJob] onSubmit() (Time Since Submission: 60 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: PreKeysSyncJob)
03-14 15:16:27.577 26698 26698 D BaseActivity: [MainActivity] onCreate()
03-14 15:16:27.581 26698 26757 I JobSchedulerScheduler: JobScheduler enqueue of NETWORK (-1733499378)
03-14 15:16:27.582 26698 26757 D CompatibilityChangeReporter: Compat change id reported: 194532703; UID 10278; state: DISABLED
03-14 15:16:27.585 26698 26698 I AppStartup: First render has finished. Cold Start: 404 ms, Render Time: 51 ms
03-14 15:16:27.589 26698 26746 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.602 26698 26757 D EmojiSearchIndexDownloa: Do not need to check. It's been 56710925 ms since the last check.
03-14 15:16:27.604 26698 26757 I RetrieveRemoteAnnouncem: Not registered, skipping.
03-14 15:16:27.605 26698 26757 I AndroidTelecomUtil: Registering phone account
03-14 15:16:27.605 26698 26746 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.611 26698 26746 D LocalMetrics: [cold-start-other] total: 403 | application-create: 313, start-activity: 39, render: 51
03-14 15:16:27.612 26698 26769 I JobRunner: [JOB::8e78b246-836b-4fd6-84ed-1348a0b786d5][PreKeysSyncJob][2] Running job. (Time Since Submission: 103 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: PreKeysSyncJob)
03-14 15:16:27.614 26698 26769 W PreKeysSyncJob: [JOB::8e78b246-836b-4fd6-84ed-1348a0b786d5][PreKeysSyncJob] Not yet registered (Time Since Submission: 106 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: PreKeysSyncJob)
03-14 15:16:27.615 26698 26769 I JobRunner: [JOB::8e78b246-836b-4fd6-84ed-1348a0b786d5][PreKeysSyncJob][2] Job finished with result SUCCESS in 4 ms. (Time Since Submission: 107 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: PreKeysSyncJob)
03-14 15:16:27.618 26698 26750 I Job     : [JOB::ffa78a61-5461-4cf1-9b0a-38337fb250b8][FontDownloaderJob] onSubmit() (Time Since Submission: 1 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: null)
03-14 15:16:27.619 26698 26750 W GroupV2UpdateSelfProfil: Not yet registered!
03-14 15:16:27.619 26698 26750 D StoryOnboardingDownload: Attempting to enqueue StoryOnboardingDownloadJob...
03-14 15:16:27.621 26698 26771 I JobRunner: [JOB::ffa78a61-5461-4cf1-9b0a-38337fb250b8][FontDownloaderJob][4] Running job. (Time Since Submission: 4 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: null)
03-14 15:16:27.622 26698 26750 I Job     : [JOB::4e279382-37c4-4a7a-8e19-5670143ebc99][CreateReleaseChannelJob] onSubmit() (Time Since Submission: 2 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: CreateReleaseChannelJob)
03-14 15:16:27.622 26698 26750 I Job     : [JOB::be586c4a-34dc-4803-a4d7-b0f77f45a7f8][StoryOnboardingDownloadJob] onSubmit() (Time Since Submission: 2 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: StoryOnboardingDownloadJob)
03-14 15:16:27.625 26698 26768 I JobRunner: [JOB::4e279382-37c4-4a7a-8e19-5670143ebc99][CreateReleaseChannelJob][1] Running job. (Time Since Submission: 3 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: CreateReleaseChannelJob)
03-14 15:16:27.626 26698 26768 I CreateReleaseChannelJob: Not registered, skipping.
03-14 15:16:27.626 26698 26768 I JobRunner: [JOB::4e279382-37c4-4a7a-8e19-5670143ebc99][CreateReleaseChannelJob][1] Job finished with result SUCCESS in 2 ms. (Time Since Submission: 5 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: CreateReleaseChannelJob)
03-14 15:16:27.628 26698 26768 I JobRunner: [JOB::be586c4a-34dc-4803-a4d7-b0f77f45a7f8][StoryOnboardingDownloadJob][1] Running job. (Time Since Submission: 7 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: StoryOnboardingDownloadJob)
03-14 15:16:27.629 26698 26768 W StoryOnboardingDownload: Cannot create story onboarding without release channel recipient.
03-14 15:16:27.629 26698 26768 W BaseJob : [JOB::be586c4a-34dc-4803-a4d7-b0f77f45a7f8][StoryOnboardingDownloadJob] Encountered a failing exception. (Time Since Submission: 8 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: StoryOnboardingDownloadJob)
03-14 15:16:27.629 26698 26768 W BaseJob : java.lang.Exception: No release channel recipient.
03-14 15:16:27.629 26698 26768 W BaseJob : 	at org.thoughtcrime.securesms.jobs.StoryOnboardingDownloadJob.onRun(StoryOnboardingDownloadJob.kt:78)
03-14 15:16:27.629 26698 26768 W BaseJob : 	at org.thoughtcrime.securesms.jobs.BaseJob.run(BaseJob.java:32)
03-14 15:16:27.629 26698 26768 W BaseJob : 	at org.thoughtcrime.securesms.jobmanager.JobRunner.run(JobRunner.java:86)
03-14 15:16:27.629 26698 26768 W BaseJob : 	at org.thoughtcrime.securesms.jobmanager.JobRunner.run(JobRunner.java:49)
03-14 15:16:27.630 26698 26698 I JobManager: onConstraintMet(NetworkConstraintObserv)
03-14 15:16:27.632 26698 26756 D IncomingMessageObserver: Network: true, Foreground: false, FCM: true, Stay open requests: [], Censored: false, Registered: false, Proxy: false, Force websocket: false
03-14 15:16:27.633 26698 26768 W JobRunner: [JOB::be586c4a-34dc-4803-a4d7-b0f77f45a7f8][StoryOnboardingDownloadJob][1] Job failed. (Time Since Submission: 12 ms, Lifespan: Immortal, Run Attempt: 1/3, Queue: StoryOnboardingDownloadJob)
03-14 15:16:27.653 26698 26771 D Fonts   : Getting Script for Latn
03-14 15:16:27.670 26698 26757 I AndroidTelecomUtil: Phone account registered successfully
03-14 15:16:27.674 26698 26750 D MtpDeviceJNI: register_android_mtp_MtpDevice
03-14 15:16:27.677 26698 26757 I JobSchedulerScheduler: JobScheduler enqueue of  (0)
03-14 15:16:27.682 26698 26698 D LoggingFragment: [WelcomeFragment] onCreate()
03-14 15:16:27.683 26698 26698 D SmsRetrieverReceiver: Registering SMS retriever receiver
03-14 15:16:27.695 26698 26750 W VideoCapabilities: Unrecognized level 1879048707 for video/x-vnd.on2.vp8
03-14 15:16:27.695 26698 26750 W VideoCapabilities: Unrecognized profile 1879048704 for video/x-vnd.on2.vp8
03-14 15:16:27.695 26698 26750 W VideoCapabilities: Unrecognized level 1879048707 for video/x-vnd.on2.vp8
03-14 15:16:27.695 26698 26750 W VideoCapabilities: Unrecognized profile 1879048704 for video/x-vnd.on2.vp8
03-14 15:16:27.697 26698 26750 W VideoCapabilities: Unsupported mime image/vnd.android.heic
03-14 15:16:27.697 26698 26750 W VideoCapabilities: Unrecognized level 1879048707 for video/x-vnd.on2.vp8
03-14 15:16:27.697 26698 26750 W VideoCapabilities: Unrecognized profile 1879048704 for video/x-vnd.on2.vp8
03-14 15:16:27.697 26698 26750 W VideoCapabilities: Unrecognized level 1879048707 for video/x-vnd.on2.vp8
03-14 15:16:27.697 26698 26750 W VideoCapabilities: Unrecognized profile 1879048704 for video/x-vnd.on2.vp8
03-14 15:16:27.746 26698 26698 D CompatibilityChangeReporter: Compat change id reported: 210923482; UID 10278; state: DISABLED
03-14 15:16:27.746 26698 26698 D CompatibilityChangeReporter: Compat change id reported: 37756858; UID 10278; state: ENABLED
03-14 15:16:27.749 26698 26771 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 15:16:27.750 26698 26771 I FontVersion: Deleting 0 files
03-14 15:16:27.769 26698 26755 D EmojiFiles: Verifying all name files exist.
03-14 15:16:27.770 26698 26771 D Fonts   : Loaded manifest.
03-14 15:16:27.771 26698 26771 D Fonts   : Getting Script for LATIN
03-14 15:16:27.771 26698 26771 D Fonts   : Loaded script for locale.
03-14 15:16:27.777 26698 26771 D Fonts   : Local font version found, returning immediate.
03-14 15:16:27.782 26698 26755 D EmojiFiles: All names exist? true
03-14 15:16:27.804 26698 26698 D LoggingFragment: [WelcomeFragment] onStart()
03-14 15:16:27.806 26698 26698 I ApplicationContext: App is now visible.
03-14 15:16:27.809 26698 26771 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 15:16:27.809 26698 26771 I FontVersion: Deleting 0 files
03-14 15:16:27.811 26698 26771 D Fonts   : Loaded manifest.
03-14 15:16:27.812 26698 26698 D FrameRateTracker: Beginning frame rate tracking. Screen refresh rate: 60.00 hz, or 16.67 ms per frame.
03-14 15:16:27.812 26698 26771 D Fonts   : Getting Script for LATIN
03-14 15:16:27.812 26698 26771 D Fonts   : Loaded script for locale.
03-14 15:16:27.814 26698 26771 D Fonts   : Local font version found, returning immediate.
03-14 15:16:27.818 26698 26698 D DeadlockDetector: Beginning deadlock monitoring.
03-14 15:16:27.819 26698 26698 D ApplicationContext: onStart() took 14 ms
03-14 15:16:27.819 26698 26757 I FeatureFlags: Scheduling remote config refresh.
03-14 15:16:27.821 26698 26771 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 15:16:27.821 26698 26771 I FontVersion: Deleting 0 files
03-14 15:16:27.822 26698 26757 I Job     : [JOB::da17cc21-4f88-4c09-bbee-a4a29fd97a23][RemoteConfigRefreshJob] onSubmit() (Time Since Submission: 2 ms, Lifespan: 86400000 ms, Run Attempt: 1/Unlimited, Queue: RemoteConfigRefreshJob)
03-14 15:16:27.822 26698 26771 D Fonts   : Loaded manifest.
03-14 15:16:27.822 26698 26771 D Fonts   : Getting Script for LATIN
03-14 15:16:27.822 26698 26771 D Fonts   : Loaded script for locale.
03-14 15:16:27.822 26698 26771 D Fonts   : Local font version found, returning immediate.
03-14 15:16:27.824 26698 26756 D IncomingMessageObserver: Network: true, Foreground: true, FCM: true, Stay open requests: [], Censored: false, Registered: false, Proxy: false, Force websocket: false
03-14 15:16:27.825 26698 26769 I JobRunner: [JOB::da17cc21-4f88-4c09-bbee-a4a29fd97a23][RemoteConfigRefreshJob][2] Running job. (Time Since Submission: 6 ms, Lifespan: 86400000 ms, Run Attempt: 1/Unlimited, Queue: RemoteConfigRefreshJob)
03-14 15:16:27.827 26698 26771 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 15:16:27.827 26698 26771 I FontVersion: Deleting 0 files
03-14 15:16:27.828 26698 26771 D Fonts   : Loaded manifest.
03-14 15:16:27.828 26698 26771 D Fonts   : Getting Script for LATIN
03-14 15:16:27.828 26698 26771 D Fonts   : Loaded script for locale.
03-14 15:16:27.830 26698 26769 W RemoteConfigRefreshJob: Not registered. Skipping.
03-14 15:16:27.830 26698 26771 D Fonts   : Local font version found, returning immediate.
03-14 15:16:27.832 26698 26779 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.833 26698 26757 I RetrieveProfileJob: Registration not complete. Skipping.
03-14 15:16:27.833 26698 26769 I JobRunner: [JOB::da17cc21-4f88-4c09-bbee-a4a29fd97a23][RemoteConfigRefreshJob][2] Job finished with result SUCCESS in 8 ms. (Time Since Submission: 14 ms, Lifespan: 86400000 ms, Run Attempt: 1/Unlimited, Queue: RemoteConfigRefreshJob)
03-14 15:16:27.841 26698 26771 I FontVersion: Timeout interval not exceeded, using what we have.
03-14 15:16:27.841 26698 26771 I FontVersion: Deleting 0 files
03-14 15:16:27.842 26698 26771 D Fonts   : Loaded manifest.
03-14 15:16:27.842 26698 26771 D Fonts   : Getting Script for LATIN
03-14 15:16:27.842 26698 26771 D Fonts   : Loaded script for locale.
03-14 15:16:27.843 26698 26771 D Fonts   : Local font version found, returning immediate.
03-14 15:16:27.845 26698 26750 D LiveRecipientCache: Warming up 0 thread recipients.
03-14 15:16:27.847 26698 26779 I SQLiteConnection: Database keying operation returned:0
03-14 15:16:27.848 26698 26779 I MegaphoneDatabase: onOpen()
03-14 15:16:27.854 26698 26698 I JobSchedulerScheduler: Waking due to job: -1733499378
03-14 15:16:27.854 26698 26771 I FontDownloaderJob: Already downloaded fonts for locale.
03-14 15:16:27.854 26698 26698 I JobSchedulerScheduler: Waking due to job: 0
03-14 15:16:27.855 26698 26698 V DeviceToDeviceTransferS: onCreate
03-14 15:16:27.856 26698 26771 I JobRunner: [JOB::ffa78a61-5461-4cf1-9b0a-38337fb250b8][FontDownloaderJob][4] Job finished with result SUCCESS in 235 ms. (Time Since Submission: 239 ms, Lifespan: 2592000000 ms, Run Attempt: 1/Unlimited, Queue: null)
03-14 15:16:27.857 26698 26750 D LiveRecipientCache: [recipient-warm-up] thread: 26  
03-14 15:16:27.873 26698 26698 I WifiDirect: Fine location permission required
03-14 15:16:27.874 26698 26698 I DeviceToDeviceTransferS: Shutdown
03-14 15:16:27.916 26698 26760 I AdrenoGLES-0: QUALCOMM build                   : 4783c89, I46ff5fc46f
03-14 15:16:27.916 26698 26760 I AdrenoGLES-0: Build Date                       : 11/30/20
03-14 15:16:27.916 26698 26760 I AdrenoGLES-0: OpenGL ES Shader Compiler Version: EV031.31.04.01
03-14 15:16:27.916 26698 26760 I AdrenoGLES-0: Local Branch                     : QPR2
03-14 15:16:27.916 26698 26760 I AdrenoGLES-0: Remote Branch                    : 
03-14 15:16:27.916 26698 26760 I AdrenoGLES-0: Remote Branch                    : 
03-14 15:16:27.916 26698 26760 I AdrenoGLES-0: Reconstruct Branch               : 
03-14 15:16:27.916 26698 26760 I AdrenoGLES-0: Build Config                     : S P 10.0.4 AArch64
03-14 15:16:27.916 26698 26760 I AdrenoGLES-0: Driver Path                      : /vendor/lib64/egl/libGLESv2_adreno.so
03-14 15:16:27.932 26698 26760 I AdrenoGLES-0: PFP: 0x016dd093, ME: 0x00000000
03-14 15:16:27.950 26698 26760 W AdrenoUtils: <ReadGpuID_from_sysfs:197>: Failed to open /sys/class/kgsl/kgsl-3d0/gpu_model
03-14 15:16:27.950 26698 26760 W AdrenoUtils: <ReadGpuID:221>: Failed to read chip ID from gpu_model. Fallback to use the GSL path
03-14 15:16:27.983 26698 26760 E OpenGLRenderer: Unable to match the desired swap behavior.
03-14 15:16:28.043 26698 26760 W Parcel  : Expecting binder but got null!
03-14 15:16:28.043 26698 26698 V DeviceToDeviceTransferS: onDestroy
03-14 15:16:28.078 26698 26698 D BaseActivity: [MainActivity] onDestroy()
03-14 15:16:33.009 26698 27063 D ProfileInstaller: Installing profile for org.thoughtcrime.securesms
03-14 15:16:43.348 26698 27249 I BackupUtil: Backup directory is not set. Returning an empty list.
03-14 15:16:43.383 26698 26698 I WelcomeFragment: Phone number detected
03-14 15:16:43.385 26698 26698 I WelcomeFragment: Skipping backup. No backup found, or no permission to look.
03-14 15:16:43.392 26698 26698 D LoggingFragment: [WelcomeFragment] onStop()
03-14 15:16:43.394 26698 26698 D LoggingFragment: [EnterPhoneNumberFragmen] onCreate()
03-14 15:16:43.399 26698 26698 D CompatibilityChangeReporter: Compat change id reported: 171228096; UID 10278; state: ENABLED
03-14 15:16:43.490 26698 26698 D CompatibilityChangeReporter: Compat change id reported: 163400105; UID 10278; state: DISABLED
03-14 15:16:43.496 26698 26698 D InputMethodManager: showSoftInput() view=com.google.android.material.textfield.TextInputEditText{aa14d5c VFED..CL. .F....I. 0,0-0,0 aid=1073741824} flags=1 reason=SHOW_SOFT_INPUT
03-14 15:16:43.502 26698 26698 D LoggingFragment: [EnterPhoneNumberFragmen] onStart()
03-14 15:16:43.567 26698 26698 I AssistStructure: Flattened final assist data: 3436 bytes, containing 1 windows, 23 views
03-14 15:16:43.653 26698 26698 W OnBackInvokedCallback: OnBackInvokedCallback is not enabled for the application.
03-14 15:16:43.653 26698 26698 W OnBackInvokedCallback: Set 'android:enableOnBackInvokedCallback="true"' in the application manifest.
03-14 15:16:43.686 26698 26698 D InsetsController: show(ime(), fromIme=true)
03-14 15:16:47.971 26698 26698 I PlayServicesUtil: Play Services: 0
03-14 15:16:48.031 26698 26760 E OpenGLRenderer: Unable to match the desired swap behavior.
03-14 15:16:48.039 26698 26760 W Parcel  : Expecting binder but got null!
03-14 15:16:49.226 26698 26698 I RegistrationViewModel: Checking if user has existing recovery password: false
03-14 15:16:49.251 26698 26698 I EnterPhoneNumberFragmen: Unable to collect necessary data to enter skip flow, returning to normal
03-14 15:16:49.480 26698 26698 I EnterPhoneNumberFragmen: Successfully registered SMS listener.
03-14 15:16:49.489 26698 26698 I AccountValues: Setting push registered: false
03-14 15:16:49.489 26698 26698 I AccountValues: java.lang.Throwable
03-14 15:16:49.489 26698 26698 I AccountValues: 	at org.thoughtcrime.securesms.keyvalue.AccountValues.setRegistered(AccountValues.kt:303)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.lambda$requestVerificationCode$10(EnterPhoneNumberFragment.java:280)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.$r8$lambda$WGT27_vWweIdZZJAosa47SPLeug(Unknown Source:0)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment$$ExternalSyntheticLambda17.accept(Unknown Source:2)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSubscribe$DoOnSubscribeSingleObserver.onSubscribe(SingleDoOnSubscribe.java:60)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess$DoOnSuccess.onSubscribe(SingleDoOnSuccess.java:48)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn$ObserveOnSingleObserver.onSubscribe(SingleObserveOn.java:57)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleMap$MapSingleObserver.onSubscribe(SingleMap.java:51)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleJust.subscribeActual(SingleJust.java:29)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleMap.subscribeActual(SingleMap.java:35)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess.subscribeActual(SingleDoOnSuccess.java:35)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSubscribe.subscribeActual(SingleDoOnSubscribe.java:41)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4799)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4768)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.requestVerificationCode(EnterPhoneNumberFragment.java:282)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.lambda$handleRequestVerification$8(EnterPhoneNumberFragment.java:240)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.$r8$lambda$pGuOcF6EfwS85Ol4ZxNoz3kSSt0(Unknown Source:0)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment$$ExternalSyntheticLambda8.onSuccess(Unknown Source:8)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at com.google.android.gms.tasks.zzm.run(com.google.android.gms:play-services-tasks@@18.0.1:1)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at android.os.Handler.handleCallback(Handler.java:942)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at android.os.Handler.dispatchMessage(Handler.java:99)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at android.os.Looper.loopOnce(Looper.java:201)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at android.os.Looper.loop(Looper.java:288)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at android.app.ActivityThread.main(ActivityThread.java:7879)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at java.lang.reflect.Method.invoke(Native Method)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:548)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at com.android.internal.os.ExecInit.main(ExecInit.java:49)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
03-14 15:16:49.489 26698 26698 I AccountValues: 	at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:355)
03-14 15:16:49.490 26698 26756 D IncomingMessageObserver: Network: true, Foreground: true, FCM: true, Stay open requests: [], Censored: false, Registered: false, Proxy: false, Force websocket: false
03-14 15:16:49.518 26698 26766 I libsignal: rust/bridge/jni/src/logging.rs:156: Initializing libsignal version:0.22.0
03-14 15:16:49.732 26698 26766 D TrafficStats: tagSocket(131) with statsTag=0xffffffff, statsUid=-1
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: java.util.NoSuchElementException: No value present
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.requestVerificationCode(EnterPhoneNumberFragment.java:282)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at [[ ↑↑ Inferred subscribe point ↑↑ ]].(:0)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at [[ ↓↓ Original trace ↓↓ ]].(:0)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at j$.util.Optional.get(Unknown Source:9)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at org.thoughtcrime.securesms.registration.RegistrationSessionProcessor.pushChallengeTimedOut(RegistrationSessionProcessor.kt:43)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at org.thoughtcrime.securesms.registration.viewmodel.BaseRegistrationViewModel.lambda$getValidSession$3(BaseRegistrationViewModel.java:289)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at org.thoughtcrime.securesms.registration.viewmodel.BaseRegistrationViewModel.$r8$lambda$BqkeWausZ7-0k4qoIQGfzOcBPHA(Unknown Source:0)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at org.thoughtcrime.securesms.registration.viewmodel.BaseRegistrationViewModel$$ExternalSyntheticLambda6.accept(Unknown Source:4)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess$DoOnSuccess.onSuccess(SingleDoOnSuccess.java:54)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at io.reactivex.rxjava3.internal.operators.single.SingleMap$MapSingleObserver.onSuccess(SingleMap.java:65)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at io.reactivex.rxjava3.internal.operators.single.SingleSubscribeOn$SubscribeOnObserver.onSuccess(SingleSubscribeOn.java:68)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at io.reactivex.rxjava3.internal.operators.single.SingleFromCallable.subscribeActual(SingleFromCallable.java:55)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at io.reactivex.rxjava3.internal.operators.single.SingleSubscribeOn$SubscribeOnObserver.run(SingleSubscribeOn.java:89)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at io.reactivex.rxjava3.internal.schedulers.ScheduledDirectTask.call(ScheduledDirectTask.java:38)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at io.reactivex.rxjava3.internal.schedulers.ScheduledDirectTask.call(ScheduledDirectTask.java:25)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at java.util.concurrent.FutureTask.run(FutureTask.java:264)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1137)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:637)
03-14 15:16:50.043 26698 26698 E SignalUncaughtException: 	at java.lang.Thread.run(Thread.java:1012)
03-14 15:16:50.069 26698 26698 I JobManager: Successfully flushed.
--------- beginning of crash
03-14 15:16:50.071 26698 26698 E AndroidRuntime: FATAL EXCEPTION: main
03-14 15:16:50.071 26698 26698 E AndroidRuntime: Process: org.thoughtcrime.securesms, PID: 26698
03-14 15:16:50.071 26698 26698 E AndroidRuntime: java.util.NoSuchElementException: No value present
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.requestVerificationCode(EnterPhoneNumberFragment.java:282)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at [[ ↑↑ Inferred subscribe point ↑↑ ]].(:0)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at [[ ↓↓ Original trace ↓↓ ]].(:0)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at j$.util.Optional.get(Unknown Source:9)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at org.thoughtcrime.securesms.registration.RegistrationSessionProcessor.pushChallengeTimedOut(RegistrationSessionProcessor.kt:43)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at org.thoughtcrime.securesms.registration.viewmodel.BaseRegistrationViewModel.lambda$getValidSession$3(BaseRegistrationViewModel.java:289)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at org.thoughtcrime.securesms.registration.viewmodel.BaseRegistrationViewModel.$r8$lambda$BqkeWausZ7-0k4qoIQGfzOcBPHA(Unknown Source:0)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at org.thoughtcrime.securesms.registration.viewmodel.BaseRegistrationViewModel$$ExternalSyntheticLambda6.accept(Unknown Source:4)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess$DoOnSuccess.onSuccess(SingleDoOnSuccess.java:54)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at io.reactivex.rxjava3.internal.operators.single.SingleMap$MapSingleObserver.onSuccess(SingleMap.java:65)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at io.reactivex.rxjava3.internal.operators.single.SingleSubscribeOn$SubscribeOnObserver.onSuccess(SingleSubscribeOn.java:68)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at io.reactivex.rxjava3.internal.operators.single.SingleFromCallable.subscribeActual(SingleFromCallable.java:55)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at io.reactivex.rxjava3.internal.operators.single.SingleSubscribeOn$SubscribeOnObserver.run(SingleSubscribeOn.java:89)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at io.reactivex.rxjava3.internal.schedulers.ScheduledDirectTask.call(ScheduledDirectTask.java:38)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at io.reactivex.rxjava3.internal.schedulers.ScheduledDirectTask.call(ScheduledDirectTask.java:25)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at java.util.concurrent.FutureTask.run(FutureTask.java:264)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1137)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:637)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at java.lang.Thread.run(Thread.java:1012)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at [[ ↑↑ Original Trace ↑↑ ]].(:0)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at [[ ↓↓ Exception Message ↓↓ ]].(:0)
03-14 15:16:50.071 26698 26698 E AndroidRuntime: 	at No value present.(:0)
03-14 15:16:50.083 26698 26729 W org.thoughtcrime.securesms: Cleared Reference was only reachable from finalizer (only reported once)
03-14 15:16:50.105 26698 26698 I Process : Sending signal. PID: 26698 SIG: 9

@alvinHRFM
Copy link

Same here. 'Unable to connect to service' prompted on 6.13.6 and 6.13.7.
I tried to downgrade it to 6.12.6 but no such issue was found.
Trying to find the cause now.

@cody-signal
Copy link
Contributor

Thanks all. @yarsiemanym appreciate the logs, looks like a new issue, we're looking into it immediately.

@alvinHRFM feel free to email me logs or post the link here to get help from our side, without them, not much we can do.

@yarsiemanym
Copy link

6.13.8 fixed this registration problem. Now I'm back to where I was originally: no push notifications. What info can I provide to help debug that?

@greyson-signal
Copy link
Contributor

@yarsiemanym You can open a new issue and include a link to a debuglog. Looks like the original issue has been fixed. Thanks!

@rany2
Copy link

rany2 commented Mar 21, 2023

I've faced this issue on both the latest stable and beta released on Google Play. I don't think it got fixed but ATM I'm unable to provide a debuglog (got banned for a day due to frequent retries). Might be worth having a second look?

@greyson-signal
Copy link
Contributor

@rany2 Please provide a log when you can, you're likely facing a different variation of the issue

@rany2
Copy link

rany2 commented Mar 22, 2023

@greyson-signal This is the log on 6.13.8 Stable (worth noting is that it also doesn't work on the beta but if you want the logs for beta, I could send them too):

03-22 21:08:24.698 13494 13494 I EnterPhoneNumberFragmen: Successfully registered SMS listener.
03-22 21:08:24.854 13494 13494 I AccountValues: Setting push registered: false
03-22 21:08:24.854 13494 13494 I AccountValues: java.lang.Throwable
03-22 21:08:24.854 13494 13494 I AccountValues: 	at org.thoughtcrime.securesms.keyvalue.AccountValues.setRegistered(AccountValues.kt:303)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.lambda$requestVerificationCode$10(EnterPhoneNumberFragment.java:280)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.$r8$lambda$WGT27_vWweIdZZJAosa47SPLeug(Unknown Source:0)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment$$ExternalSyntheticLambda17.accept(Unknown Source:2)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSubscribe$DoOnSubscribeSingleObserver.onSubscribe(SingleDoOnSubscribe.java:60)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess$DoOnSuccess.onSubscribe(SingleDoOnSuccess.java:48)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn$ObserveOnSingleObserver.onSubscribe(SingleObserveOn.java:57)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap$SingleFlatMapCallback.onSubscribe(SingleFlatMap.java:68)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleMap$MapSingleObserver.onSubscribe(SingleMap.java:51)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleSubscribeOn.subscribeActual(SingleSubscribeOn.java:35)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleMap.subscribeActual(SingleMap.java:35)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleFlatMap.subscribeActual(SingleFlatMap.java:37)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess.subscribeActual(SingleDoOnSuccess.java:35)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleDoOnSubscribe.subscribeActual(SingleDoOnSubscribe.java:41)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4799)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4768)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.requestVerificationCode(EnterPhoneNumberFragment.java:282)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.lambda$handleRequestVerification$8(EnterPhoneNumberFragment.java:240)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment.$r8$lambda$pGuOcF6EfwS85Ol4ZxNoz3kSSt0(Unknown Source:0)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at org.thoughtcrime.securesms.registration.fragments.EnterPhoneNumberFragment$$ExternalSyntheticLambda8.onSuccess(Unknown Source:8)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at com.google.android.gms.tasks.zzm.run(com.google.android.gms:play-services-tasks@@18.0.1:1)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at android.os.Handler.handleCallback(Handler.java:942)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at android.os.Handler.dispatchMessage(Handler.java:99)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at android.os.Looper.loopOnce(Looper.java:201)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at android.os.Looper.loop(Looper.java:288)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at android.app.ActivityThread.main(ActivityThread.java:7891)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at java.lang.reflect.Method.invoke(Native Method)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:548)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at com.android.internal.os.ExecInit.main(ExecInit.java:49)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
03-22 21:08:24.854 13494 13494 I AccountValues: 	at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:355)
03-22 21:08:24.854 13494 13547 D IncomingMessageObserver: Network: true, Foreground: true, FCM: true, Stay open requests: [], Censored: false, Registered: false, Proxy: false, Force websocket: false
03-22 21:08:24.913 13494 13553 D TrafficStats: tagSocket(213) with statsTag=0xffffffff, statsUid=-1
03-22 21:08:25.038   712  6065 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xff Opcode 0 Length 14 unhandled by debug_VendorEvent
03-22 21:08:25.039   792 12783 W AudioFlinger: write blocked for 460 msecs, 10 delayed writes, thread 229
03-22 21:08:25.546 13494 13553 D BaseRegistrationViewMod: Submitting completed captcha challenge
03-22 21:08:25.740 13494 13553 D TrafficStats: tagSocket(217) with statsTag=0xffffffff, statsUid=-1
03-22 21:08:26.220  3092  5856 D TrafficStats: tagSocket(124) with statsTag=0xffffffff, statsUid=-1
03-22 21:08:26.821 13494 13553 D VerifyAccountRepository: SMS Verification requested
03-22 21:08:26.839  1296  1523 W ProcessStats: Tracking association SourceState{e47745e system/1000 BTopFgs #82988} whose 03-22 21:08:26.881  1296  1535 I am_compact: [24098,.ShannonImsService,file,116144,102404,12960,31808,0,0,0,0,44,1,39801253,915,15,421372,0]
03-22 21:08:27.035 13494 13553 D TrafficStats: tagSocket(228) with statsTag=0xffffffff, statsUid=-1
03-22 21:08:27.183   778   778 I WifiHAL : Creating message to get link statistics; iface = 47
03-22 21:08:27.218  1296  1418 I DisplayPowerController[0]: BrightnessEvent: disp=0, physDisp=local:4619827677550801152, brt=0.22057737, initBrt=0.124438226, rcmdBrt=0.22057737, preBrt=0.124438226, lux=815.9281, preLux=267.49414, hbmMax=0.62, hbmMode=off, rbcStrength=50, powerFactor=1.0, thrmMax=1.0, wasShortTermModelActive=false, flags=, reason=automatic, autoBrightness=true
03-22 21:08:27.218  1296  1418 I DisplayPowerController[0]: BrightnessEvent: disp=0, physDisp=local:4619827677550801152, brt=0.22057737, initBrt=0.22057737, rcmdBrt=0.22057737, preBrt=0.124438226, lux=815.9281, preLux=267.49414, hbmMax=0.62, hbmMode=off, rbcStrength=50, powerFactor=1.0, thrmMax=1.0, wasShortTermModelActive=false, flags=, reason=automatic, autoBrightness=true
03-22 21:08:27.220  1296  1296 I BrightnessSynchronizer: [1841] New Update 0.22057737(f) set brightness values:  57(i)
03-22 21:08:27.220  1296  1296 I BrightnessSynchronizer: New PendingUpdate: {[1842] 0.22057737(f), mUpdatedTypes=0, mConfirmedTypes=0, mTimeUpdated=0}, prev=null
03-22 21:08:27.222  1296  1296 I BrightnessSynchronizer: Swallowing update of 57(i) by update: {[1841] 0.22057737(f), mUpdatedTypes=1, mConfirmedTypes=1, mTimeUpdated=39830055}
03-22 21:08:27.222  1296  1296 I BrightnessSynchronizer: Completed Update: {[1841] 0.22057737(f), mUpdatedTypes=1, mConfirmedTypes=1, mTimeUpdated=39830055}
03-22 21:08:27.225   778   778 I WifiHAL : In GetLinkStatsCommand::handleResponse
03-22 21:08:27.426  1296  1534 I commit_sys_config_file: [settings-1-0,5]
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: Unknown error during verification code request
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: org.whispersystems.signalservice.api.push.exceptions.NonSuccessfulResponseCodeException: [502] Bad response: 502 Bad Gateway
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.validateServiceResponse(PushServiceSocket.java:1769)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.makeServiceRequest(PushServiceSocket.java:1712)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.requestVerificationCode(PushServiceSocket.java:373)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at org.whispersystems.signalservice.api.SignalServiceAccountManager.requestSmsVerificationCode(SignalServiceAccountManager.java:275)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at org.thoughtcrime.securesms.registration.VerifyAccountRepository.requestVerificationCode$lambda$4(VerifyAccountRepository.kt:132)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at org.thoughtcrime.securesms.registration.VerifyAccountRepository.$r8$lambda$4YuC6roLLQpy3ZfcLaXKaNALQKQ(Unknown Source:0)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at org.thoughtcrime.securesms.registration.VerifyAccountRepository$$ExternalSyntheticLambda3.call(Unknown Source:10)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.internal.operators.single.SingleFromCallable.subscribeActual(SingleFromCallable.java:43)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.internal.operators.single.SingleSubscribeOn$SubscribeOnObserver.run(SingleSubscribeOn.java:89)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.internal.schedulers.ScheduledDirectTask.call(ScheduledDirectTask.java:38)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.internal.schedulers.ScheduledDirectTask.call(ScheduledDirectTask.java:25)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at java.util.concurrent.FutureTask.run(FutureTask.java:264)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1137)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:637)
03-22 21:08:27.951 13494 13494 I EnterPhoneNumberFragmen: 	at java.lang.Thread.run(Thread.java:1012)
03-22 21:08:27.987  1296 10890 D CoreBackPreview: Window{7d23f55 u0 org.thoughtcrime.securesms/org.thoughtcrime.securesms.registration.RegistrationNavigationActivity}: Setting back callback OnBackInvokedCallbackInfo{mCallback=android.window.IOnBackInvokedCallback$Stub$Proxy@d309937, mPriority=0}
03-22 21:08:28.001  1296  1372 I input_focus: [Focus request 7d23f55 org.thoughtcrime.securesms/org.thoughtcrime.securesms.registration.RegistrationNavigationActivity,reason=UpdateInputWindows]
03-22 21:08:28.019  1296  1546 I input_focus: [Focus leaving 7dd07fe org.thoughtcrime.securesms/org.thoughtcrime.securesms.registration.RegistrationNavigationActivity (server),reason=Waiting for window because NO_WINDOW]
03-22 21:08:28.063  1296  1546 I input_focus: [Focus entering 7d23f55 org.thoughtcrime.securesms/org.thoughtcrime.securesms.registration.RegistrationNavigationActivity (server),reason=Window became focusable. Previous reason: NOT_VISIBLE]

@greyson-signal
Copy link
Contributor

@rany2 Hmm, so you've hit a 502 when requesting an SMS code. That means that our SMS provider couldn't send you an SMS for some reason. There's certain countries or telecom providers where sending SMS may be difficult or intermittent. That may be what's happening to you?

@rany2
Copy link

rany2 commented Mar 23, 2023

@greyson-signal Yes, this seems like the case. I just tried with my US SIM and it seems to be working. At any rate, the error needs to be more useful than this...

@alvinHRFM
Copy link

alvinHRFM commented Mar 23, 2023

Same issue on 6.13.8, here is the log:

03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: Unknown error during verification code request
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: org.whispersystems.signalservice.api.push.exceptions.NonSuccessfulResponseCodeException: [502] Bad response: 502 Bad Gateway
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.validateServiceResponse(PushServiceSocket.java:1769)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.makeServiceRequest(PushServiceSocket.java:1712)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.requestVerificationCode(PushServiceSocket.java:373)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at org.whispersystems.signalservice.api.SignalServiceAccountManager.requestSmsVerificationCode(SignalServiceAccountManager.java:275)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at org.thoughtcrime.securesms.registration.VerifyAccountRepository.requestVerificationCode$lambda$4(VerifyAccountRepository.kt:132)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at org.thoughtcrime.securesms.registration.VerifyAccountRepository.$r8$lambda$4YuC6roLLQpy3ZfcLaXKaNALQKQ(Unknown Source:0)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at org.thoughtcrime.securesms.registration.VerifyAccountRepository$$ExternalSyntheticLambda3.call(Unknown Source:10)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.internal.operators.single.SingleFromCallable.subscribeActual(SingleFromCallable.java:43)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.core.Single.subscribe(Single.java:4813)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.internal.operators.single.SingleSubscribeOn$SubscribeOnObserver.run(SingleSubscribeOn.java:89)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.internal.schedulers.ScheduledDirectTask.call(ScheduledDirectTask.java:38)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at io.reactivex.rxjava3.internal.schedulers.ScheduledDirectTask.call(ScheduledDirectTask.java:25)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at java.util.concurrent.FutureTask.run(FutureTask.java:264)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1137)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:637)
03-23 23:22:55.622 10439 10439 I EnterPhoneNumberFragmen: 	at java.lang.Thread.run(Thread.java:1012)

I tried to register the same number with the same SIM in the iOS version and succeeded.

@rany2
Copy link

rany2 commented Mar 23, 2023

@alvinHRFM weird, in my case it worked instantly on Android with my US SIM. It would make sense if they were using different verification services for iOS.

@frankont
Copy link

Same problem here as the original post using 6.16.2 on grapheneOS

@ew0k
Copy link

ew0k commented Jun 20, 2023

I am a legitimate user encountering same problems as described in this thread. I am unable to change my phone number. I am also using a de-googled Android phone. Apparently, that makes me a spammer and therefore I am not allowed equitable access to Signal features such as changing phone number.

@adriandelgg
Copy link

I'm also experiencing this issue. GrapheneOS without Google.

@greyson-signal
Copy link
Contributor

@ew0k @codeonblocks Can you please share debuglogs? We need to be able to see the specific error you're hitting.

@adriandelgg
Copy link

@ew0k @codeonblocks Can you please share debuglogs? We need to be able to see the specific error you're hitting.

I can't share debug logs because I can't login. However, I downgraded to a lower version and I was able to get a different pop-up which showed that I was locked out for over 3 hours.

@RHJihan
Copy link

RHJihan commented Feb 26, 2024

Still facing this issue.

I'm having trouble registering my phone number on Signal. I keep getting the message "Unable to connect to service. Please check network connection and try again." When I retry, I get temporarily banned for about 4 hours, and this cycle repeats. Sometimes, it repeatedly asks for a captcha.

Device: Samsung M32
OS: Android 13 (OneUI 5.1)
Signal version: 4.46.7 (Play Store)

@nicholas-signal
Copy link
Contributor

Hi @RHJihan, please upload a debug log so we can try to diagnose your issue.

@complikeyt
Copy link

Same issue pls help

I'm having trouble registering my phone number on Signal. I keep getting the message "Unable to connect to service. Please check network connection and try again." When I retry, I get temporarily banned for about 4 hours, and this cycle repeats. Sometimes, it repeatedly asks for a captcha.

@RHJihan
Copy link

RHJihan commented Feb 29, 2024

Hi @RHJihan, please upload a debug log so we can try to diagnose your issue.

I cannot login in the Signal app, so I did not find a way to generate the debug log.

@nicholas-signal
Copy link
Contributor

Same issue pls help

I'm having trouble registering my phone number on Signal. I keep getting the message "Unable to connect to service. Please check network connection and try again." When I retry, I get temporarily banned for about 4 hours, and this cycle repeats. Sometimes, it repeatedly asks for a captcha.

I cannot login in the Signal app, so I did not found a way to generate the debug log.

You can generate a debug log by tapping 8 times on the header in any screen during registration.

@RHJihan
Copy link

RHJihan commented Feb 29, 2024

@Abdulali97
Copy link

Abdulali97 commented Sep 24, 2024

same here, country Iraq, I try with and without a Vpn

debugging logs

Edit:
I just tried with a US number and it's working fine!

@minosimo
Copy link

I have run into this several time when setting up Signal for myself and family. Enabling a US VPN allowed me to register, perhaps Signal does some matching of the IP to area code. If you are running into this issue I would suggest using a VPN, Riseup is on FDroid if you need a free one.

@Dizirgee
Copy link

Dizirgee commented Dec 5, 2024

Just had this problem when trying to restore on new phone from local backup. After backup file was processed i was asked for phone number where sms was supposed to be send but i was getting this OP error , tried both wifi and mobile data but no luck. What worked for me (maybe just pure luck) i noticed there is updated version of signal on playstore so i manually updated and then it worked...

@minosimo : I hope they dont (match IPs) because that would be stupid thing to do, as a) its none of their business (to policy users in such way) b) whenever you are out of home you would have problem to restore...

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Development

No branches or pull requests