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

no call ringing when using own ntfy server #8761

Open
warioishere opened this issue Feb 15, 2024 · 72 comments
Open

no call ringing when using own ntfy server #8761

warioishere opened this issue Feb 15, 2024 · 72 comments
Labels
T-Defect Something isn't working: bugs, crashes, hangs and other reported problems

Comments

@warioishere
Copy link

warioishere commented Feb 15, 2024

Steps to reproduce

Hello,

I am encountering an issue with call notifications not working when using a custom ntfy server. When selecting Google Push for notifications, both call notifications and the ringtone work more or less as expected. However, switching to a custom ntfy server, the call notifications fail to come through, although message notifications continue to work without issue.

This problem persists across the following setup:

  • Matrix-synapse version: v1.101.0 (Latest)
  • Element version on smartphone: 1.6.10

Additionally, if relevant, a custom coturn server has been set up for handling phone and video calls.
Same problem on different phone. Had a p7 Pro with calyx + micro-G before, now having a P8Pro with GrapheneOS, same Problem again.

Could there be a compatibility issue with the custom ntfy server, or is there a specific configuration I might be missing to enable call notifications through it? Any guidance or suggestions on troubleshooting this issue would be greatly appreciated.

Thank you for your assistance.

Outcome

What did you expect?

Calling notification works on external ntfy server

What happened instead?

No ringing at all

Your phone model

Pixel 8 Pro

Operating system version

Android 14 GrapheneOS latest

Application version and app store

No response

Homeserver

f-Droid 1.6.10

Will you send logs?

Yes

Are you willing to provide a PR?

Yes

@warioishere warioishere added the T-Defect Something isn't working: bugs, crashes, hangs and other reported problems label Feb 15, 2024
@warioishere
Copy link
Author

Additional info. I've been talking to ntfy devs and we checked the server deployment of mine, they say my server setup is correct. They said I should reach out to the devs of element on android. Also, it doesnt work with the public ntfy.sh server.
Calls do not get a notfication, message notfications work fine.

Greetings

@p1gp1g
Copy link
Contributor

p1gp1g commented May 13, 2024

@warioishere
Copy link
Author

It may be related to this: https://matrix-org.github.io/synapse/latest/usage/configuration/config_documentation.html#ip_range_whitelist

thanks, just tried this. Added my own push server to the whitelisted IP adresses.

Still not luck. Element on Desktop Linux works, it rings. Phone doesnt want to ring when using own push server.

Sad no one of the devs answers me on this.

@p1gp1g
Copy link
Contributor

p1gp1g commented May 14, 2024

#7069 (comment) follow those steps

@warioishere
Copy link
Author

warioishere commented May 14, 2024

Again, I am not having problems receiving notifications at all. Messages notifications do arrive. Also the Push Test on element is sucessful. They only thing i missed was the IP whitelisting, but still, the notifications worked because of some firewall settings I have.

The only thing that doesnt work, are call notifications. I dont get notification if someone calls me. Nothing happens, only if I open the app, the call rings. If the app is closed. No ringing, allthough Element passes the Push test, and message notifications do arrive.

I checked those steps you linked to me, but no sucess.

@p1gp1g
Copy link
Contributor

p1gp1g commented May 14, 2024

  1. Have you disabled battery optimization for Element ?
  2. Have you disabled any kind of notification in Android settings ?
  3. Can you get some logs using ADB:
    1. Set Element in the background
    2. Send a message to this client
    3. Start a call

@warioishere
Copy link
Author

  1. Have you disabled battery optimization for Element ?

    1. Have you disabled any kind of notification in Android settings ?

    2. Can you get some logs using ADB:

      1. Set Element in the background
      2. Send a message to this client
      3. Start a call
  1. Bat Optimization is off for ntfy
  2. No, didnt disable any kind of notification on Android
  3. If your would tell me what to do, I can provide logs, I have ADB installed, but need some short instructions if you have time :)

@p1gp1g
Copy link
Contributor

p1gp1g commented May 14, 2024

  1. I was talking about battery optimization for Element.
  2. Please check, I've already seen someone who thought nothing were changed but the notification channel was mute
  3. Of course, here you are: PID=$(adb shell pidof im.vector.app) && adb logcat --pid $PID

@warioishere
Copy link
Author

  1. I was talking about battery optimization for Element.

    1. Please check, I've already seen someone who thought nothing were changed but the notification channel was mute

    2. Of course, here you are: PID=$(adb shell pidof im.vector.app) && adb logcat --pid $PID

what do you mean by channel notifications? All notifications on Android settings notification wise are allowed for the app. Also i get loud notfication on messages.

I will check battery optimiztions on element later. Just curious why I should disable it as it didnt read anything about disabling bat optimizations when own push servers are used.

Using googles push services makes the phone ring. Also on battery optimization activated on element.

Ill check the logs on element later or tomorrow with my gf

thanks in advance :)

@maximushugus
Copy link

I confirm I have the same issue as @warioishere
I have a very similar configuration with self hosted Synapse server, with self hosted ntfy server.
I also get normal messages notification, but no call notification on my phone without google services, relying on unified push for notifications.

I have to verify but it looks as if other users on my Synapse server wich are using Android with google notification also gets messages notifications but not call notification

@maximushugus
Copy link

maximushugus commented May 19, 2024

Looks similar to #8428 and #7135

@warioishere
Copy link
Author

I can just tell that the matrix server can reach the ntfy server and everytime I get a message server, I get this on my matrix logs:

2024-05-19 14:47:47,711 - synapse.push.httppusher - 236 - INFO - httppush.process-293 - Processing 0 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upuTdBfWrtUslO?up=1 starting at stream_ordering 1317362

when someone calls me, there is no log like this. I didnt check the element logs yet, sorry

@maximushugus
Copy link

Here is what I tried : in element by default in the general notification settings, "messages sent by robots" is disable. I enabled it, I will see if this resolve the problem

@p1gp1g
Copy link
Contributor

p1gp1g commented May 20, 2024

I can just tell that the matrix server can reach the ntfy server and everytime I get a message server, I get this on my matrix logs:

2024-05-19 14:47:47,711 - synapse.push.httppusher - 236 - INFO - httppush.process-293 - Processing 0 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upuTdBfWrtUslO?up=1 starting at stream_ordering 1317362

when someone calls me, there is no log like this. I didnt check the element logs yet, sorry

That's interesting, can you try your exact same setup with an account on matrix.org ? There might be something with synapse then

@warioishere
Copy link
Author

That's interesting, can you try your exact same setup with an account on matrix.org ? There might be something with synapse then

yes, I'll try later. I can imagine that its actually a synapse problem, because it doesnt work n schildichat too, although its a fork I guess and it could have a similar issue.

Will let you know

@warioishere
Copy link
Author

I can just tell that the matrix server can reach the ntfy server and everytime I get a message server, I get this on my matrix logs:

2024-05-19 14:47:47,711 - synapse.push.httppusher - 236 - INFO - httppush.process-293 - Processing 0 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upuTdBfWrtUslO?up=1 starting at stream_ordering 1317362

when someone calls me, there is no log like this. I didnt check the element logs yet, sorry

That's interesting, can you try your exact same setup with an account on matrix.org ? There might be something with synapse then

sorry for this late answer. Tested on matrix.org with my own ntfy server. It works instantly. Getting Call notification.
I it looks like its my matrix instance. But I dont know what to check for or change in homeserver.yml
Do you you have any hint on that?

@warioishere
Copy link
Author

Additional Info: we are using our own coturn server. Are there things or additional configs needed to be done there?

@warioishere
Copy link
Author

warioishere commented Jun 5, 2024

Additional Info, we are using a Letsencrypt Certificate from letsencrypt for the coturn server, there was once a coturn tester for matrix instances, but I cant find it anymore. It said that time ago that the setup was functional. Now i read this again:

NB: If your TLS certificate was provided by Let's Encrypt, TLS/DTLS will not work with any Matrix client that uses Chromium's WebRTC library. This currently includes Element Android & iOS; for more details, see their [respective](https://github.com/element-hq/element-android/issues/1533) [issues](https://github.com/element-hq/element-ios/issues/2712) as well as the underlying [WebRTC issue](https://bugs.chromium.org/p/webrtc/issues/detail?id=11710). Consider using a ZeroSSL certificate for your TURN server as a working alternative.

Could be the issue?

@warioishere
Copy link
Author

I changed to ZeroSSL Certificates, now Element Crashes after Calling the phone with my own matrix-synapse instance:

osVersion: google/husky/husky:14/AP1A.240505.005/2024053100:user/release-keys
package: im.vector.app:40106142
process: im.vector.app
processUptime: 55946 + 249 ms
installer: org.fdroid.fdroid

java.lang.RuntimeException: Unable to start service im.vector.app.core.services.CallAndroidService@b58b5ec with Intent { act=im.vector.app.core.services.CallService.ACTION_CALL_TERMINATED cmp=im.vector.app/.core.services.CallAndroidService (has extras) }: android.app.ForegroundServiceStartNotAllowedException: Service.startForeground() not allowed due to mAllowStartForeground false: service im.vector.app/.core.services.CallAndroidService
	at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5079)
	at android.app.ActivityThread.-$$Nest$mhandleServiceArgs(Unknown Source:0)
	at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2397)
	at android.os.Handler.dispatchMessage(Handler.java:107)
	at android.os.Looper.loopOnce(Looper.java:232)
	at android.os.Looper.loop(Looper.java:317)
	at android.app.ActivityThread.main(ActivityThread.java:8532)
	at java.lang.reflect.Method.invoke(Native Method)
	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:552)
	at com.android.internal.os.ExecInit.main(ExecInit.java:50)
	at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
	at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:359)
Caused by: android.app.ForegroundServiceStartNotAllowedException: Service.startForeground() not allowed due to mAllowStartForeground false: service im.vector.app/.core.services.CallAndroidService
	at android.app.ForegroundServiceStartNotAllowedException$1.createFromParcel(ForegroundServiceStartNotAllowedException.java:54)
	at android.app.ForegroundServiceStartNotAllowedException$1.createFromParcel(ForegroundServiceStartNotAllowedException.java:50)
	at android.os.Parcel.readParcelableInternal(Parcel.java:5035)
	at android.os.Parcel.readParcelable(Parcel.java:5017)
	at android.os.Parcel.createExceptionOrNull(Parcel.java:3186)
	at android.os.Parcel.createException(Parcel.java:3175)
	at android.os.Parcel.readException(Parcel.java:3151)
	at android.os.Parcel.readException(Parcel.java:3093)
	at android.app.IActivityManager$Stub$Proxy.setServiceForeground(IActivityManager.java:6876)
	at android.app.Service.startForeground(Service.java:878)
	at androidx.work.impl.foreground.SystemForegroundService$Api29Impl$$ExternalSyntheticApiModelOutline0.m(R8$$SyntheticClass:1)
	at im.vector.app.core.extensions.ServiceKt.startForegroundCompat(Service.kt:31)
	at im.vector.app.core.extensions.ServiceKt.startForegroundCompat$default(Service.kt:6)
	at im.vector.app.core.services.CallAndroidService.handleUnexpectedState(CallAndroidService.kt:55)
	at im.vector.app.core.services.CallAndroidService.handleCallTerminated(CallAndroidService.kt:89)
	at im.vector.app.core.services.CallAndroidService.onStartCommand(CallAndroidService.kt:98)
	at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5061)
	... 11 more

@santilococo
Copy link

I changed to ZeroSSL Certificates, now Element Crashes after Calling the phone with my own matrix-synapse instance:

osVersion: google/husky/husky:14/AP1A.240505.005/2024053100:user/release-keys
package: im.vector.app:40106142
process: im.vector.app
processUptime: 55946 + 249 ms
installer: org.fdroid.fdroid

java.lang.RuntimeException: Unable to start service im.vector.app.core.services.CallAndroidService@b58b5ec with Intent { act=im.vector.app.core.services.CallService.ACTION_CALL_TERMINATED cmp=im.vector.app/.core.services.CallAndroidService (has extras) }: android.app.ForegroundServiceStartNotAllowedException: Service.startForeground() not allowed due to mAllowStartForeground false: service im.vector.app/.core.services.CallAndroidService
	at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5079)
	at android.app.ActivityThread.-$$Nest$mhandleServiceArgs(Unknown Source:0)
	at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2397)
	at android.os.Handler.dispatchMessage(Handler.java:107)
	at android.os.Looper.loopOnce(Looper.java:232)
	at android.os.Looper.loop(Looper.java:317)
	at android.app.ActivityThread.main(ActivityThread.java:8532)
	at java.lang.reflect.Method.invoke(Native Method)
	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:552)
	at com.android.internal.os.ExecInit.main(ExecInit.java:50)
	at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
	at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:359)
Caused by: android.app.ForegroundServiceStartNotAllowedException: Service.startForeground() not allowed due to mAllowStartForeground false: service im.vector.app/.core.services.CallAndroidService
	at android.app.ForegroundServiceStartNotAllowedException$1.createFromParcel(ForegroundServiceStartNotAllowedException.java:54)
	at android.app.ForegroundServiceStartNotAllowedException$1.createFromParcel(ForegroundServiceStartNotAllowedException.java:50)
	at android.os.Parcel.readParcelableInternal(Parcel.java:5035)
	at android.os.Parcel.readParcelable(Parcel.java:5017)
	at android.os.Parcel.createExceptionOrNull(Parcel.java:3186)
	at android.os.Parcel.createException(Parcel.java:3175)
	at android.os.Parcel.readException(Parcel.java:3151)
	at android.os.Parcel.readException(Parcel.java:3093)
	at android.app.IActivityManager$Stub$Proxy.setServiceForeground(IActivityManager.java:6876)
	at android.app.Service.startForeground(Service.java:878)
	at androidx.work.impl.foreground.SystemForegroundService$Api29Impl$$ExternalSyntheticApiModelOutline0.m(R8$$SyntheticClass:1)
	at im.vector.app.core.extensions.ServiceKt.startForegroundCompat(Service.kt:31)
	at im.vector.app.core.extensions.ServiceKt.startForegroundCompat$default(Service.kt:6)
	at im.vector.app.core.services.CallAndroidService.handleUnexpectedState(CallAndroidService.kt:55)
	at im.vector.app.core.services.CallAndroidService.handleCallTerminated(CallAndroidService.kt:89)
	at im.vector.app.core.services.CallAndroidService.onStartCommand(CallAndroidService.kt:98)
	at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5061)
	... 11 more

I am having the exact same problem. With background synchronization as the notification method, it works great (although not always instantly), but at least call notifications work sometimes. However, if I use ntfy, it crashes when an incoming call is received, throwing the exact same error as @warioishere described. I think this might be related to the fact that Element is trying to do something with foreground services, but it isn't allowed (since it is not using that method). I can help with debugging this if you think my theory might be correct.

@warioishere
Copy link
Author

warioishere commented Oct 6, 2024

I changed to ZeroSSL Certificates, now Element Crashes after Calling the phone with my own matrix-synapse instance:

osVersion: google/husky/husky:14/AP1A.240505.005/2024053100:user/release-keys
package: im.vector.app:40106142
process: im.vector.app
processUptime: 55946 + 249 ms
installer: org.fdroid.fdroid

java.lang.RuntimeException: Unable to start service im.vector.app.core.services.CallAndroidService@b58b5ec with Intent { act=im.vector.app.core.services.CallService.ACTION_CALL_TERMINATED cmp=im.vector.app/.core.services.CallAndroidService (has extras) }: android.app.ForegroundServiceStartNotAllowedException: Service.startForeground() not allowed due to mAllowStartForeground false: service im.vector.app/.core.services.CallAndroidService
	at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5079)
	at android.app.ActivityThread.-$$Nest$mhandleServiceArgs(Unknown Source:0)
	at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2397)
	at android.os.Handler.dispatchMessage(Handler.java:107)
	at android.os.Looper.loopOnce(Looper.java:232)
	at android.os.Looper.loop(Looper.java:317)
	at android.app.ActivityThread.main(ActivityThread.java:8532)
	at java.lang.reflect.Method.invoke(Native Method)
	at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:552)
	at com.android.internal.os.ExecInit.main(ExecInit.java:50)
	at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
	at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:359)
Caused by: android.app.ForegroundServiceStartNotAllowedException: Service.startForeground() not allowed due to mAllowStartForeground false: service im.vector.app/.core.services.CallAndroidService
	at android.app.ForegroundServiceStartNotAllowedException$1.createFromParcel(ForegroundServiceStartNotAllowedException.java:54)
	at android.app.ForegroundServiceStartNotAllowedException$1.createFromParcel(ForegroundServiceStartNotAllowedException.java:50)
	at android.os.Parcel.readParcelableInternal(Parcel.java:5035)
	at android.os.Parcel.readParcelable(Parcel.java:5017)
	at android.os.Parcel.createExceptionOrNull(Parcel.java:3186)
	at android.os.Parcel.createException(Parcel.java:3175)
	at android.os.Parcel.readException(Parcel.java:3151)
	at android.os.Parcel.readException(Parcel.java:3093)
	at android.app.IActivityManager$Stub$Proxy.setServiceForeground(IActivityManager.java:6876)
	at android.app.Service.startForeground(Service.java:878)
	at androidx.work.impl.foreground.SystemForegroundService$Api29Impl$$ExternalSyntheticApiModelOutline0.m(R8$$SyntheticClass:1)
	at im.vector.app.core.extensions.ServiceKt.startForegroundCompat(Service.kt:31)
	at im.vector.app.core.extensions.ServiceKt.startForegroundCompat$default(Service.kt:6)
	at im.vector.app.core.services.CallAndroidService.handleUnexpectedState(CallAndroidService.kt:55)
	at im.vector.app.core.services.CallAndroidService.handleCallTerminated(CallAndroidService.kt:89)
	at im.vector.app.core.services.CallAndroidService.onStartCommand(CallAndroidService.kt:98)
	at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:5061)
	... 11 more

I am having the exact same problem. With background synchronization as the notification method, it works great (although not always instantly), but at least call notifications work sometimes. However, if I use ntfy, it crashes when an incoming call is received, throwing the exact same error as @warioishere described. I think this might be related to the fact that Element is trying to do something with foreground services, but it isn't allowed (since it is not using that method). I can help with debugging this if you think my theory might be correct.

sorry to answer so late, but why does it work with googles fcm without a problem,
Nobody, absolute nobody from the devs are responding. they rather fix millions of issues, which I dont even realize that they are there, but fucking notfications with a own push provider they fucking dont care.

I talked to the devs of ntfy and they say, as far as the logs say, they cant do anything, the push server delivers the message to matrix-synapse properly, and also to the client instantly whenever a message is sent:

2024-10-06 10:09:07,417 - synapse.push.httppusher - 236 - INFO - httppush.process-8679 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upRfmKVtd7pBP9?up=1 starting at stream_ordering 1753670
2024-10-06 10:09:07,417 - synapse.push.httppusher - 236 - INFO - httppush.process-8680 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upOpna7Q1rvIi1?up=1 starting at stream_ordering 1753670
2024-10-06 10:09:07,419 - synapse.push.httppusher - 236 - INFO - httppush.process-8681 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upY7KEE1gg3Xgx?up=1 starting at stream_ordering 1753670
2024-10-06 10:09:07,419 - synapse.push.httppusher - 236 - INFO - httppush.process-8678 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upvnGFStGzxUfC?up=1 starting at stream_ordering 1753670
2024-10-06 10:09:07,443 - synapse.http.client - 428 - INFO - httppush.process-8681 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:09:07,448 - synapse.http.client - 428 - INFO - httppush.process-8679 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:09:07,451 - synapse.http.client - 428 - INFO - httppush.process-8678 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:09:07,462 - synapse.http.client - 428 - INFO - httppush.process-8680 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:09:39,233 - synapse.push.httppusher - 236 - INFO - httppush.process-8682 - Processing 0 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upvnGFStGzxUfC?up=1 starting at stream_ordering 1753689
2024-10-06 10:09:39,235 - synapse.push.httppusher - 236 - INFO - httppush.process-8684 - Processing 0 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upOpna7Q1rvIi1?up=1 starting at stream_ordering 1753689
2024-10-06 10:09:39,235 - synapse.push.httppusher - 236 - INFO - httppush.process-8683 - Processing 0 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upRfmKVtd7pBP9?up=1 starting at stream_ordering 1753689
2024-10-06 10:09:39,235 - synapse.push.httppusher - 236 - INFO - httppush.process-8685 - Processing 0 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upY7KEE1gg3Xgx?up=1 starting at stream_ordering 1753689
2024-10-06 10:11:17,573 - synapse.push.httppusher - 236 - INFO - httppush.process-8689 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upY7KEE1gg3Xgx?up=1 starting at stream_ordering 1753689
2024-10-06 10:11:17,574 - synapse.push.httppusher - 236 - INFO - httppush.process-8688 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upOpna7Q1rvIi1?up=1 starting at stream_ordering 1753689
2024-10-06 10:11:17,574 - synapse.push.httppusher - 236 - INFO - httppush.process-8687 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upRfmKVtd7pBP9?up=1 starting at stream_ordering 1753689
2024-10-06 10:11:17,574 - synapse.push.httppusher - 236 - INFO - httppush.process-8686 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upvnGFStGzxUfC?up=1 starting at stream_ordering 1753689
2024-10-06 10:11:17,608 - synapse.http.client - 428 - INFO - httppush.process-8686 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:11:17,611 - synapse.http.client - 428 - INFO - httppush.process-8688 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:11:17,615 - synapse.http.client - 428 - INFO - httppush.process-8687 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:11:17,627 - synapse.http.client - 428 - INFO - httppush.process-8689 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:11:18,056 - synapse.push.httppusher - 236 - INFO - httppush.process-8690 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upvnGFStGzxUfC?up=1 starting at stream_ordering 1753696
2024-10-06 10:11:18,058 - synapse.push.httppusher - 236 - INFO - httppush.process-8691 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upRfmKVtd7pBP9?up=1 starting at stream_ordering 1753696
2024-10-06 10:11:18,058 - synapse.push.httppusher - 236 - INFO - httppush.process-8693 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upY7KEE1gg3Xgx?up=1 starting at stream_ordering 1753696
2024-10-06 10:11:18,059 - synapse.push.httppusher - 236 - INFO - httppush.process-8692 - Processing 1 unprocessed push actions for @marioyd:matrix.yourdevice.ch/im.vector.app.android/https://ntfy.yourdevice.ch/upOpna7Q1rvIi1?up=1 starting at stream_ordering 1753696
2024-10-06 10:11:18,071 - synapse.http.client - 428 - INFO - httppush.process-8690 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:11:18,074 - synapse.http.client - 428 - INFO - httppush.process-8691 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:11:18,079 - synapse.http.client - 428 - INFO - httppush.process-8692 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:11:18,090 - synapse.http.client - 428 - INFO - httppush.process-8693 - Received response to POST https://ntfy.yourdevice.ch/_matrix/push/v1/notify: 200
2024-10-06 10:11:18,293 - synapse.push.httppusher

@p1gp1g
Copy link
Contributor

p1gp1g commented Oct 8, 2024

If you have an exception android.app.ForegroundServiceStartNotAllowedException, this is because you need to set unrestricted battery use for element.

FCM don't have that issue because they use an option reserved for system applications (BroadcastOptions#setTemporaryAppAllowlist)

The good news is there is a way to do the same thing without this privilege. And it will be implemented in the next version of unifiedpush

@warioishere
Copy link
Author

If you have an exception android.app.ForegroundServiceStartNotAllowedException, this is because you need to set unrestricted battery use for element.

FCM don't have that issue because they use an option reserved for system applications (BroadcastOptions#setTemporaryAppAllowlist)

The good news is there is a way to do the same thing without this privilege. And it will be implemented in the next version of unifiedpush

Why does it work on matrix.org server then with my ntfy server and the same element version?
I get calling notification when on a matrix.org account but using my own ntfy server.

@p1gp1g
Copy link
Contributor

p1gp1g commented Oct 8, 2024

This is what this particular exception means.

@warioishere
Copy link
Author

This is what this particular exception means.

I still dont understand why it works on matrix.org Servers and not on my homeserver. What are people doing wrong, as I am not the only one with this kind of problems.

I don't need to set element for unlimited background to work with calling notifications with own ntfy server and an account on matrix.org

@p1gp1g
Copy link
Contributor

p1gp1g commented Oct 8, 2024

That's probably how you test it. You have 2 solutions: disable battery optimizations for element (which won't increase your battery consumption), or wait for the new version to be published and adopted by element.

@warioishere
Copy link
Author

That's probably how you test it. You have 2 solutions: disable battery optimizations for element (which won't increase your battery consumption), or wait for the new version to be published and adopted by element.

I dont want to question anything what you say, still I dont unterstand why i dont need to disable bat optimization for element when I use an matrix.org account.

I want to understand the matter, not just do some things which I don't understand.
Thanks for your time :)

@warioishere
Copy link
Author

warioishere commented Dec 19, 2024

my humble analyzis:

Jobs (SyncWorker) are being started multiple times and then cancelled ("Task was cancelled").
This suggests that either the constraints for executing these jobs are constantly being met and then revoked, or another process is interrupting and cancelling the jobs.

Since the SyncWorker is being interrupted, incoming call events (pushed from the Matrix server) cannot be processed in a timely manner. This results in only a notification being generated, but no active ringing.

@p1gp1g
Copy link
Contributor

p1gp1g commented Dec 20, 2024

Can you share a video of what you observe ?

@warioishere
Copy link
Author

warioishere commented Dec 20, 2024

heres a link, you can hear a short notification sound of the phone that is beeing called. The ringing tone you hear is the sound of the phone that is calling. Its a phone with a matrix.org account that is calling. And the phone that is being called, is on our setup

https://privatecloud.yourdevice.ch/s/6f2HGFQLKd6PP75

Can you share a video of what you observe ?

@p1gp1g
Copy link
Contributor

p1gp1g commented Dec 20, 2024

Ok, so it doesn't seem related to push notifications. Can you do 2-3 tests and grep the logs only for element ? And write down the time you do the tests: adb logcat --pid=(adb shell pidof io.element.android)

@warioishere
Copy link
Author

Ok, so it doesn't seem related to push notifications. Can you do 2-3 tests and grep the logs only for element ? And write down the time you do the tests: adb logcat --pid=(adb shell pidof io.element.android)

I get a syntax error with:

mario@vivobook:~$ adb logcat --pid=(adb shell pidof io.element.android)
bash: Syntaxfehler beim unerwarteten Wort »(«

with $ i get:

mario@vivobook:~$ adb logcat --pid=$(adb shell pidof io.element.android)
logcat: pid '' out of range.

@p1gp1g
Copy link
Contributor

p1gp1g commented Dec 20, 2024

adb logcat --pid=$(adb shell pidof im.vector.app), it was the wrong package name (and the $ isn't needed with fish :) , but I forgot to add it again)

@warioishere
Copy link
Author

try one: exactly in this moment (let it ring, and stop the call on the phone that calls

12-20 16:51:03.016 13606 13619 I im.vector.app: Background concurrent mark compact GC freed 97MB AllocSpace bytes, 155(3148KB) LOS objects, 54% free, 79MB/175MB, paused 6.320ms,7.413ms total 302.856s
12-20 16:51:03.178 13606  6599 D TrafficStats: tagSocket(123) with statsTag=0xffffffff, statsUid=-1
12-20 16:51:03.183 13606  6600 D TrafficStats: tagSocket(126) with statsTag=0xffffffff, statsUid=-1
12-20 16:51:03.456 13606  6463 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:51:03.456 13606  6463 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:51:03.456 13606  6463 D UnifiedPush: Found saved distributor.
12-20 16:51:03.457 13606  6463 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:51:03.457 13606  6463 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:51:03.457 13606  6463 D UnifiedPush: Found saved distributor.
12-20 16:51:03.587 13606 13675 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:51:03.596 13606 13675 D WM-SystemJobScheduler: Scheduling work ID fc8983d1-72bf-433e-beac-d9799a5b3934Job ID 30997
12-20 16:51:03.612 13606 13675 D WM-GreedyScheduler: Starting tracking for fc8983d1-72bf-433e-beac-d9799a5b3934
12-20 16:51:03.616 13606 13675 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:51:03.616 13606 13675 D WM-NetworkStateTracker: Registering network callback
12-20 16:51:03.618 13606 13675 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: fc8983d1-72bf-433e-beac-d9799a5b3934}
12-20 16:51:03.619 13606 13675 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=fc8983d1-72bf-433e-beac-d9799a5b3934, generation=0)
12-20 16:51:03.623 13606 13675 D WM-Processor: Processor cancelling fc8983d1-72bf-433e-beac-d9799a5b3934
12-20 16:51:03.623 13606 13675 D WM-Processor: WorkerWrapper could not be found for fc8983d1-72bf-433e-beac-d9799a5b3934
12-20 16:51:03.624 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=fc8983d1-72bf-433e-beac-d9799a5b3934, generation=0)
12-20 16:51:03.624 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:51:03.625 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=fc8983d1-72bf-433e-beac-d9799a5b3934, generation=0)
12-20 16:51:03.625 13606 13675 D WM-GreedyScheduler: Cancelling work ID fc8983d1-72bf-433e-beac-d9799a5b3934
12-20 16:51:03.667 13606 13675 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:51:03.671 13606 13675 D WM-SystemJobScheduler: Scheduling work ID 439c0d53-039d-4edd-a288-60fa52a0337fJob ID 30998
12-20 16:51:03.676 13606 13675 D WM-GreedyScheduler: Starting tracking for 439c0d53-039d-4edd-a288-60fa52a0337f
12-20 16:51:03.676 13606 13675 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 439c0d53-039d-4edd-a288-60fa52a0337f}
12-20 16:51:03.676 13606 13675 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: fc8983d1-72bf-433e-beac-d9799a5b3934}
12-20 16:51:03.676 13606 13675 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=439c0d53-039d-4edd-a288-60fa52a0337f, generation=0)
12-20 16:51:03.676 13606 13675 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=fc8983d1-72bf-433e-beac-d9799a5b3934, generation=0)
12-20 16:51:03.677 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=439c0d53-039d-4edd-a288-60fa52a0337f, generation=0)
12-20 16:51:03.680 13606 13675 W WM-Processor: Didn't find WorkSpec for id WorkGenerationalId(workSpecId=fc8983d1-72bf-433e-beac-d9799a5b3934, generation=0)
12-20 16:51:03.681 13606 13675 W WM-Processor: Didn't find WorkSpec for id WorkGenerationalId(workSpecId=fc8983d1-72bf-433e-beac-d9799a5b3934, generation=0)
12-20 16:51:03.682 13606 13606 D WM-Processor: Processor fc8983d1-72bf-433e-beac-d9799a5b3934 executed; reschedule = false
12-20 16:51:03.682 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=fc8983d1-72bf-433e-beac-d9799a5b3934, generation=0)
12-20 16:51:03.682 13606 13606 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 439c0d53-039d-4edd-a288-60fa52a0337f}
12-20 16:51:03.682 13606 13606 D WM-SystemJobService: fc8983d1-72bf-433e-beac-d9799a5b3934 executed on JobScheduler
12-20 16:51:03.682 13606 13675 D WM-Processor: WorkerWrapper could not be found for fc8983d1-72bf-433e-beac-d9799a5b3934
12-20 16:51:03.682 13606 13675 D WM-StopWorkRunnable: StopWorkRunnable for fc8983d1-72bf-433e-beac-d9799a5b3934; Processor.stopWork = false
12-20 16:51:03.682 13606 13606 D WM-Processor: Processor fc8983d1-72bf-433e-beac-d9799a5b3934 executed; reschedule = false
12-20 16:51:03.682 13606 13606 D WM-SystemJobService: fc8983d1-72bf-433e-beac-d9799a5b3934 executed on JobScheduler
12-20 16:51:03.682 13606 13675 D WM-Processor: WorkerWrapper could not be found for fc8983d1-72bf-433e-beac-d9799a5b3934
12-20 16:51:03.682 13606 13675 D WM-StopWorkRunnable: StopWorkRunnable for fc8983d1-72bf-433e-beac-d9799a5b3934; Processor.stopWork = false
12-20 16:51:03.686 13606 13675 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=439c0d53-039d-4edd-a288-60fa52a0337f, generation=0)
12-20 16:51:03.686 13606 13675 W WM-Processor: Didn't find WorkSpec for id WorkGenerationalId(workSpecId=fc8983d1-72bf-433e-beac-d9799a5b3934, generation=0)
12-20 16:51:03.686 13606 13606 D WM-Processor: Processor fc8983d1-72bf-433e-beac-d9799a5b3934 executed; reschedule = false
12-20 16:51:03.686 13606 13606 D WM-SystemJobService: fc8983d1-72bf-433e-beac-d9799a5b3934 executed on JobScheduler
12-20 16:51:03.687 13606 13675 D WM-Processor: Work WorkGenerationalId(workSpecId=439c0d53-039d-4edd-a288-60fa52a0337f, generation=0) is already enqueued for processing
12-20 16:51:03.692 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:51:10.635 13606 13670 D WM-Processor: Processor cancelling 439c0d53-039d-4edd-a288-60fa52a0337f
12-20 16:51:10.636 13606 13670 D WM-WorkerWrapper: Work interrupted for Work [ id=439c0d53-039d-4edd-a288-60fa52a0337f, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:51:10.643 13606 13670 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:51:10.646 13606 13670 D WM-WorkerWrapper: WorkSpec {WorkSpec: 439c0d53-039d-4edd-a288-60fa52a0337f} is already done. Not interrupting.
12-20 16:51:10.646 13606 13670 D WM-Processor: WorkerWrapper interrupted for 439c0d53-039d-4edd-a288-60fa52a0337f
12-20 16:51:10.647 13606 13606 D WM-Processor: Processor 439c0d53-039d-4edd-a288-60fa52a0337f executed; reschedule = false
12-20 16:51:10.647 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=439c0d53-039d-4edd-a288-60fa52a0337f, generation=0)
12-20 16:51:10.647 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:51:10.649 13606 13606 D WM-SystemJobService: 439c0d53-039d-4edd-a288-60fa52a0337f executed on JobScheduler
12-20 16:51:10.652 13606 13670 D WM-GreedyScheduler: Cancelling work ID 439c0d53-039d-4edd-a288-60fa52a0337f
12-20 16:51:10.654 13606 13670 D WM-Processor: Processor cancelling 5f17baaf-6f9a-4cb7-8409-688135c5f63e
12-20 16:51:10.654 13606 13670 D WM-Processor: WorkerWrapper could not be found for 5f17baaf-6f9a-4cb7-8409-688135c5f63e
12-20 16:51:10.658 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=439c0d53-039d-4edd-a288-60fa52a0337f, generation=0)
12-20 16:51:10.658 13606 13670 D WM-GreedyScheduler: Cancelling work ID 5f17baaf-6f9a-4cb7-8409-688135c5f63e
12-20 16:51:10.661 13606 13670 D WM-Processor: Processor cancelling d66b05ee-427d-475b-a424-2b2022a0fab6
12-20 16:51:10.662 13606 13670 D WM-Processor: WorkerWrapper could not be found for d66b05ee-427d-475b-a424-2b2022a0fab6
12-20 16:51:10.662 13606 13670 D WM-GreedyScheduler: Cancelling work ID d66b05ee-427d-475b-a424-2b2022a0fab6
12-20 16:51:10.668 13606 13670 D WM-Processor: Processor cancelling e470debb-4fce-4830-b840-17800c6be3a3
12-20 16:51:10.668 13606 13670 D WM-Processor: WorkerWrapper could not be found for e470debb-4fce-4830-b840-17800c6be3a3
12-20 16:51:10.668 13606 13670 D WM-GreedyScheduler: Cancelling work ID e470debb-4fce-4830-b840-17800c6be3a3
12-20 16:51:10.701 13606 13670 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:51:10.713 13606 13670 D WM-SystemJobScheduler: Scheduling work ID 3f94864c-b7a4-4756-8bd4-537a3f9c8cfaJob ID 30999
12-20 16:51:10.732 13606 13670 D WM-GreedyScheduler: Starting tracking for 3f94864c-b7a4-4756-8bd4-537a3f9c8cfa
12-20 16:51:10.735 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=3f94864c-b7a4-4756-8bd4-537a3f9c8cfa, generation=0)
12-20 16:51:10.739 13606 13670 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:51:10.739 13606 13670 D WM-NetworkStateTracker: Registering network callback
12-20 16:51:10.747 13606 13670 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 3f94864c-b7a4-4756-8bd4-537a3f9c8cfa}
12-20 16:51:10.747 13606 13670 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=3f94864c-b7a4-4756-8bd4-537a3f9c8cfa, generation=0)
12-20 16:51:10.747 13606 13670 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:51:10.747 13606 13670 D WM-WorkerWrapper: Work interrupted for Work [ id=439c0d53-039d-4edd-a288-60fa52a0337f, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:51:10.749 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:51:10.752 13606 13670 D WM-GreedyScheduler: Cancelling work ID 439c0d53-039d-4edd-a288-60fa52a0337f
12-20 16:51:10.758 13606 13670 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=3f94864c-b7a4-4756-8bd4-537a3f9c8cfa, generation=0)
12-20 16:51:10.760 13606 13670 D WM-Processor: Work WorkGenerationalId(workSpecId=3f94864c-b7a4-4756-8bd4-537a3f9c8cfa, generation=0) is already enqueued for processing
12-20 16:51:10.766 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:51:15.650 13606 13619 I im.vector.app: Background concurrent mark compact GC freed 102MB AllocSpace bytes, 113(2260KB) LOS objects, 56% free, 74MB/170MB, paused 1.119ms,3.141ms total 483.918ms
12-20 16:51:16.641 13606 13672 D WM-Processor: Processor cancelling 3f94864c-b7a4-4756-8bd4-537a3f9c8cfa
12-20 16:51:16.642 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=3f94864c-b7a4-4756-8bd4-537a3f9c8cfa, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:51:16.653 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:51:16.654 13606 13672 D WM-WorkerWrapper: WorkSpec {WorkSpec: 3f94864c-b7a4-4756-8bd4-537a3f9c8cfa} is already done. Not interrupting.
12-20 16:51:16.654 13606 13672 D WM-Processor: WorkerWrapper interrupted for 3f94864c-b7a4-4756-8bd4-537a3f9c8cfa
12-20 16:51:16.654 13606 13606 D WM-Processor: Processor 3f94864c-b7a4-4756-8bd4-537a3f9c8cfa executed; reschedule = false
12-20 16:51:16.654 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=3f94864c-b7a4-4756-8bd4-537a3f9c8cfa, generation=0)
12-20 16:51:16.654 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:51:16.656 13606 13606 D WM-SystemJobService: 3f94864c-b7a4-4756-8bd4-537a3f9c8cfa executed on JobScheduler
12-20 16:51:16.664 13606 13672 D WM-GreedyScheduler: Cancelling work ID 3f94864c-b7a4-4756-8bd4-537a3f9c8cfa
12-20 16:51:16.676 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:51:16.685 13606 13672 D WM-SystemJobScheduler: Scheduling work ID b9c729cd-62f8-479b-84c2-35987b936c64Job ID 31000
12-20 16:51:16.701 13606 13672 D WM-GreedyScheduler: Starting tracking for b9c729cd-62f8-479b-84c2-35987b936c64
12-20 16:51:16.702 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=b9c729cd-62f8-479b-84c2-35987b936c64, generation=0)
12-20 16:51:16.709 13606 13672 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:51:16.710 13606 13672 D WM-NetworkStateTracker: Registering network callback
12-20 16:51:16.713 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: b9c729cd-62f8-479b-84c2-35987b936c64}
12-20 16:51:16.714 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=b9c729cd-62f8-479b-84c2-35987b936c64, generation=0)
12-20 16:51:16.714 13606 13672 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:51:16.714 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=3f94864c-b7a4-4756-8bd4-537a3f9c8cfa, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:51:16.718 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:51:16.718 13606 13672 D WM-GreedyScheduler: Cancelling work ID 3f94864c-b7a4-4756-8bd4-537a3f9c8cfa
12-20 16:51:16.724 13606 13672 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=b9c729cd-62f8-479b-84c2-35987b936c64, generation=0)
12-20 16:51:16.727 13606 13672 D WM-Processor: Work WorkGenerationalId(workSpecId=b9c729cd-62f8-479b-84c2-35987b936c64, generation=0) is already enqueued for processing
12-20 16:51:16.734 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:51:18.797 13606  9284 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:51:18.797 13606  9284 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:51:18.797 13606  9284 D UnifiedPush: Found saved distributor.
12-20 16:51:18.798 13606  9284 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:51:18.798 13606  9284 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:51:18.798 13606  9284 D UnifiedPush: Found saved distributor.
12-20 16:51:18.809 13606 13676 D WM-Processor: Processor cancelling a571de79-ae99-4ad9-b59b-ee8b2233e4ad
12-20 16:51:18.809 13606 13676 D WM-Processor: WorkerWrapper could not be found for a571de79-ae99-4ad9-b59b-ee8b2233e4ad
12-20 16:51:18.814 13606 13676 D WM-GreedyScheduler: Cancelling work ID a571de79-ae99-4ad9-b59b-ee8b2233e4ad
12-20 16:51:18.819 13606 13676 D WM-Processor: Processor cancelling b9c729cd-62f8-479b-84c2-35987b936c64
12-20 16:51:18.819 13606 13676 D WM-WorkerWrapper: Work interrupted for Work [ id=b9c729cd-62f8-479b-84c2-35987b936c64, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:51:18.825 13606 13676 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:51:18.826 13606 13676 D WM-Processor: WorkerWrapper interrupted for b9c729cd-62f8-479b-84c2-35987b936c64
12-20 16:51:18.826 13606 13606 D WM-Processor: Processor b9c729cd-62f8-479b-84c2-35987b936c64 executed; reschedule = false
12-20 16:51:18.826 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=b9c729cd-62f8-479b-84c2-35987b936c64, generation=0)
12-20 16:51:18.827 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:51:18.829 13606 13606 D WM-SystemJobService: b9c729cd-62f8-479b-84c2-35987b936c64 executed on JobScheduler
12-20 16:51:18.832 13606 13676 D WM-GreedyScheduler: Cancelling work ID b9c729cd-62f8-479b-84c2-35987b936c64
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper: Work [ id=b9c729cd-62f8-479b-84c2-35987b936c64, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ] was cancelled
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper: java.util.concurrent.CancellationException: Task was cancelled.
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.cancellationExceptionWithCause(SourceFile:3)
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.getDoneValue(SourceFile:31)
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.get(SourceFile:92)
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper:      at androidx.work.impl.WorkerWrapper$2.run(SourceFile:5)
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper:      at androidx.work.impl.utils.SerialExecutorImpl$Task.run(SourceFile:3)
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:644)
12-20 16:51:18.841 13606 13676 I WM-WorkerWrapper:      at java.lang.Thread.run(Thread.java:1117)
12-20 16:51:18.842 13606 13676 D WM-WorkerWrapper: Work interrupted for Work [ id=b9c729cd-62f8-479b-84c2-35987b936c64, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:51:18.848 13606 13676 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:51:18.874 13606 13676 D WM-GreedyScheduler: Cancelling work ID b9c729cd-62f8-479b-84c2-35987b936c64
12-20 16:51:18.875 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=b9c729cd-62f8-479b-84c2-35987b936c64, generation=0)
12-20 16:51:29.235 13606 13619 I im.vector.app: Background concurrent mark compact GC freed 94MB AllocSpace bytes, 140(2800KB) LOS objects, 55% free, 77MB/173MB, paused 7.753ms,10.059ms total 1.712s

@warioishere
Copy link
Author

second:

12-20 16:53:20.216 13606  6675 D TrafficStats: tagSocket(123) with statsTag=0xffffffff, statsUid=-1
12-20 16:53:20.217 13606  6599 D TrafficStats: tagSocket(126) with statsTag=0xffffffff, statsUid=-1
12-20 16:53:20.427 13606  6613 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:53:20.427 13606  6613 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:53:20.427 13606  6613 D UnifiedPush: Found saved distributor.
12-20 16:53:20.429 13606  6613 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:53:20.430 13606  6613 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:53:20.430 13606  6613 D UnifiedPush: Found saved distributor.
12-20 16:53:20.555 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:53:20.570 13606 13672 D WM-SystemJobScheduler: Scheduling work ID bc894ec4-8ce2-4989-b88a-0facbadbbf06Job ID 31002
12-20 16:53:20.586 13606 13672 D WM-GreedyScheduler: Starting tracking for bc894ec4-8ce2-4989-b88a-0facbadbbf06
12-20 16:53:20.596 13606 13672 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:53:20.596 13606 13672 D WM-NetworkStateTracker: Registering network callback
12-20 16:53:20.601 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: bc894ec4-8ce2-4989-b88a-0facbadbbf06}
12-20 16:53:20.601 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=bc894ec4-8ce2-4989-b88a-0facbadbbf06, generation=0)
12-20 16:53:20.612 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:53:20.614 13606 13672 D WM-Processor: Processor cancelling bc894ec4-8ce2-4989-b88a-0facbadbbf06
12-20 16:53:20.615 13606 13672 D WM-Processor: WorkerWrapper could not be found for bc894ec4-8ce2-4989-b88a-0facbadbbf06
12-20 16:53:20.619 13606 13672 D WM-GreedyScheduler: Cancelling work ID bc894ec4-8ce2-4989-b88a-0facbadbbf06
12-20 16:53:20.645 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:53:20.653 13606 13672 D WM-SystemJobScheduler: Scheduling work ID ffad2de9-aecd-4382-bce9-a08ec99a6b36Job ID 31003
12-20 16:53:20.667 13606 13672 D WM-GreedyScheduler: Starting tracking for ffad2de9-aecd-4382-bce9-a08ec99a6b36
12-20 16:53:20.667 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: ffad2de9-aecd-4382-bce9-a08ec99a6b36}
12-20 16:53:20.667 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: bc894ec4-8ce2-4989-b88a-0facbadbbf06}
12-20 16:53:20.667 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=ffad2de9-aecd-4382-bce9-a08ec99a6b36, generation=0)
12-20 16:53:20.667 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=bc894ec4-8ce2-4989-b88a-0facbadbbf06, generation=0)
12-20 16:53:20.671 13606 13672 W WM-Processor: Didn't find WorkSpec for id WorkGenerationalId(workSpecId=bc894ec4-8ce2-4989-b88a-0facbadbbf06, generation=0)
12-20 16:53:20.675 13606 13672 D WM-Processor: WorkerWrapper could not be found for bc894ec4-8ce2-4989-b88a-0facbadbbf06
12-20 16:53:20.675 13606 13672 D WM-StopWorkRunnable: StopWorkRunnable for bc894ec4-8ce2-4989-b88a-0facbadbbf06; Processor.stopWork = false
12-20 16:53:20.679 13606 13672 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=ffad2de9-aecd-4382-bce9-a08ec99a6b36, generation=0)
12-20 16:53:20.681 13606 13672 W WM-Processor: Didn't find WorkSpec for id WorkGenerationalId(workSpecId=bc894ec4-8ce2-4989-b88a-0facbadbbf06, generation=0)
12-20 16:53:20.691 13606 13606 D WM-Processor: Processor bc894ec4-8ce2-4989-b88a-0facbadbbf06 executed; reschedule = false
12-20 16:53:20.691 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=bc894ec4-8ce2-4989-b88a-0facbadbbf06, generation=0)
12-20 16:53:20.692 13606 13606 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: ffad2de9-aecd-4382-bce9-a08ec99a6b36}
12-20 16:53:20.692 13606 13606 D WM-SystemJobService: bc894ec4-8ce2-4989-b88a-0facbadbbf06 executed on JobScheduler
12-20 16:53:20.692 13606 13606 D WM-Processor: Processor bc894ec4-8ce2-4989-b88a-0facbadbbf06 executed; reschedule = false
12-20 16:53:20.692 13606 13606 D WM-SystemJobService: bc894ec4-8ce2-4989-b88a-0facbadbbf06 executed on JobScheduler
12-20 16:53:20.692 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=ffad2de9-aecd-4382-bce9-a08ec99a6b36, generation=0)
12-20 16:53:20.693 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:53:20.694 13606 13676 D WM-Processor: Work WorkGenerationalId(workSpecId=ffad2de9-aecd-4382-bce9-a08ec99a6b36, generation=0) is already enqueued for processing
12-20 16:53:27.064 13606 13675 D WM-Processor: Processor cancelling 433402e2-870a-44f2-aff4-7926323e5510
12-20 16:53:27.065 13606 13675 D WM-Processor: WorkerWrapper could not be found for 433402e2-870a-44f2-aff4-7926323e5510
12-20 16:53:27.069 13606 13675 D WM-GreedyScheduler: Cancelling work ID 433402e2-870a-44f2-aff4-7926323e5510
12-20 16:53:27.072 13606 13675 D WM-Processor: Processor cancelling 5e3a27a6-9c29-4d7b-abc8-2a41c7439278
12-20 16:53:27.072 13606 13675 D WM-Processor: WorkerWrapper could not be found for 5e3a27a6-9c29-4d7b-abc8-2a41c7439278
12-20 16:53:27.073 13606 13675 D WM-GreedyScheduler: Cancelling work ID 5e3a27a6-9c29-4d7b-abc8-2a41c7439278
12-20 16:53:27.078 13606 13675 D WM-Processor: Processor cancelling ffad2de9-aecd-4382-bce9-a08ec99a6b36
12-20 16:53:27.079 13606 13675 D WM-WorkerWrapper: Work interrupted for Work [ id=ffad2de9-aecd-4382-bce9-a08ec99a6b36, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:27.092 13606 13675 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:53:27.093 13606 13675 D WM-WorkerWrapper: WorkSpec {WorkSpec: ffad2de9-aecd-4382-bce9-a08ec99a6b36} is already done. Not interrupting.
12-20 16:53:27.093 13606 13675 D WM-Processor: WorkerWrapper interrupted for ffad2de9-aecd-4382-bce9-a08ec99a6b36
12-20 16:53:27.093 13606 13606 D WM-Processor: Processor ffad2de9-aecd-4382-bce9-a08ec99a6b36 executed; reschedule = false
12-20 16:53:27.093 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=ffad2de9-aecd-4382-bce9-a08ec99a6b36, generation=0)
12-20 16:53:27.093 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:53:27.095 13606 13606 D WM-SystemJobService: ffad2de9-aecd-4382-bce9-a08ec99a6b36 executed on JobScheduler
12-20 16:53:27.099 13606 13675 D WM-GreedyScheduler: Cancelling work ID ffad2de9-aecd-4382-bce9-a08ec99a6b36
12-20 16:53:27.103 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=ffad2de9-aecd-4382-bce9-a08ec99a6b36, generation=0)
12-20 16:53:27.114 13606 13675 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:53:27.122 13606 13675 D WM-SystemJobScheduler: Scheduling work ID 92270b33-afaa-47ed-8219-a7419dc4a0f6Job ID 31004
12-20 16:53:27.140 13606 13675 D WM-GreedyScheduler: Starting tracking for 92270b33-afaa-47ed-8219-a7419dc4a0f6
12-20 16:53:27.143 13606 13675 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:53:27.143 13606 13675 D WM-NetworkStateTracker: Registering network callback
12-20 16:53:27.143 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=92270b33-afaa-47ed-8219-a7419dc4a0f6, generation=0)
12-20 16:53:27.146 13606 13675 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 92270b33-afaa-47ed-8219-a7419dc4a0f6}
12-20 16:53:27.146 13606 13675 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=92270b33-afaa-47ed-8219-a7419dc4a0f6, generation=0)
12-20 16:53:27.146 13606 13675 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:53:27.146 13606 13675 D WM-WorkerWrapper: Work interrupted for Work [ id=ffad2de9-aecd-4382-bce9-a08ec99a6b36, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:27.151 13606 13675 D WM-GreedyScheduler: Cancelling work ID ffad2de9-aecd-4382-bce9-a08ec99a6b36
12-20 16:53:27.151 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:53:27.158 13606 13675 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=92270b33-afaa-47ed-8219-a7419dc4a0f6, generation=0)
12-20 16:53:27.159 13606 13675 D WM-Processor: Work WorkGenerationalId(workSpecId=92270b33-afaa-47ed-8219-a7419dc4a0f6, generation=0) is already enqueued for processing
12-20 16:53:27.166 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:53:29.678 13606 13619 I im.vector.app: Background concurrent mark compact GC freed 104MB AllocSpace bytes, 114(2376KB) LOS objects, 54% free, 78MB/174MB, paused 3.872ms,2.353ms total 601.095ms
12-20 16:53:30.253 13606 13672 D WM-Processor: Processor cancelling 92270b33-afaa-47ed-8219-a7419dc4a0f6
12-20 16:53:30.253 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=92270b33-afaa-47ed-8219-a7419dc4a0f6, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:30.259 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:53:30.259 13606 13672 D WM-WorkerWrapper: WorkSpec {WorkSpec: 92270b33-afaa-47ed-8219-a7419dc4a0f6} is already done. Not interrupting.
12-20 16:53:30.259 13606 13672 D WM-Processor: WorkerWrapper interrupted for 92270b33-afaa-47ed-8219-a7419dc4a0f6
12-20 16:53:30.259 13606 13606 D WM-Processor: Processor 92270b33-afaa-47ed-8219-a7419dc4a0f6 executed; reschedule = false
12-20 16:53:30.259 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=92270b33-afaa-47ed-8219-a7419dc4a0f6, generation=0)
12-20 16:53:30.259 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:53:30.260 13606 13606 D WM-SystemJobService: 92270b33-afaa-47ed-8219-a7419dc4a0f6 executed on JobScheduler
12-20 16:53:30.263 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=92270b33-afaa-47ed-8219-a7419dc4a0f6, generation=0)
12-20 16:53:30.267 13606 13672 D WM-GreedyScheduler: Cancelling work ID 92270b33-afaa-47ed-8219-a7419dc4a0f6
12-20 16:53:30.271 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:53:30.275 13606 13672 D WM-SystemJobScheduler: Scheduling work ID 679d652a-d48d-4835-b8bc-e6be3c80be6dJob ID 31005
12-20 16:53:30.282 13606 13672 D WM-GreedyScheduler: Starting tracking for 679d652a-d48d-4835-b8bc-e6be3c80be6d
12-20 16:53:30.284 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=679d652a-d48d-4835-b8bc-e6be3c80be6d, generation=0)
12-20 16:53:30.285 13606 13672 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:53:30.285 13606 13672 D WM-NetworkStateTracker: Registering network callback
12-20 16:53:30.286 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 679d652a-d48d-4835-b8bc-e6be3c80be6d}
12-20 16:53:30.286 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=679d652a-d48d-4835-b8bc-e6be3c80be6d, generation=0)
12-20 16:53:30.287 13606 13672 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:53:30.287 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=92270b33-afaa-47ed-8219-a7419dc4a0f6, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:30.288 13606 13672 D WM-GreedyScheduler: Cancelling work ID 92270b33-afaa-47ed-8219-a7419dc4a0f6
12-20 16:53:30.291 13606 13672 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=679d652a-d48d-4835-b8bc-e6be3c80be6d, generation=0)
12-20 16:53:30.292 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:53:30.293 13606 13672 D WM-Processor: Work WorkGenerationalId(workSpecId=679d652a-d48d-4835-b8bc-e6be3c80be6d, generation=0) is already enqueued for processing
12-20 16:53:30.297 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:53:34.490 13606  9284 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:53:34.490 13606  9284 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:53:34.490 13606  9284 D UnifiedPush: Found saved distributor.
12-20 16:53:34.492 13606  9284 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:53:34.492 13606  9284 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:53:34.492 13606  9284 D UnifiedPush: Found saved distributor.
12-20 16:53:34.507 13606 13675 D WM-Processor: Processor cancelling 679d652a-d48d-4835-b8bc-e6be3c80be6d
12-20 16:53:34.510 13606 13675 D WM-WorkerWrapper: Work interrupted for Work [ id=679d652a-d48d-4835-b8bc-e6be3c80be6d, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:34.518 13606 13675 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:53:34.519 13606 13675 D WM-Processor: WorkerWrapper interrupted for 679d652a-d48d-4835-b8bc-e6be3c80be6d
12-20 16:53:34.528 13606 13675 D WM-GreedyScheduler: Cancelling work ID 679d652a-d48d-4835-b8bc-e6be3c80be6d
12-20 16:53:34.530 13606 13675 D WM-Processor: Processor cancelling f71ba788-84c8-4458-beec-53ae1da7f4ee
12-20 16:53:34.530 13606 13675 D WM-Processor: WorkerWrapper could not be found for f71ba788-84c8-4458-beec-53ae1da7f4ee
12-20 16:53:34.532 13606 13675 D WM-GreedyScheduler: Cancelling work ID f71ba788-84c8-4458-beec-53ae1da7f4ee
12-20 16:53:34.551 13606 13606 D WM-Processor: Processor 679d652a-d48d-4835-b8bc-e6be3c80be6d executed; reschedule = false
12-20 16:53:34.552 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=679d652a-d48d-4835-b8bc-e6be3c80be6d, generation=0)
12-20 16:53:34.552 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:53:34.555 13606 13606 D WM-SystemJobService: 679d652a-d48d-4835-b8bc-e6be3c80be6d executed on JobScheduler
12-20 16:53:34.555 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=679d652a-d48d-4835-b8bc-e6be3c80be6d, generation=0)
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper: Work [ id=679d652a-d48d-4835-b8bc-e6be3c80be6d, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ] was cancelled
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper: java.util.concurrent.CancellationException: Task was cancelled.
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.cancellationExceptionWithCause(SourceFile:3)
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.getDoneValue(SourceFile:31)
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.get(SourceFile:92)
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.WorkerWrapper$2.run(SourceFile:5)
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.utils.SerialExecutorImpl$Task.run(SourceFile:3)
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:644)
12-20 16:53:34.561 13606 13675 I WM-WorkerWrapper:      at java.lang.Thread.run(Thread.java:1117)
12-20 16:53:34.561 13606 13675 D WM-WorkerWrapper: Work interrupted for Work [ id=679d652a-d48d-4835-b8bc-e6be3c80be6d, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:34.564 13606 13675 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:53:34.565 13606 13675 D WM-GreedyScheduler: Cancelling work ID 679d652a-d48d-4835-b8bc-e6be3c80be6d
12-20 16:53:34.571 13606 13676 D WM-Processor: WorkerWrapper could not be found for 679d652a-d48d-4835-b8bc-e6be3c80be6d
12-20 16:53:34.571 13606 13676 D WM-StopWorkRunnable: StopWorkRunnable for 679d652a-d48d-4835-b8bc-e6be3c80be6d; Processor.stopWork = false
12-20 16:53:34.604 13606 13670 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:53:34.614 13606 13670 D WM-SystemJobScheduler: Scheduling work ID 6fd70901-9e93-48ef-bc7d-71a49ed51dd0Job ID 31006
12-20 16:53:34.628 13606 13670 D WM-GreedyScheduler: Starting tracking for 6fd70901-9e93-48ef-bc7d-71a49ed51dd0
12-20 16:53:34.631 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=6fd70901-9e93-48ef-bc7d-71a49ed51dd0, generation=0)
12-20 16:53:34.632 13606 13670 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:53:34.633 13606 13670 D WM-NetworkStateTracker: Registering network callback
12-20 16:53:34.635 13606 13670 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 6fd70901-9e93-48ef-bc7d-71a49ed51dd0}
12-20 16:53:34.637 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:53:34.638 13606 13670 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=6fd70901-9e93-48ef-bc7d-71a49ed51dd0, generation=0)
12-20 16:53:34.641 13606 13670 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=6fd70901-9e93-48ef-bc7d-71a49ed51dd0, generation=0)
12-20 16:53:34.643 13606 13670 D WM-Processor: Work WorkGenerationalId(workSpecId=6fd70901-9e93-48ef-bc7d-71a49ed51dd0, generation=0) is already enqueued for processing
12-20 16:53:34.647 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:53:39.131 13606 13672 D WM-Processor: Processor cancelling 6fd70901-9e93-48ef-bc7d-71a49ed51dd0
12-20 16:53:39.131 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=6fd70901-9e93-48ef-bc7d-71a49ed51dd0, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:39.144 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:53:39.145 13606 13672 D WM-WorkerWrapper: WorkSpec {WorkSpec: 6fd70901-9e93-48ef-bc7d-71a49ed51dd0} is already done. Not interrupting.
12-20 16:53:39.145 13606 13672 D WM-Processor: WorkerWrapper interrupted for 6fd70901-9e93-48ef-bc7d-71a49ed51dd0
12-20 16:53:39.145 13606 13606 D WM-Processor: Processor 6fd70901-9e93-48ef-bc7d-71a49ed51dd0 executed; reschedule = false
12-20 16:53:39.145 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=6fd70901-9e93-48ef-bc7d-71a49ed51dd0, generation=0)
12-20 16:53:39.145 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:53:39.147 13606 13606 D WM-SystemJobService: 6fd70901-9e93-48ef-bc7d-71a49ed51dd0 executed on JobScheduler
12-20 16:53:39.157 13606 13672 D WM-GreedyScheduler: Cancelling work ID 6fd70901-9e93-48ef-bc7d-71a49ed51dd0
12-20 16:53:39.164 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=6fd70901-9e93-48ef-bc7d-71a49ed51dd0, generation=0)
12-20 16:53:39.166 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:53:39.171 13606 13672 D WM-SystemJobScheduler: Scheduling work ID 3cedf7ea-0c20-4492-b19e-c3580591bd4bJob ID 31007
12-20 16:53:39.186 13606 13672 D WM-GreedyScheduler: Starting tracking for 3cedf7ea-0c20-4492-b19e-c3580591bd4b
12-20 16:53:39.190 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=3cedf7ea-0c20-4492-b19e-c3580591bd4b, generation=0)
12-20 16:53:39.196 13606 13672 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:53:39.196 13606 13672 D WM-NetworkStateTracker: Registering network callback
12-20 16:53:39.199 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 3cedf7ea-0c20-4492-b19e-c3580591bd4b}
12-20 16:53:39.199 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=3cedf7ea-0c20-4492-b19e-c3580591bd4b, generation=0)
12-20 16:53:39.199 13606 13672 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:53:39.199 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=6fd70901-9e93-48ef-bc7d-71a49ed51dd0, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:39.202 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:53:39.203 13606 13672 D WM-GreedyScheduler: Cancelling work ID 6fd70901-9e93-48ef-bc7d-71a49ed51dd0
12-20 16:53:39.210 13606 13672 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=3cedf7ea-0c20-4492-b19e-c3580591bd4b, generation=0)
12-20 16:53:39.212 13606 13672 D WM-Processor: Work WorkGenerationalId(workSpecId=3cedf7ea-0c20-4492-b19e-c3580591bd4b, generation=0) is already enqueued for processing
12-20 16:53:39.219 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:53:44.447 13606 13672 D WM-Processor: Processor cancelling 3cedf7ea-0c20-4492-b19e-c3580591bd4b
12-20 16:53:44.447 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=3cedf7ea-0c20-4492-b19e-c3580591bd4b, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:44.473 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:53:44.474 13606 13672 D WM-WorkerWrapper: WorkSpec {WorkSpec: 3cedf7ea-0c20-4492-b19e-c3580591bd4b} is already done. Not interrupting.
12-20 16:53:44.474 13606 13672 D WM-Processor: WorkerWrapper interrupted for 3cedf7ea-0c20-4492-b19e-c3580591bd4b
12-20 16:53:44.474 13606 13606 D WM-Processor: Processor 3cedf7ea-0c20-4492-b19e-c3580591bd4b executed; reschedule = false
12-20 16:53:44.474 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=3cedf7ea-0c20-4492-b19e-c3580591bd4b, generation=0)
12-20 16:53:44.474 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:53:44.475 13606 13606 D WM-SystemJobService: 3cedf7ea-0c20-4492-b19e-c3580591bd4b executed on JobScheduler
12-20 16:53:44.482 13606 13672 D WM-GreedyScheduler: Cancelling work ID 3cedf7ea-0c20-4492-b19e-c3580591bd4b
12-20 16:53:44.492 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:53:44.505 13606 13672 D WM-SystemJobScheduler: Scheduling work ID 63a27317-2767-40d5-a464-38a207bd0038Job ID 31008
12-20 16:53:44.528 13606 13672 D WM-GreedyScheduler: Starting tracking for 63a27317-2767-40d5-a464-38a207bd0038
12-20 16:53:44.534 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=63a27317-2767-40d5-a464-38a207bd0038, generation=0)
12-20 16:53:44.536 13606 13672 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:53:44.536 13606 13672 D WM-NetworkStateTracker: Registering network callback
12-20 16:53:44.542 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 63a27317-2767-40d5-a464-38a207bd0038}
12-20 16:53:44.545 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:53:44.546 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=63a27317-2767-40d5-a464-38a207bd0038, generation=0)
12-20 16:53:44.547 13606 13676 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:53:44.547 13606 13676 D WM-WorkerWrapper: Work interrupted for Work [ id=3cedf7ea-0c20-4492-b19e-c3580591bd4b, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:44.580 13606 13676 D WM-GreedyScheduler: Cancelling work ID 3cedf7ea-0c20-4492-b19e-c3580591bd4b
12-20 16:53:44.589 13606 13670 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=63a27317-2767-40d5-a464-38a207bd0038, generation=0)
12-20 16:53:44.591 13606 13670 D WM-Processor: Work WorkGenerationalId(workSpecId=63a27317-2767-40d5-a464-38a207bd0038, generation=0) is already enqueued for processing
12-20 16:53:44.636 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:53:45.519 13606 13619 I im.vector.app: Background concurrent mark compact GC freed 100MB AllocSpace bytes, 120(2400KB) LOS objects, 52% free, 85MB/181MB, paused 20.251ms,12.171ms total 1.997s
12-20 16:53:49.610 13606 13676 D WM-Processor: Processor cancelling 63a27317-2767-40d5-a464-38a207bd0038
12-20 16:53:49.612 13606 13676 D WM-WorkerWrapper: Work interrupted for Work [ id=63a27317-2767-40d5-a464-38a207bd0038, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:49.626 13606 13676 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:53:49.628 13606 13606 D WM-Processor: Processor 63a27317-2767-40d5-a464-38a207bd0038 executed; reschedule = false
12-20 16:53:49.628 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=63a27317-2767-40d5-a464-38a207bd0038, generation=0)
12-20 16:53:49.628 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:53:49.630 13606 13676 D WM-WorkerWrapper: WorkSpec {WorkSpec: 63a27317-2767-40d5-a464-38a207bd0038} is already done. Not interrupting.
12-20 16:53:49.630 13606 13676 D WM-Processor: WorkerWrapper interrupted for 63a27317-2767-40d5-a464-38a207bd0038
12-20 16:53:49.637 13606 13676 D WM-GreedyScheduler: Cancelling work ID 63a27317-2767-40d5-a464-38a207bd0038
12-20 16:53:49.640 13606 13606 D WM-SystemJobService: 63a27317-2767-40d5-a464-38a207bd0038 executed on JobScheduler
12-20 16:53:49.641 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=63a27317-2767-40d5-a464-38a207bd0038, generation=0)
12-20 16:53:49.651 13606 13676 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:53:49.660 13606 13676 D WM-SystemJobScheduler: Scheduling work ID 3381efb6-5430-48ae-bce1-e3d861a0d4a8Job ID 31009
12-20 16:53:49.675 13606 13676 D WM-GreedyScheduler: Starting tracking for 3381efb6-5430-48ae-bce1-e3d861a0d4a8
12-20 16:53:49.677 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=3381efb6-5430-48ae-bce1-e3d861a0d4a8, generation=0)
12-20 16:53:49.679 13606 13676 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:53:49.679 13606 13676 D WM-NetworkStateTracker: Registering network callback
12-20 16:53:49.683 13606 13676 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 3381efb6-5430-48ae-bce1-e3d861a0d4a8}
12-20 16:53:49.683 13606 13676 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=3381efb6-5430-48ae-bce1-e3d861a0d4a8, generation=0)
12-20 16:53:49.683 13606 13676 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:53:49.683 13606 13676 D WM-WorkerWrapper: Work interrupted for Work [ id=63a27317-2767-40d5-a464-38a207bd0038, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:53:49.685 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -46, Link speed: 1297Mbps, Tx Link speed: 1297Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2041Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -46 UnderlyingNetworks: Null]
12-20 16:53:49.685 13606 13676 D WM-GreedyScheduler: Cancelling work ID 63a27317-2767-40d5-a464-38a207bd0038
12-20 16:53:49.691 13606 13676 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=3381efb6-5430-48ae-bce1-e3d861a0d4a8, generation=0)
12-20 16:53:49.692 13606 13676 D WM-Processor: Work WorkGenerationalId(workSpecId=3381efb6-5430-48ae-bce1-e3d861a0d4a8, generation=0) is already enqueued for processing
12-20 16:53:49.698 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker

@warioishere
Copy link
Author

third try:

12-20 16:55:05.968 13606 13672 D WM-Processor: Processor cancelling 0c5339f9-3ac2-4627-84e1-e08cd4ad78db
12-20 16:55:05.969 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=0c5339f9-3ac2-4627-84e1-e08cd4ad78db, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:05.984 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:55:05.984 13606 13672 D WM-WorkerWrapper: WorkSpec {WorkSpec: 0c5339f9-3ac2-4627-84e1-e08cd4ad78db} is already done. Not interrupting.
12-20 16:55:05.985 13606 13672 D WM-Processor: WorkerWrapper interrupted for 0c5339f9-3ac2-4627-84e1-e08cd4ad78db
12-20 16:55:05.985 13606 13606 D WM-Processor: Processor 0c5339f9-3ac2-4627-84e1-e08cd4ad78db executed; reschedule = false
12-20 16:55:05.985 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=0c5339f9-3ac2-4627-84e1-e08cd4ad78db, generation=0)
12-20 16:55:05.985 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:55:05.988 13606 13606 D WM-SystemJobService: 0c5339f9-3ac2-4627-84e1-e08cd4ad78db executed on JobScheduler
12-20 16:55:05.992 13606 13672 D WM-GreedyScheduler: Cancelling work ID 0c5339f9-3ac2-4627-84e1-e08cd4ad78db
12-20 16:55:05.999 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=0c5339f9-3ac2-4627-84e1-e08cd4ad78db, generation=0)
12-20 16:55:06.012 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:55:06.028 13606 13672 D WM-SystemJobScheduler: Scheduling work ID 4079ba74-e65c-46be-9b18-b19c1812f9a6Job ID 31022
12-20 16:55:06.048 13606 13672 D WM-GreedyScheduler: Starting tracking for 4079ba74-e65c-46be-9b18-b19c1812f9a6
12-20 16:55:06.052 13606 13672 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:55:06.052 13606 13672 D WM-NetworkStateTracker: Registering network callback
12-20 16:55:06.053 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=4079ba74-e65c-46be-9b18-b19c1812f9a6, generation=0)
12-20 16:55:06.055 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 4079ba74-e65c-46be-9b18-b19c1812f9a6}
12-20 16:55:06.055 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=4079ba74-e65c-46be-9b18-b19c1812f9a6, generation=0)
12-20 16:55:06.055 13606 13672 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:55:06.055 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=0c5339f9-3ac2-4627-84e1-e08cd4ad78db, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:06.057 13606 13672 D WM-GreedyScheduler: Cancelling work ID 0c5339f9-3ac2-4627-84e1-e08cd4ad78db
12-20 16:55:06.058 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -47, Link speed: 2401Mbps, Tx Link speed: 2401Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2268Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -47 UnderlyingNetworks: Null]
12-20 16:55:06.064 13606 13672 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=4079ba74-e65c-46be-9b18-b19c1812f9a6, generation=0)
12-20 16:55:06.065 13606 13672 D WM-Processor: Work WorkGenerationalId(workSpecId=4079ba74-e65c-46be-9b18-b19c1812f9a6, generation=0) is already enqueued for processing
12-20 16:55:06.068 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:55:10.151 13606 13619 I im.vector.app: Background concurrent mark compact GC freed 103MB AllocSpace bytes, 143(2860KB) LOS objects, 54% free, 78MB/174MB, paused 10.315ms,11.689ms total 1.860s
12-20 16:55:12.440 13606  6728 D TrafficStats: tagSocket(126) with statsTag=0xffffffff, statsUid=-1
12-20 16:55:12.885 13606  6663 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:55:12.886 13606  6663 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:55:12.886 13606  6663 D UnifiedPush: Found saved distributor.
12-20 16:55:12.887 13606  6663 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:55:12.887 13606  6663 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:55:12.887 13606  6663 D UnifiedPush: Found saved distributor.
12-20 16:55:12.929 13606 13675 D WM-Processor: Processor cancelling 4079ba74-e65c-46be-9b18-b19c1812f9a6
12-20 16:55:12.929 13606 13675 D WM-WorkerWrapper: Work interrupted for Work [ id=4079ba74-e65c-46be-9b18-b19c1812f9a6, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:12.940 13606 13675 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:55:12.941 13606 13675 D WM-Processor: WorkerWrapper interrupted for 4079ba74-e65c-46be-9b18-b19c1812f9a6
12-20 16:55:12.945 13606 13675 D WM-GreedyScheduler: Cancelling work ID 4079ba74-e65c-46be-9b18-b19c1812f9a6
12-20 16:55:12.947 13606 13675 D WM-Processor: Processor cancelling 90a8b8b7-45b6-4d1e-b5df-11d04f0ff06e
12-20 16:55:12.947 13606 13675 D WM-Processor: WorkerWrapper could not be found for 90a8b8b7-45b6-4d1e-b5df-11d04f0ff06e
12-20 16:55:12.948 13606 13675 D WM-GreedyScheduler: Cancelling work ID 90a8b8b7-45b6-4d1e-b5df-11d04f0ff06e
12-20 16:55:12.956 13606 13675 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:55:12.961 13606 13675 D WM-SystemJobScheduler: Scheduling work ID 9aee0800-8916-4214-b532-bc08853e9d74Job ID 31023
12-20 16:55:12.968 13606 13675 D WM-GreedyScheduler: Starting tracking for 9aee0800-8916-4214-b532-bc08853e9d74
12-20 16:55:12.968 13606 13675 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 9aee0800-8916-4214-b532-bc08853e9d74}
12-20 16:55:12.968 13606 13675 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 4079ba74-e65c-46be-9b18-b19c1812f9a6}
12-20 16:55:12.968 13606 13675 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=9aee0800-8916-4214-b532-bc08853e9d74, generation=0)
12-20 16:55:12.968 13606 13675 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=4079ba74-e65c-46be-9b18-b19c1812f9a6, generation=0)
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper: Work [ id=4079ba74-e65c-46be-9b18-b19c1812f9a6, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ] was cancelled
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper: java.util.concurrent.CancellationException: Task was cancelled.
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.cancellationExceptionWithCause(SourceFile:3)
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.getDoneValue(SourceFile:31)
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.get(SourceFile:92)
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.WorkerWrapper$2.run(SourceFile:5)
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper:      at androidx.work.impl.utils.SerialExecutorImpl$Task.run(SourceFile:3)
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:644)
12-20 16:55:12.969 13606 13675 I WM-WorkerWrapper:      at java.lang.Thread.run(Thread.java:1117)
12-20 16:55:12.969 13606 13675 D WM-WorkerWrapper: Work interrupted for Work [ id=4079ba74-e65c-46be-9b18-b19c1812f9a6, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:12.972 13606 13675 D WM-GreedyScheduler: Cancelling work ID 4079ba74-e65c-46be-9b18-b19c1812f9a6
12-20 16:55:12.975 13606 13675 D WM-Processor: WorkerWrapper could not be found for 4079ba74-e65c-46be-9b18-b19c1812f9a6
12-20 16:55:12.975 13606 13675 D WM-StopWorkRunnable: StopWorkRunnable for 4079ba74-e65c-46be-9b18-b19c1812f9a6; Processor.stopWork = false
12-20 16:55:12.993 13606 13675 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=9aee0800-8916-4214-b532-bc08853e9d74, generation=0)
12-20 16:55:12.994 13606 13606 D WM-Processor: Processor 4079ba74-e65c-46be-9b18-b19c1812f9a6 executed; reschedule = false
12-20 16:55:12.994 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=4079ba74-e65c-46be-9b18-b19c1812f9a6, generation=0)
12-20 16:55:12.994 13606 13606 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 9aee0800-8916-4214-b532-bc08853e9d74}
12-20 16:55:12.994 13606 13606 D WM-SystemJobService: 4079ba74-e65c-46be-9b18-b19c1812f9a6 executed on JobScheduler
12-20 16:55:12.994 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=4079ba74-e65c-46be-9b18-b19c1812f9a6, generation=0)
12-20 16:55:12.994 13606 13675 W WM-Processor: Didn't find WorkSpec for id WorkGenerationalId(workSpecId=4079ba74-e65c-46be-9b18-b19c1812f9a6, generation=0)
12-20 16:55:12.995 13606 13675 D WM-Processor: WorkerWrapper could not be found for 4079ba74-e65c-46be-9b18-b19c1812f9a6
12-20 16:55:12.995 13606 13675 D WM-StopWorkRunnable: StopWorkRunnable for 4079ba74-e65c-46be-9b18-b19c1812f9a6; Processor.stopWork = false
12-20 16:55:12.996 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=9aee0800-8916-4214-b532-bc08853e9d74, generation=0)
12-20 16:55:12.999 13606 13606 D WM-Processor: Processor 4079ba74-e65c-46be-9b18-b19c1812f9a6 executed; reschedule = false
12-20 16:55:12.999 13606 13606 D WM-SystemJobService: 4079ba74-e65c-46be-9b18-b19c1812f9a6 executed on JobScheduler
12-20 16:55:13.001 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:55:13.003 13606 13675 D WM-Processor: Work WorkGenerationalId(workSpecId=9aee0800-8916-4214-b532-bc08853e9d74, generation=0) is already enqueued for processing
12-20 16:55:20.146 13606 13672 D WM-Processor: Processor cancelling 9aee0800-8916-4214-b532-bc08853e9d74
12-20 16:55:20.147 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=9aee0800-8916-4214-b532-bc08853e9d74, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:20.157 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:55:20.157 13606 13672 D WM-WorkerWrapper: WorkSpec {WorkSpec: 9aee0800-8916-4214-b532-bc08853e9d74} is already done. Not interrupting.
12-20 16:55:20.157 13606 13672 D WM-Processor: WorkerWrapper interrupted for 9aee0800-8916-4214-b532-bc08853e9d74
12-20 16:55:20.157 13606 13606 D WM-Processor: Processor 9aee0800-8916-4214-b532-bc08853e9d74 executed; reschedule = false
12-20 16:55:20.158 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=9aee0800-8916-4214-b532-bc08853e9d74, generation=0)
12-20 16:55:20.158 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:55:20.160 13606 13606 D WM-SystemJobService: 9aee0800-8916-4214-b532-bc08853e9d74 executed on JobScheduler
12-20 16:55:20.163 13606 13672 D WM-GreedyScheduler: Cancelling work ID 9aee0800-8916-4214-b532-bc08853e9d74
12-20 16:55:20.169 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=9aee0800-8916-4214-b532-bc08853e9d74, generation=0)
12-20 16:55:20.170 13606 13672 D WM-Processor: Processor cancelling c6974e23-49cd-4c99-809d-9bca9a0e2d60
12-20 16:55:20.170 13606 13672 D WM-Processor: WorkerWrapper could not be found for c6974e23-49cd-4c99-809d-9bca9a0e2d60
12-20 16:55:20.171 13606 13672 D WM-GreedyScheduler: Cancelling work ID c6974e23-49cd-4c99-809d-9bca9a0e2d60
12-20 16:55:20.173 13606 13672 D WM-Processor: Processor cancelling cc1584a8-b1a9-475f-9d26-186338663ba0
12-20 16:55:20.173 13606 13672 D WM-Processor: WorkerWrapper could not be found for cc1584a8-b1a9-475f-9d26-186338663ba0
12-20 16:55:20.174 13606 13672 D WM-GreedyScheduler: Cancelling work ID cc1584a8-b1a9-475f-9d26-186338663ba0
12-20 16:55:20.186 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:55:20.194 13606 13672 D WM-SystemJobScheduler: Scheduling work ID 3adf8240-45a6-40c6-93fa-0bb3f4b18763Job ID 31024
12-20 16:55:20.209 13606 13672 D WM-GreedyScheduler: Starting tracking for 3adf8240-45a6-40c6-93fa-0bb3f4b18763
12-20 16:55:20.210 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=3adf8240-45a6-40c6-93fa-0bb3f4b18763, generation=0)
12-20 16:55:20.214 13606 13672 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:55:20.214 13606 13672 D WM-NetworkStateTracker: Registering network callback
12-20 16:55:20.216 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 3adf8240-45a6-40c6-93fa-0bb3f4b18763}
12-20 16:55:20.216 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=3adf8240-45a6-40c6-93fa-0bb3f4b18763, generation=0)
12-20 16:55:20.216 13606 13672 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:55:20.216 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=9aee0800-8916-4214-b532-bc08853e9d74, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:20.220 13606 13672 D WM-GreedyScheduler: Cancelling work ID 9aee0800-8916-4214-b532-bc08853e9d74
12-20 16:55:20.223 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -47, Link speed: 2401Mbps, Tx Link speed: 2401Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2268Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -47 UnderlyingNetworks: Null]
12-20 16:55:20.226 13606 13672 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=3adf8240-45a6-40c6-93fa-0bb3f4b18763, generation=0)
12-20 16:55:20.228 13606 13676 D WM-Processor: Work WorkGenerationalId(workSpecId=3adf8240-45a6-40c6-93fa-0bb3f4b18763, generation=0) is already enqueued for processing
12-20 16:55:20.236 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:55:21.951 13606 13619 I im.vector.app: Background concurrent mark compact GC freed 100MB AllocSpace bytes, 102(2040KB) LOS objects, 56% free, 74MB/170MB, paused 1.103ms,1.338ms total 254.792ms
12-20 16:55:24.816 13606 13670 D WM-Processor: Processor cancelling 3adf8240-45a6-40c6-93fa-0bb3f4b18763
12-20 16:55:24.816 13606 13670 D WM-WorkerWrapper: Work interrupted for Work [ id=3adf8240-45a6-40c6-93fa-0bb3f4b18763, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:24.826 13606 13670 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:55:24.827 13606 13670 D WM-WorkerWrapper: WorkSpec {WorkSpec: 3adf8240-45a6-40c6-93fa-0bb3f4b18763} is already done. Not interrupting.
12-20 16:55:24.827 13606 13670 D WM-Processor: WorkerWrapper interrupted for 3adf8240-45a6-40c6-93fa-0bb3f4b18763
12-20 16:55:24.827 13606 13606 D WM-Processor: Processor 3adf8240-45a6-40c6-93fa-0bb3f4b18763 executed; reschedule = false
12-20 16:55:24.827 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=3adf8240-45a6-40c6-93fa-0bb3f4b18763, generation=0)
12-20 16:55:24.827 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:55:24.829 13606 13606 D WM-SystemJobService: 3adf8240-45a6-40c6-93fa-0bb3f4b18763 executed on JobScheduler
12-20 16:55:24.831 13606 13670 D WM-GreedyScheduler: Cancelling work ID 3adf8240-45a6-40c6-93fa-0bb3f4b18763
12-20 16:55:24.837 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=3adf8240-45a6-40c6-93fa-0bb3f4b18763, generation=0)
12-20 16:55:24.846 13606 13670 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:55:24.855 13606 13670 D WM-SystemJobScheduler: Scheduling work ID fdc0c8d0-4117-474e-bbd3-4d71db982b79Job ID 31025
12-20 16:55:24.868 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=fdc0c8d0-4117-474e-bbd3-4d71db982b79, generation=0)
12-20 16:55:24.870 13606 13670 D WM-GreedyScheduler: Starting tracking for fdc0c8d0-4117-474e-bbd3-4d71db982b79
12-20 16:55:24.875 13606 13670 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:55:24.875 13606 13670 D WM-NetworkStateTracker: Registering network callback
12-20 16:55:24.878 13606 13670 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: fdc0c8d0-4117-474e-bbd3-4d71db982b79}
12-20 16:55:24.878 13606 13670 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=fdc0c8d0-4117-474e-bbd3-4d71db982b79, generation=0)
12-20 16:55:24.878 13606 13670 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:55:24.879 13606 13670 D WM-WorkerWrapper: Work interrupted for Work [ id=3adf8240-45a6-40c6-93fa-0bb3f4b18763, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:24.884 13606 13670 D WM-GreedyScheduler: Cancelling work ID 3adf8240-45a6-40c6-93fa-0bb3f4b18763
12-20 16:55:24.884 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -47, Link speed: 2401Mbps, Tx Link speed: 2401Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2268Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -47 UnderlyingNetworks: Null]
12-20 16:55:24.891 13606 13670 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=fdc0c8d0-4117-474e-bbd3-4d71db982b79, generation=0)
12-20 16:55:24.893 13606 13670 D WM-Processor: Work WorkGenerationalId(workSpecId=fdc0c8d0-4117-474e-bbd3-4d71db982b79, generation=0) is already enqueued for processing
12-20 16:55:24.899 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:55:28.307 13606 13676 D WM-Processor: Processor cancelling fdc0c8d0-4117-474e-bbd3-4d71db982b79
12-20 16:55:28.307 13606 13676 D WM-WorkerWrapper: Work interrupted for Work [ id=fdc0c8d0-4117-474e-bbd3-4d71db982b79, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:28.321 13606 13676 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:55:28.322 13606 13676 D WM-WorkerWrapper: WorkSpec {WorkSpec: fdc0c8d0-4117-474e-bbd3-4d71db982b79} is already done. Not interrupting.
12-20 16:55:28.322 13606 13606 D WM-Processor: Processor fdc0c8d0-4117-474e-bbd3-4d71db982b79 executed; reschedule = false
12-20 16:55:28.322 13606 13676 D WM-Processor: WorkerWrapper interrupted for fdc0c8d0-4117-474e-bbd3-4d71db982b79
12-20 16:55:28.323 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=fdc0c8d0-4117-474e-bbd3-4d71db982b79, generation=0)
12-20 16:55:28.323 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:55:28.325 13606 13606 D WM-SystemJobService: fdc0c8d0-4117-474e-bbd3-4d71db982b79 executed on JobScheduler
12-20 16:55:28.329 13606 13676 D WM-GreedyScheduler: Cancelling work ID fdc0c8d0-4117-474e-bbd3-4d71db982b79
12-20 16:55:28.331 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=fdc0c8d0-4117-474e-bbd3-4d71db982b79, generation=0)
12-20 16:55:28.336 13606 13676 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:55:28.343 13606 13676 D WM-SystemJobScheduler: Scheduling work ID 79af2e75-463e-48bc-9cb7-c2e9e873ff21Job ID 31026
12-20 16:55:28.353 13606 13676 D WM-GreedyScheduler: Starting tracking for 79af2e75-463e-48bc-9cb7-c2e9e873ff21
12-20 16:55:28.353 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=79af2e75-463e-48bc-9cb7-c2e9e873ff21, generation=0)
12-20 16:55:28.356 13606 13676 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:55:28.356 13606 13676 D WM-NetworkStateTracker: Registering network callback
12-20 16:55:28.357 13606 13676 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 79af2e75-463e-48bc-9cb7-c2e9e873ff21}
12-20 16:55:28.358 13606 13676 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=79af2e75-463e-48bc-9cb7-c2e9e873ff21, generation=0)
12-20 16:55:28.358 13606 13676 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:55:28.358 13606 13676 D WM-WorkerWrapper: Work interrupted for Work [ id=fdc0c8d0-4117-474e-bbd3-4d71db982b79, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:28.360 13606 13676 D WM-GreedyScheduler: Cancelling work ID fdc0c8d0-4117-474e-bbd3-4d71db982b79
12-20 16:55:28.362 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -47, Link speed: 2401Mbps, Tx Link speed: 2401Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2268Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -47 UnderlyingNetworks: Null]
12-20 16:55:28.364 13606 13676 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=79af2e75-463e-48bc-9cb7-c2e9e873ff21, generation=0)
12-20 16:55:28.365 13606 13676 D WM-Processor: Work WorkGenerationalId(workSpecId=79af2e75-463e-48bc-9cb7-c2e9e873ff21, generation=0) is already enqueued for processing
12-20 16:55:28.370 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:55:32.614 13606 13672 D WM-Processor: Processor cancelling 5d104789-7a23-4e7c-9ff5-09ab334a3017
12-20 16:55:32.615 13606 13672 D WM-Processor: WorkerWrapper could not be found for 5d104789-7a23-4e7c-9ff5-09ab334a3017
12-20 16:55:32.617 13606 13672 D WM-GreedyScheduler: Cancelling work ID 5d104789-7a23-4e7c-9ff5-09ab334a3017
12-20 16:55:32.621 13606 13672 D WM-Processor: Processor cancelling 79af2e75-463e-48bc-9cb7-c2e9e873ff21
12-20 16:55:32.621 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=79af2e75-463e-48bc-9cb7-c2e9e873ff21, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:32.633 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:55:32.634 13606 13672 D WM-WorkerWrapper: WorkSpec {WorkSpec: 79af2e75-463e-48bc-9cb7-c2e9e873ff21} is already done. Not interrupting.
12-20 16:55:32.634 13606 13672 D WM-Processor: WorkerWrapper interrupted for 79af2e75-463e-48bc-9cb7-c2e9e873ff21
12-20 16:55:32.634 13606 13606 D WM-Processor: Processor 79af2e75-463e-48bc-9cb7-c2e9e873ff21 executed; reschedule = false
12-20 16:55:32.635 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=79af2e75-463e-48bc-9cb7-c2e9e873ff21, generation=0)
12-20 16:55:32.635 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:55:32.636 13606 13606 D WM-SystemJobService: 79af2e75-463e-48bc-9cb7-c2e9e873ff21 executed on JobScheduler
12-20 16:55:32.639 13606 13672 D WM-GreedyScheduler: Cancelling work ID 79af2e75-463e-48bc-9cb7-c2e9e873ff21
12-20 16:55:32.644 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=79af2e75-463e-48bc-9cb7-c2e9e873ff21, generation=0)
12-20 16:55:32.654 13606 13672 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-20 16:55:32.663 13606 13672 D WM-SystemJobScheduler: Scheduling work ID 87e131d9-f1aa-4e71-83e7-17e579cf9dc9Job ID 31027
12-20 16:55:32.678 13606 13672 D WM-GreedyScheduler: Starting tracking for 87e131d9-f1aa-4e71-83e7-17e579cf9dc9
12-20 16:55:32.679 13606 13606 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=87e131d9-f1aa-4e71-83e7-17e579cf9dc9, generation=0)
12-20 16:55:32.682 13606 13672 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=false, isNotRoaming=true)
12-20 16:55:32.682 13606 13672 D WM-NetworkStateTracker: Registering network callback
12-20 16:55:32.684 13606 13672 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 87e131d9-f1aa-4e71-83e7-17e579cf9dc9}
12-20 16:55:32.684 13606 13672 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=87e131d9-f1aa-4e71-83e7-17e579cf9dc9, generation=0)
12-20 16:55:32.684 13606 13672 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-20 16:55:32.684 13606 13672 D WM-WorkerWrapper: Work interrupted for Work [ id=79af2e75-463e-48bc-9cb7-c2e9e873ff21, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:32.688 13606 13672 D WM-GreedyScheduler: Cancelling work ID 79af2e75-463e-48bc-9cb7-c2e9e873ff21
12-20 16:55:32.689 13606 13694 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=48637Kbps LinkDnBandwidth>=126182Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.1.90, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 11ax, RSSI: -47, Link speed: 2401Mbps, Tx Link speed: 2401Mbps, Max Supported Tx Link speed: 2401Mbps, Rx Link speed: 2268Mbps, Max Supported Rx Link speed: 2401Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>, Vendor Data: <none>> SignalStrength: -47 UnderlyingNetworks: Null]
12-20 16:55:32.696 13606 13672 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=87e131d9-f1aa-4e71-83e7-17e579cf9dc9, generation=0)
12-20 16:55:32.698 13606 13672 D WM-Processor: Work WorkGenerationalId(workSpecId=87e131d9-f1aa-4e71-83e7-17e579cf9dc9, generation=0) is already enqueued for processing
12-20 16:55:32.704 13606 13606 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-20 16:55:32.980 13606 13619 I im.vector.app: Background concurrent mark compact GC freed 97MB AllocSpace bytes, 129(2580KB) LOS objects, 54% free, 80MB/176MB, paused 1.170ms,1.322ms total 310.448ms
12-20 16:55:33.036 13606  9284 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:55:33.036 13606  9284 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:55:33.036 13606  9284 D UnifiedPush: Found saved distributor.
12-20 16:55:33.037 13606  9284 D UnifiedPush: Found distributor with package name im.vector.app
12-20 16:55:33.037 13606  9284 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-20 16:55:33.037 13606  9284 D UnifiedPush: Found saved distributor.
12-20 16:55:33.042 13606 13670 D WM-Processor: Processor cancelling 87e131d9-f1aa-4e71-83e7-17e579cf9dc9
12-20 16:55:33.042 13606 13670 D WM-WorkerWrapper: Work interrupted for Work [ id=87e131d9-f1aa-4e71-83e7-17e579cf9dc9, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:33.051 13606 13670 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:55:33.051 13606 13670 D WM-Processor: WorkerWrapper interrupted for 87e131d9-f1aa-4e71-83e7-17e579cf9dc9
12-20 16:55:33.054 13606 13670 D WM-GreedyScheduler: Cancelling work ID 87e131d9-f1aa-4e71-83e7-17e579cf9dc9
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper: Work [ id=87e131d9-f1aa-4e71-83e7-17e579cf9dc9, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ] was cancelled
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper: java.util.concurrent.CancellationException: Task was cancelled.
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.cancellationExceptionWithCause(SourceFile:3)
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.getDoneValue(SourceFile:31)
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.get(SourceFile:92)
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper:      at androidx.work.impl.WorkerWrapper$2.run(SourceFile:5)
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper:      at androidx.work.impl.utils.SerialExecutorImpl$Task.run(SourceFile:3)
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:644)
12-20 16:55:33.056 13606 13670 I WM-WorkerWrapper:      at java.lang.Thread.run(Thread.java:1117)
12-20 16:55:33.056 13606 13670 D WM-WorkerWrapper: Work interrupted for Work [ id=87e131d9-f1aa-4e71-83e7-17e579cf9dc9, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-20 16:55:33.061 13606 13670 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-20 16:55:33.063 13606 13670 D WM-GreedyScheduler: Cancelling work ID 87e131d9-f1aa-4e71-83e7-17e579cf9dc9
12-20 16:55:33.065 13606 13672 D WM-Processor: WorkerWrapper could not be found for 87e131d9-f1aa-4e71-83e7-17e579cf9dc9
12-20 16:55:33.065 13606 13672 D WM-StopWorkRunnable: StopWorkRunnable for 87e131d9-f1aa-4e71-83e7-17e579cf9dc9; Processor.stopWork = false
12-20 16:55:33.083 13606 13606 D WM-Processor: Processor 87e131d9-f1aa-4e71-83e7-17e579cf9dc9 executed; reschedule = false
12-20 16:55:33.083 13606 13606 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=87e131d9-f1aa-4e71-83e7-17e579cf9dc9, generation=0)
12-20 16:55:33.083 13606 13606 D WM-NetworkStateTracker: Unregistering network callback
12-20 16:55:33.085 13606 13606 D WM-SystemJobService: 87e131d9-f1aa-4e71-83e7-17e579cf9dc9 executed on JobScheduler
12-20 16:55:33.085 13606 13606 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=87e131d9-f1aa-4e71-83e7-17e579cf9dc9, generation=0)

@warioishere warioishere changed the title no call notification when using own ntfy server no call ringing when using own ntfy server Dec 20, 2024
@warioishere
Copy link
Author

@p1gp1g sorry to tag you again, hope you had some nice and peacefulchristmas days. Jst wanted to ask if those logs give you any glue and if you have an idea why our phones dont ring. I can provide you with more details if you need more, jst let me know what you want.

greetings

@p1gp1g
Copy link
Contributor

p1gp1g commented Dec 29, 2024

I forgot to answer. It looks like the issue is not related to push notifications. Can you try with Element-X ?

@warioishere
Copy link
Author

warioishere commented Dec 29, 2024

similiar or even worse behavior:

Call from matrix.org account to an account on our server with our ntfy server: Absolutly nothing when receiving an element-call. No notification, no sound, no beep, no ring.

Calling from our homeserver the phone with matrix.org account using our ntfy server. Only single notification, no ringing. Similar or the same on what I experience with element.

Message notifications do work with element-x on our instance with our ntfy server. Also the notification test on Element-X doesnt show any problems.

@p1gp1g
Copy link
Contributor

p1gp1g commented Dec 29, 2024

I get a notification only with Element Video call too. Once again, this is not related to push notifications. Something is probably wrong with Element (not X) and your TURN server

@p1gp1g
Copy link
Contributor

p1gp1g commented Dec 29, 2024

You may need to check incoming connections on your server

@warioishere
Copy link
Author

You may need to check incoming connections on your server

incoming connections on my synapse server, or on my turn server?

multiple test and logs from my turnserver dont show any problems

turntest:

turnutils_uclient -p 5349 -W xxx -v -y coturn.redington.ch

0: : IPv4. Connected from: 192.168.1.84:56679
0: : IPv4. Connected from: 192.168.1.84:56679
0: : IPv4. Connected to: 82.165.65.57:5349
0: : allocate sent
0: : allocate response received: 
0: : allocate sent
0: : allocate response received: 
0: : success
0: : IPv4. Received relay addr: 82.165.65.57:43268
0: : clnet_allocate: rtv=4334497539337271207
0: : refresh sent
1: : refresh response received: 
1: : success
1: : IPv4. Connected from: 192.168.1.84:32919
1: : IPv4. Connected to: 82.165.65.57:5349
1: : IPv4. Connected from: 192.168.1.84:60806
1: : IPv4. Connected to: 82.165.65.57:5349
1: : IPv4. Connected from: 192.168.1.84:54320
1: : IPv4. Connected to: 82.165.65.57:5349
1: : IPv4. Connected from: 192.168.1.84:38687
1: : IPv4. Connected to: 82.165.65.57:5349
1: : allocate sent
1: : allocate response received: 
1: : allocate sent
1: : allocate response received: 
1: : success
1: : IPv4. Received relay addr: 82.165.65.57:43269
1: : clnet_allocate: rtv=0
1: : refresh sent
1: : refresh response received: 
1: : success
1: : allocate sent
1: : allocate response received: 
1: : allocate sent
1: : allocate response received: 
1: : success
1: : IPv4. Received relay addr: 82.165.65.57:46628
1: : clnet_allocate: rtv=2565534525979484690
1: : refresh sent
1: : refresh response received: 
1: : success
1: : allocate sent
1: : allocate response received: 
1: : allocate sent
1: : allocate response received: 
1: : success
1: : IPv4. Received relay addr: 82.165.65.57:46629
1: : clnet_allocate: rtv=0
1: : refresh sent
1: : refresh response received: 
1: : success
1: : allocate sent
1: : allocate response received: 
1: : allocate sent
1: : allocate response received: 
1: : success
1: : IPv4. Received relay addr: 82.165.65.57:57874
1: : clnet_allocate: rtv=7436414119844114618
1: : refresh sent
1: : refresh response received: 
1: : success
1: : channel bind sent
1: : cb response received: 
1: : success: 0x678a
1: : channel bind sent
1: : cb response received: 
1: : success: 0x4fad
1: : channel bind sent
1: : cb response received: 
1: : success: 0x7c42
1: : channel bind sent
1: : cb response received: 
1: : success: 0x59ed
2: : Total connect time is 2
2: : start_mclient: msz=4, tot_send_msgs=0, tot_recv_msgs=0, tot_send_bytes ~ 0, tot_recv_bytes ~ 0
3: : start_mclient: msz=4, tot_send_msgs=0, tot_recv_msgs=0, tot_send_bytes ~ 0, tot_recv_bytes ~ 0
4: : start_mclient: msz=4, tot_send_msgs=0, tot_recv_msgs=0, tot_send_bytes ~ 0, tot_recv_bytes ~ 0
5: : start_mclient: msz=4, tot_send_msgs=0, tot_recv_msgs=0, tot_send_bytes ~ 0, tot_recv_bytes ~ 0
6: : start_mclient: msz=4, tot_send_msgs=15, tot_recv_msgs=15, tot_send_bytes ~ 1500, tot_recv_bytes ~ 1500
7: : start_mclient: msz=4, tot_send_msgs=15, tot_recv_msgs=15, tot_send_bytes ~ 1500, tot_recv_bytes ~ 1500
7: : done, connection 0x726946261010 closed.
7: : done, connection 0x726946240010 closed.
7: : done, connection 0x72694621f010 closed.
7: : done, connection 0x726945fdf010 closed.
7: : start_mclient: tot_send_msgs=20, tot_recv_msgs=20
7: : start_mclient: tot_send_bytes ~ 2000, tot_recv_bytes ~ 2000
7: : Total transmit time is 5
7: : Total lost packets 0 (0.000000%), total send dropped 0 (0.000000%)
7: : Average round trip delay 32.650000 ms; min = 26 ms, max = 41 ms
7: : Average jitter 2.800000 ms; min = 0 ms, max = 14 ms

logs from coturn server:

874: : session 003000000000000004: realm <coturn.redington.ch> user <>: incoming packet BINDING processed, success
874: : session 003000000000000004: realm <coturn.redington.ch> user <>: incoming packet message processed, error 401: Unauthorized
874: : IPv4. tcp or tls connected to: 194.230.148.154:16215
874: : IPv4. tcp or tls connected to: 194.230.148.154:53419
874: : IPv4. tcp or tls connected to: 194.230.148.154:53420
874: : session 000000000000000003: realm <coturn.redington.ch> user <>: incoming packet message processed, error 401: Unauthorized
874: : IPv4. Local relay addr: 82.165.65.57:54872
874: : session 003000000000000004: new, realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, lifetime=600
874: : session 003000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet ALLOCATE processed, success
874: : IPv4. Local relay addr: 82.165.65.57:33214
874: : session 000000000000000003: new, realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, lifetime=600
874: : session 000000000000000003: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet ALLOCATE processed, success
874: : session 001000000000000004: realm <coturn.redington.ch> user <>: incoming packet message processed, error 401: Unauthorized
874: : session 000000000000000004: realm <coturn.redington.ch> user <>: incoming packet message processed, error 401: Unauthorized
874: : IPv4. Local relay addr: 82.165.65.57:47863
874: : session 001000000000000004: new, realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, lifetime=600, cipher=ECDHE-RSA-AES128-GCM-SHA256, method=TLSv1.2
874: : session 001000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet ALLOCATE processed, success
874: : session 000000000000000004: realm <coturn.redington.ch> user <>: incoming packet message processed, error 401: Unauthorized
874: : session 001000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet ALLOCATE processed, success
874: : IPv4. Local relay addr: 82.165.65.57:36288
874: : session 000000000000000004: new, realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, lifetime=600, cipher=ECDHE-RSA-AES128-GCM-SHA256, method=TLSv1.2
874: : session 000000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet ALLOCATE processed, success
874: : session 000000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet ALLOCATE processed, success
884: : session 003000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet BINDING processed, success
894: : session 003000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet BINDING processed, success
896: : session 000000000000000004: refreshed, realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, lifetime=0, cipher=ECDHE-RSA-AES128-GCM-SHA256, method=TLSv1.2
896: : session 000000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet REFRESH processed, success
896: : session 000000000000000004: TLS/TCP socket disconnected: 194.230.148.154:53420
896: : session 000000000000000004: usage: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, rp=5, rb=488, sp=5, sb=552
896: : session 000000000000000004: peer usage: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, rp=0, rb=0, sp=0, sb=0
896: : session 000000000000000004: closed (2nd stage), user <1735590425:@schidddy999:matrix.yourdevice.ch> realm <coturn.redington.ch> origin <>, local 82.165.65.57:5349, remote 194.230.148.154:53420, reason: TLS/TCP socket buffer operation error (callback)
896: : session 000000000000000004: delete: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>
896: : session 001000000000000004: refreshed, realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, lifetime=0, cipher=ECDHE-RSA-AES128-GCM-SHA256, method=TLSv1.2
896: : session 001000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet REFRESH processed, success
896: : session 001000000000000004: TLS/TCP socket disconnected: 194.230.148.154:53419
896: : session 001000000000000004: usage: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, rp=4, rb=460, sp=4, sb=432
896: : session 001000000000000004: peer usage: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, rp=0, rb=0, sp=0, sb=0
896: : session 001000000000000004: closed (2nd stage), user <1735590425:@schidddy999:matrix.yourdevice.ch> realm <coturn.redington.ch> origin <>, local 82.165.65.57:5349, remote 194.230.148.154:53419, reason: TLS/TCP socket buffer operation error (callback)
896: : session 001000000000000004: delete: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>
896: : session 003000000000000004: refreshed, realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, lifetime=0
896: : session 003000000000000004: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet REFRESH processed, success
896: : session 000000000000000003: refreshed, realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, lifetime=0
896: : session 000000000000000003: realm <coturn.redington.ch> user <1735590425:@schidddy999:matrix.yourdevice.ch>: incoming packet REFRESH processed, success
896: : session 000000000000000003: TCP socket closed remotely 194.230.148.154:16215
896: : session 000000000000000003: usage: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, rp=3, rb=316, sp=3, sb=320
896: : session 000000000000000003: peer usage: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, rp=0, rb=0, sp=0, sb=0
896: : session 000000000000000003: closed (2nd stage), user <1735590425:@schidddy999:matrix.yourdevice.ch> realm <coturn.redington.ch> origin <>, local 82.165.65.57:3478, remote 194.230.148.154:16215, reason: TCP connection closed by client (callback)
896: : session 000000000000000003: delete: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>
897: : session 003000000000000004: usage: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, rp=6, rb=376, sp=6, sb=596
897: : session 003000000000000004: peer usage: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>, rp=0, rb=0, sp=0, sb=0
897: : session 003000000000000004: closed (2nd stage), user <1735590425:@schidddy999:matrix.yourdevice.ch> realm <coturn.redington.ch> origin <>, local 82.165.65.57:3478, remote 194.230.148.154:42496, reason: allocation timeout
897: : session 003000000000000004: delete: realm=<coturn.redington.ch>, username=<1735590425:@schidddy999:matrix.yourdevice.ch>

i think the 401 unauthorized comes up because clients first wanna autheticate with username password but after not sucess, they correctly use the shared secret authentication method. I also dont see any problems here

Any further hints?

Thanks for still looking into this.

@warioishere
Copy link
Author

warioishere commented Dec 29, 2024

I know the TLS/TCP socket buffer operation error (callback) comes from using LE certificates I am currently using. But i have a no tls connection on the turn server and the matrixserver configured aswell.
I tried also ZeroSSL Certificats, but that didnt made any difference except that the TLS/TCP socket buffer operation error (callback) errors disappeared. I still had no luck with the calls. Only notification, no more ringing.

And I am still like thinking, as it worked on element 1.5.28 so there shouldnt be coturn problems here aswell causing this?

@p1gp1g
Copy link
Contributor

p1gp1g commented Dec 29, 2024

It may be because of your missing certificate. Does it work with an account on matrix.org ?

@warioishere
Copy link
Author

It may be because of your missing certificate. Does it work with an account on matrix.org ?

No, I told you, i am also using non tls connection fallback on port 3478
And I used ZeroSSL Zertificates too which dont have this issue with webrtc connections like LE certificates do. Actually i thought this was resolved:

#1533

i also used pure turn setup without tls, no luck. The phone doesnt ring.

I never said the call itself dont work, they always work. The turn server is relaying the calls, we can call. But on Element, the phone dont ring but just give this damn notification sound and then you always miss the call because you things its just a notification

@p1gp1g
Copy link
Contributor

p1gp1g commented Dec 29, 2024

Can you share this ?

image

@maximushugus
Copy link

Just to update, as I said previously, I have a similar configuration as @warioishere with self hosted synapse, coturn and ntfy.
I also have this problem with no ringing when calling.
@p1gp1g My android setting for calls is exactly the same as in your screenshot.

@nikmit
Copy link

nikmit commented Jan 8, 2025

I have the same issue as well and just read this whole thread. Thanks to everyone who has done work on it to help resolve what is a really annoying issue.

@warioishere I would consider helping fund some dev time so someone like p1gp1g can have a troubleshooting session (screenshare on jitsi?) with you and help push this forward. I have huge respect for what you are doing in trying to move people away from the likes of WhatsApp. That said, I recently came across this: https://soatok.blog/2024/08/14/security-issues-in-matrixs-olm-library/ and it is a discouraging read. Have you looked into it? I am hoping it is overblown.

@Bugsbane
Copy link

Bugsbane commented Feb 17, 2025

I'm having the same issue (messages come through just fine, but no notification of calls). I have a similar selfhosted setup. I'm using Yunohost's Coturn and unified push via Nextpush (the Nextcloud Unified Push app) not NTFY. This suggests to me that the issue is not with NTFY.

My phone runs GrapheneOS with Nextpush application and calls don't seem to ring, either incoming or outgoing. I'm testing with family members who are running Element with Google notifications. That suggests to me that the issue isn't Unified Push, but either Synapse, Element or Coturn.

I'm not sure, but if I remember right, I think calls were working with Element web, just not between Element Android and Element Android/iOS.

I would also be willing to help fund some dev time and/or troubleshoot to get this fixed.

@warioishere
Copy link
Author

warioishere commented Feb 18, 2025

Don't expect any help from element hq itself. They don't care about this issue, they dont want to solve it from what reason ever. They dont care about this thread. Propably because they are jst on their new element-x messenger and other things which are completly uneccesary (mas, element-call, and other services which are just not needed in any cases because there are already exisiting solutions which works fine, at least in my my humble opinion. They integrate sooo many wild things, fix so many issues which I never experienced ever myself, and so many new features and etc but basics like calling notifications dont work since years. Not even with googles fcm reliable.

I am writing since a year and trying to fix this issue. I gave up.

I even showed, that all these things where working on a previous version, seen in my posts. but there is no will to go back to the working settings in version see here:

#8761 (comment)

I even tried to reach out to bmarty per mail, no reply. no answer, nothing.

@warioishere
Copy link
Author

Again for all readers: Version Version 1.5.28 works with ringing notification even in optimized battery mode.

If that is the case, then its completly clear that element messenger itself is the faulty thing here, because on the same setup, same server, same infrastructure. that version work, and newer not anymore.

Try it yourself

@Bugsbane
Copy link

Just curious if anyone here has tried another Matrix client such as Cinny or FluffyChat, and if so, if they have this problem?

@nikmit
Copy link

nikmit commented Feb 20, 2025

@Bugsbane Thank you for mentioning these - FluffyChat is in F-Droid, installed, all verification and history sync worked nicely. When the normal ppl wake up I will do some notification testing.

@warioishere
Copy link
Author

of course I tried them too, but they have the same problem because they are based on element.

Just curious if anyone here has tried another Matrix client such as Cinny or FluffyChat, and if so, if they have this problem?

@Bugsbane
Copy link

Bugsbane commented Mar 1, 2025

@warioishere Any chance you could update this to reflect that it affects basically anyone not using Google / Apple for notifications, and not just people using NTFY? Eg:

"Calls won't ring without Google / Apple notifications (eg using UnifiedPush)"

It may make it easier to raise some attention for this, if people realize it's for all Element users on non Google / Apple phones rather than just users of NTFY.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
T-Defect Something isn't working: bugs, crashes, hangs and other reported problems
Projects
None yet
Development

No branches or pull requests

6 participants