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 · 65 comments
Open

no call ringing when using own ntfy server #8761

warioishere opened this issue Feb 15, 2024 · 65 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

@p1gp1g I know you are propably busy, but can you share your opinion on that? I am running and hosting a whole matrix ecosystem with seperate coturn server since over two years now, and trying to push matrix in switzerland hard, but what everyone hates is, that calling notification do not work, which makes people like not really happy with matrix, and then they switch back to their usual behavior using whatsapp/telegram and co, thats what we want to prevent.

@warioishere
Copy link
Author

sorry but did you read my messages? I did that, and all I get on normal element is a message notification (for a call), and not a ringing notification. I just get a messages notfication only once and then nothing anymore when someone is calling.

I was asking your friendlly, why this element version from the past, as I stated here:

#8761 (comment)

makes element/the phone ring when somebody calls. I dont need to even give unrestricted network permissions on that old version. It rings as it should. Even in optimized mode.

Again, do that: element-hq/element-x-android#3031 (comment)

For info:

* [Not receiving notifications on the F-Droid version with unified push element-x-android#3031 (comment)](https://github.com/element-hq/element-x-android/issues/3031#issuecomment-2451420774)

* [Not receiving notifications on the F-Droid version with unified push element-x-android#3031 (comment)](https://github.com/element-hq/element-x-android/issues/3031#issuecomment-2451422699)

@warioishere
Copy link
Author

warioishere commented Nov 5, 2024

@p1gp1g just another testing, I upgrade on my testing phone from element 1.5.28, to 1.5.30 and thats the version where issues started to arrise: calling notifications stops working.

I can make you a video if you dont believe.

Version 1.5.28 works with ringing notification even in optimized battery mode.
From 1.5.30 on, you need it to have unrestricted mode for it not to crash, and then only in unrestricted mode, it receives a ringing notification.
Version 1.5.3.2 the same behavior - only unrestricked mode works with a ringing notification it rings until you take the call
Version 1.6.0 doesnt work anymore, no calling ringing notification even in unrestricted mode. this is the version where you started to get no more ringing, but just a single notification once. Again, its in unrestricted mode.

You know, everything I wanna do is just help, and this calling issue is a really issue, I am not dreaming, everyone here that uses ntfy and our server with coturn has the same issue. I am not making this up, if its working on 1.5.28 just completly normaly, why not reverting back to that state in the topic of how notification works?

I am not a coder, just a systemadmin. Again, I dont want to bother, but its essential that we have a working calling notification. And thats just not the case.

I spend another hour now on testing and giving you information, i dont know how much time I already spend on finding out whats the problem, we even reinstalled our infrastructed, reinstalled coturn, our own ntfy server because people told me I propably have setup problems, but I dont. ntfy server is whitelisted on the homeserver.yml. the Firewalls are not the problem, neither certificates or wrong settings on the coturn server.

@p1gp1g
Copy link
Contributor

p1gp1g commented Nov 8, 2024

I have just tested with Element 1.6.20 and my phone rings when I receive a call. Are you sure you didn't do anything to the notification settings ?

I think you should debug your things by watching your ntfy access log and your synapse logs and element logcats in the same time

PS:

  • If I've suggested to triple check battery optimization is because of the new android UX many users confuse "allow background usage" and "allow background usage without restrictions", see the 1st link of the previous comment.
  • The 2nd link explain why there is this bug, and that it is going to be fixed
  • The 3rd link is a comment explaining what Element should do too
    I've pasted them because it's tiring to have to repeat the same things. I help voluntarily on this.

@warioishere
Copy link
Author

warioishere commented Dec 19, 2024

I have just tested with Element 1.6.20 and my phone rings when I receive a call. Are you sure you didn't do anything to the notification settings ?

I think you should debug your things by watching your ntfy access log and your synapse logs and element logcats in the same time

PS:

  • If I've suggested to triple check battery optimization is because of the new android UX many users confuse "allow background usage" and "allow background usage without restrictions", see the 1st link of the previous comment.
  • The 2nd link explain why there is this bug, and that it is going to be fixed
  • The 3rd link is a comment explaining what Element should do too
    I've pasted them because it's tiring to have to repeat the same things. I help voluntarily on this.

For the third time, no I didnt do anything to the notifications settings.
For the third time, i double checked bat mode as maybe you read in my previous post. I am also tired of repeating myself a thousand times. I did those testings on multiple phones! Every phone shows the same behavior.

Also I dont understand why you and nobody commend smy testings and what ive found out with element versions below 1.5.28 that didnt need unrestricted mode for the phone to ring correcty. It used to work, now it doesnt. same setup, nothing changend, simple as that.

I did a shit load of work in the past two years to get people away from mass survailance messengers like telegram and whatsapp. We once had over 250 People and we run the biggest server her in switzerland. But everyone has problems with notifications, (even with googles fcm) people partially wait 8H for a notification of a new message to arrive.

We almost got no more users anymore, everyone wents back to tg and whatsapp, I run 3 servers and I spent so many hours in trying to fix this.

I am stopping my contributions to the matrix foundations and element hq donations from now on. @bmarty tagged in one issue, didnt reply even. I have enough, shutting down the server as soon as the contract ends.

Its just a joke what happens here, so many complain about this shit push implementation and they do nothing bug fixing bugs that ive never heard off or never encountered, or even integrate own OIDP Solution even there are million outside there.

Or the best ever thing, no more p2p calls on element-x, I couldnt jst believe this why I need a damn matrix-call server now for this to work. How many server do you want me to run. This all costs money and ressources! No more normal calls on element-x ? Its just a complete joke if you ask me.

Thanks for your time and all your effort but we are swtiching away to other chat protocols that are able to handle push and calls properly.

@p1gp1g
Copy link
Contributor

p1gp1g commented Dec 19, 2024

You know, I am helping benevolent. I do not work for any company related to matrix and I am happy to help when I can. So the bare minimum would be to stay polite, and pinging developers and people helping you is usually not good manners. One other thing you seem to forget is free software doesn't mean free support (=free work), especially when it comes to debug your specific self-hosting setup. Nevertheless, if we can find a solution for you that's great

If I have suggested multiple time the same thing, for a good reason: you forgot to did it (again, that's because of a terrible UX, and that's still the issue for santilococo). Then you wonder why it wasn't required for a previous version, so I've pasted everything you need to understand why (here I suggest to do something you have already done, because it was an answer after yet another highlight, so I didn't read the whole thread)

Now regarding your issue, if it works with matrix.org and not with your homeserver: this is probably regarding your hosting setup. It is pretty hard to debug without at least your app logs (which you forgot to send). You probably need to check every different part of the system, you can probably do it yourself. You may have some help with step by step feedback and patience or hire someone to do it.

@warioishere
Copy link
Author

warioishere commented Dec 19, 2024

You know, I am helping benevolent. I do not work for any company related to matrix and I am happy to help when I can. So the bare minimum would be to stay polite, and pinging developers and people helping you is usually not good manners. One other thing you seem to forget is free software doesn't mean free support (=free work), especially when it comes to debug your specific self-hosting setup. Nevertheless, if we can find a solution for you that's great

If I have suggested multiple time the same thing, for a good reason: you forgot to did it (again, that's because of a terrible UX, and that's still the issue for santilococo). Then you wonder why it wasn't required for a previous version, so I've pasted everything you need to understand why (here I suggest to do something you have already done, because it was an answer after yet another highlight, so I didn't read the whole thread)

Now regarding your issue, if it works with matrix.org and not with your homeserver: this is probably regarding your hosting setup. It is pretty hard to debug without at least your app logs (which you forgot to send). You probably need to check every different part of the system, you can probably do it yourself. You may have some help with step by step feedback and patience or hire someone to do it.

I am sorry for pushing and showing this kind of behavior, I am just frustrated because we loose so many members already. We also have people with Googles FCM getting heavily delayed message notifications and they have to go into complete unrestricted mode to get instant notifications again.
I already debugged my setup so many times, with the guys from ntfy, with synapse admin chats, with server adminfriends arround. And also because it worked with previous version i mentioned, I believe somehow the setup is not wrong?

Alright here are my android logs. Phone Is pixel 9 pro, ntfy and element with in complete unrestricted mode. Android 15 grapheneOS latest. Latest ntfy server version. latest coturn version. Latest matrix-synapse version. I only get a short notification, but no ringing when receiving a call. Its like a message notfication then the sound turns off again. Once, I think it was yesterday, the screen went on like before showing a call incoming. It was once but never happend again. Heres the logs from the call:

12-19 20:51:27.648  3536  3737 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-19 20:51:27.676  3536  3737 D WM-SystemJobScheduler: Scheduling work ID a51da7c4-e3ef-4adb-99b0-1a580dd924cbJob ID 30515
12-19 20:51:27.703  3536  3737 D WM-GreedyScheduler: Starting tracking for a51da7c4-e3ef-4adb-99b0-1a580dd924cb
12-19 20:51:27.710  3536  3737 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=true, isNotRoaming=true)
12-19 20:51:27.710  3536  3737 D WM-NetworkStateTracker: Registering network callback
12-19 20:51:27.714  3536  3737 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: a51da7c4-e3ef-4adb-99b0-1a580dd924cb}
12-19 20:51:27.714  3536  3737 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, generation=0)
12-19 20:51:27.726  3536  3660 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: CELLULAR Capabilities: SUPL&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=7898Kbps LinkDnBandwidth>=9363Kbps Specifier: <TelephonyNetworkSpecifier [mSubId = 1]> SubscriptionIds: {1} UnderlyingNetworks: Null]
12-19 20:51:27.731  3536  3536 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, generation=0)
12-19 20:51:27.731  3536  3737 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, generation=0)
12-19 20:51:27.752  3536  3536 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-19 20:51:27.762  3536  3737 D WM-Processor: Work WorkGenerationalId(workSpecId=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, generation=0) is already enqueued for processing
12-19 20:51:28.748  3536 28172 D TrafficStats: tagSocket(122) with statsTag=0xffffffff, statsUid=-1
12-19 20:51:35.070  3536  3658 D UnifiedPush: Found distributor with package name im.vector.app
12-19 20:51:35.070  3536  3658 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-19 20:51:35.070  3536  3658 D UnifiedPush: Found saved distributor.
12-19 20:51:35.073  3536  3658 D UnifiedPush: Found distributor with package name im.vector.app
12-19 20:51:35.073  3536  3658 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-19 20:51:35.076  3536  3658 D UnifiedPush: Found saved distributor.
12-19 20:51:35.298  3536  3739 D WM-Processor: Processor cancelling 242c79b6-337a-413a-b919-838b0eaec9fd
12-19 20:51:35.298  3536  3739 D WM-Processor: WorkerWrapper could not be found for 242c79b6-337a-413a-b919-838b0eaec9fd
12-19 20:51:35.300  3536  3739 D WM-GreedyScheduler: Cancelling work ID 242c79b6-337a-413a-b919-838b0eaec9fd
12-19 20:51:35.304  3536  3739 D WM-Processor: Processor cancelling 74f863ae-4892-4ec0-85d1-8d3a1e53731c
12-19 20:51:35.304  3536  3739 D WM-Processor: WorkerWrapper could not be found for 74f863ae-4892-4ec0-85d1-8d3a1e53731c
12-19 20:51:35.305  3536  3739 D WM-GreedyScheduler: Cancelling work ID 74f863ae-4892-4ec0-85d1-8d3a1e53731c
12-19 20:51:35.311  3536  3739 D WM-Processor: Processor cancelling a51da7c4-e3ef-4adb-99b0-1a580dd924cb
12-19 20:51:35.311  3536  3739 D WM-WorkerWrapper: Work interrupted for Work [ id=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:35.328  3536  3739 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-19 20:51:35.329  3536  3739 D WM-Processor: WorkerWrapper interrupted for a51da7c4-e3ef-4adb-99b0-1a580dd924cb
12-19 20:51:35.335  3536  3739 D WM-GreedyScheduler: Cancelling work ID a51da7c4-e3ef-4adb-99b0-1a580dd924cb
12-19 20:51:35.349  3536  3739 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-19 20:51:35.358  3536  3739 D WM-SystemJobScheduler: Scheduling work ID 51265ac3-55e7-492e-9d6c-3f00ad78c46bJob ID 30516
12-19 20:51:35.374  3536  3739 D WM-GreedyScheduler: Starting tracking for 51265ac3-55e7-492e-9d6c-3f00ad78c46b
12-19 20:51:35.374  3536  3739 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: a51da7c4-e3ef-4adb-99b0-1a580dd924cb}
12-19 20:51:35.374  3536  3739 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 51265ac3-55e7-492e-9d6c-3f00ad78c46b}
12-19 20:51:35.374  3536  3739 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, generation=0)
12-19 20:51:35.374  3536  3739 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=51265ac3-55e7-492e-9d6c-3f00ad78c46b, generation=0)
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper: Work [ id=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ] was cancelled
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper: java.util.concurrent.CancellationException: Task was cancelled.
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.cancellationExceptionWithCause(SourceFile:3)
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.getDoneValue(SourceFile:31)
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.get(SourceFile:92)
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper:      at androidx.work.impl.WorkerWrapper$2.run(SourceFile:5)
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper:      at androidx.work.impl.utils.SerialExecutorImpl$Task.run(SourceFile:3)
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:644)
12-19 20:51:35.388  3536  3739 I WM-WorkerWrapper:      at java.lang.Thread.run(Thread.java:1117)
12-19 20:51:35.389  3536  3739 D WM-WorkerWrapper: Work interrupted for Work [ id=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:35.392  3536  3739 D WM-GreedyScheduler: Cancelling work ID a51da7c4-e3ef-4adb-99b0-1a580dd924cb
12-19 20:51:35.395  3536  3739 D WM-Processor: WorkerWrapper could not be found for a51da7c4-e3ef-4adb-99b0-1a580dd924cb
12-19 20:51:35.396  3536  3739 D WM-StopWorkRunnable: StopWorkRunnable for a51da7c4-e3ef-4adb-99b0-1a580dd924cb; Processor.stopWork = false
12-19 20:51:35.398  3536  3739 W WM-Processor: Didn't find WorkSpec for id WorkGenerationalId(workSpecId=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, generation=0)
12-19 20:51:35.400  3536  3739 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=51265ac3-55e7-492e-9d6c-3f00ad78c46b, generation=0)
12-19 20:51:35.400  3536  3739 D WM-Processor: WorkerWrapper could not be found for a51da7c4-e3ef-4adb-99b0-1a580dd924cb
12-19 20:51:35.400  3536  3739 D WM-StopWorkRunnable: StopWorkRunnable for a51da7c4-e3ef-4adb-99b0-1a580dd924cb; Processor.stopWork = false
12-19 20:51:35.465  3536  3536 D WM-Processor: Processor a51da7c4-e3ef-4adb-99b0-1a580dd924cb executed; reschedule = false
12-19 20:51:35.465  3536  3536 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, generation=0)
12-19 20:51:35.465  3536  3536 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 51265ac3-55e7-492e-9d6c-3f00ad78c46b}
12-19 20:51:35.465  3536  3536 D WM-SystemJobService: a51da7c4-e3ef-4adb-99b0-1a580dd924cb executed on JobScheduler
12-19 20:51:35.465  3536  3536 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=a51da7c4-e3ef-4adb-99b0-1a580dd924cb, generation=0)
12-19 20:51:35.475  3536  3536 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=51265ac3-55e7-492e-9d6c-3f00ad78c46b, generation=0)
12-19 20:51:35.478  3536  3646 D WM-Processor: Work WorkGenerationalId(workSpecId=51265ac3-55e7-492e-9d6c-3f00ad78c46b, generation=0) is already enqueued for processing
12-19 20:51:35.480  3536  3536 D WM-Processor: Processor a51da7c4-e3ef-4adb-99b0-1a580dd924cb executed; reschedule = false
12-19 20:51:35.480  3536  3536 D WM-SystemJobService: a51da7c4-e3ef-4adb-99b0-1a580dd924cb executed on JobScheduler
12-19 20:51:35.483  3536  3536 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-19 20:51:37.769  3536  3737 D WM-Processor: Processor cancelling 51265ac3-55e7-492e-9d6c-3f00ad78c46b
12-19 20:51:37.770  3536  3737 D WM-WorkerWrapper: Work interrupted for Work [ id=51265ac3-55e7-492e-9d6c-3f00ad78c46b, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:37.800  3536  3737 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-19 20:51:37.801  3536  3737 D WM-WorkerWrapper: WorkSpec {WorkSpec: 51265ac3-55e7-492e-9d6c-3f00ad78c46b} is already done. Not interrupting.
12-19 20:51:37.801  3536  3737 D WM-Processor: WorkerWrapper interrupted for 51265ac3-55e7-492e-9d6c-3f00ad78c46b
12-19 20:51:37.802  3536  3536 D WM-Processor: Processor 51265ac3-55e7-492e-9d6c-3f00ad78c46b executed; reschedule = false
12-19 20:51:37.802  3536  3536 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=51265ac3-55e7-492e-9d6c-3f00ad78c46b, generation=0)
12-19 20:51:37.802  3536  3536 D WM-NetworkStateTracker: Unregistering network callback
12-19 20:51:37.806  3536  3536 D WM-SystemJobService: 51265ac3-55e7-492e-9d6c-3f00ad78c46b executed on JobScheduler
12-19 20:51:37.806  3536  3536 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=51265ac3-55e7-492e-9d6c-3f00ad78c46b, generation=0)
12-19 20:51:37.807  3536  3737 D WM-GreedyScheduler: Cancelling work ID 51265ac3-55e7-492e-9d6c-3f00ad78c46b
12-19 20:51:37.819  3536  3737 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-19 20:51:37.826  3536  3737 D WM-SystemJobScheduler: Scheduling work ID ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6fJob ID 30517
12-19 20:51:37.833  3536  3737 D WM-GreedyScheduler: Starting tracking for ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f
12-19 20:51:37.835  3536  3536 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f, generation=0)
12-19 20:51:37.837  3536  3737 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=true, isNotRoaming=true)
12-19 20:51:37.837  3536  3737 D WM-NetworkStateTracker: Registering network callback
12-19 20:51:37.839  3536  3737 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f}
12-19 20:51:37.839  3536  3737 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f, generation=0)
12-19 20:51:37.839  3536  3737 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-19 20:51:37.839  3536  3737 D WM-WorkerWrapper: Work interrupted for Work [ id=51265ac3-55e7-492e-9d6c-3f00ad78c46b, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:37.841  3536  3737 D WM-GreedyScheduler: Cancelling work ID 51265ac3-55e7-492e-9d6c-3f00ad78c46b
12-19 20:51:37.842  3536  3660 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: CELLULAR Capabilities: SUPL&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=7898Kbps LinkDnBandwidth>=9363Kbps Specifier: <TelephonyNetworkSpecifier [mSubId = 1]> SubscriptionIds: {1} UnderlyingNetworks: Null]
12-19 20:51:37.847  3536  3737 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f, generation=0)
12-19 20:51:37.848  3536  3645 D WM-Processor: Work WorkGenerationalId(workSpecId=ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f, generation=0) is already enqueued for processing
12-19 20:51:37.854  3536  3536 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-19 20:51:38.426  3536  3571 I im.vector.app: Background concurrent mark compact GC freed 105MB AllocSpace bytes, 155(3244KB) LOS objects, 60% free, 63MB/159MB, paused 3.144ms,2.788ms total 677.220ms
12-19 20:51:39.197  3536  3646 D WM-Processor: Processor cancelling ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f
12-19 20:51:39.198  3536  3646 D WM-WorkerWrapper: Work interrupted for Work [ id=ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:39.211  3536  3646 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-19 20:51:39.213  3536  3646 D WM-WorkerWrapper: WorkSpec {WorkSpec: ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f} is already done. Not interrupting.
12-19 20:51:39.213  3536  3536 D WM-Processor: Processor ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f executed; reschedule = false
12-19 20:51:39.213  3536  3536 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f, generation=0)
12-19 20:51:39.213  3536  3536 D WM-NetworkStateTracker: Unregistering network callback
12-19 20:51:39.215  3536  3536 D WM-SystemJobService: ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f executed on JobScheduler
12-19 20:51:39.217  3536  3646 D WM-Processor: WorkerWrapper interrupted for ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f
12-19 20:51:39.221  3536  3646 D WM-GreedyScheduler: Cancelling work ID ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f
12-19 20:51:39.244  3536  3646 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-19 20:51:39.268  3536  3646 D WM-SystemJobScheduler: Scheduling work ID b3ca1897-eee9-42aa-92d0-313c743d758cJob ID 30518
12-19 20:51:39.285  3536  3646 D WM-GreedyScheduler: Starting tracking for b3ca1897-eee9-42aa-92d0-313c743d758c
12-19 20:51:39.285  3536  3536 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=b3ca1897-eee9-42aa-92d0-313c743d758c, generation=0)
12-19 20:51:39.289  3536  3646 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=true, isNotRoaming=true)
12-19 20:51:39.289  3536  3646 D WM-NetworkStateTracker: Registering network callback
12-19 20:51:39.292  3536  3646 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: b3ca1897-eee9-42aa-92d0-313c743d758c}
12-19 20:51:39.292  3536  3646 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=b3ca1897-eee9-42aa-92d0-313c743d758c, generation=0)
12-19 20:51:39.293  3536  3646 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-19 20:51:39.293  3536  3646 D WM-WorkerWrapper: Work interrupted for Work [ id=ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:39.294  3536  3660 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: CELLULAR Capabilities: SUPL&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=7898Kbps LinkDnBandwidth>=9363Kbps Specifier: <TelephonyNetworkSpecifier [mSubId = 1]> SubscriptionIds: {1} UnderlyingNetworks: Null]
12-19 20:51:39.301  3536  3646 D WM-GreedyScheduler: Cancelling work ID ee2bc0cb-9986-4dbd-ba91-e0c1c7e34a6f
12-19 20:51:39.308  3536  3646 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=b3ca1897-eee9-42aa-92d0-313c743d758c, generation=0)
12-19 20:51:39.310  3536  3646 D WM-Processor: Work WorkGenerationalId(workSpecId=b3ca1897-eee9-42aa-92d0-313c743d758c, generation=0) is already enqueued for processing
12-19 20:51:39.316  3536  3536 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-19 20:51:41.393  3536  3645 D WM-Processor: Processor cancelling b3ca1897-eee9-42aa-92d0-313c743d758c
12-19 20:51:41.393  3536  3645 D WM-WorkerWrapper: Work interrupted for Work [ id=b3ca1897-eee9-42aa-92d0-313c743d758c, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:41.414  3536  3645 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-19 20:51:41.415  3536  3645 D WM-WorkerWrapper: WorkSpec {WorkSpec: b3ca1897-eee9-42aa-92d0-313c743d758c} is already done. Not interrupting.
12-19 20:51:41.415  3536  3536 D WM-Processor: Processor b3ca1897-eee9-42aa-92d0-313c743d758c executed; reschedule = false
12-19 20:51:41.416  3536  3536 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=b3ca1897-eee9-42aa-92d0-313c743d758c, generation=0)
12-19 20:51:41.416  3536  3536 D WM-NetworkStateTracker: Unregistering network callback
12-19 20:51:41.418  3536  3645 D WM-Processor: WorkerWrapper interrupted for b3ca1897-eee9-42aa-92d0-313c743d758c
12-19 20:51:41.422  3536  3536 D WM-SystemJobService: b3ca1897-eee9-42aa-92d0-313c743d758c executed on JobScheduler
12-19 20:51:41.424  3536  3645 D WM-GreedyScheduler: Cancelling work ID b3ca1897-eee9-42aa-92d0-313c743d758c
12-19 20:51:41.427  3536  3536 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=b3ca1897-eee9-42aa-92d0-313c743d758c, generation=0)
12-19 20:51:41.444  3536  3645 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-19 20:51:41.455  3536  3645 D WM-SystemJobScheduler: Scheduling work ID 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112Job ID 30519
12-19 20:51:41.478  3536  3536 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112, generation=0)
12-19 20:51:41.480  3536  3645 D WM-GreedyScheduler: Starting tracking for 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112
12-19 20:51:41.482  3536  3645 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=true, isNotRoaming=true)
12-19 20:51:41.482  3536  3645 D WM-NetworkStateTracker: Registering network callback
12-19 20:51:41.484  3536  3645 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112}
12-19 20:51:41.484  3536  3645 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112, generation=0)
12-19 20:51:41.484  3536  3645 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-19 20:51:41.484  3536  3645 D WM-WorkerWrapper: Work interrupted for Work [ id=b3ca1897-eee9-42aa-92d0-313c743d758c, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:41.488  3536  3645 D WM-GreedyScheduler: Cancelling work ID b3ca1897-eee9-42aa-92d0-313c743d758c
12-19 20:51:41.488  3536  3660 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: CELLULAR Capabilities: SUPL&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=7898Kbps LinkDnBandwidth>=9363Kbps Specifier: <TelephonyNetworkSpecifier [mSubId = 1]> SubscriptionIds: {1} UnderlyingNetworks: Null]
12-19 20:51:41.493  3536  3645 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112, generation=0)
12-19 20:51:41.495  3536  3645 D WM-Processor: Work WorkGenerationalId(workSpecId=7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112, generation=0) is already enqueued for processing
12-19 20:51:41.501  3536  3536 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-19 20:51:43.218  3536  3646 D WM-Processor: Processor cancelling 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112
12-19 20:51:43.219  3536  3646 D WM-WorkerWrapper: Work interrupted for Work [ id=7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:43.241  3536  3646 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-19 20:51:43.242  3536  3646 D WM-WorkerWrapper: WorkSpec {WorkSpec: 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112} is already done. Not interrupting.
12-19 20:51:43.242  3536  3646 D WM-Processor: WorkerWrapper interrupted for 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112
12-19 20:51:43.246  3536  3646 D WM-GreedyScheduler: Cancelling work ID 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112
12-19 20:51:43.259  3536  3646 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-19 20:51:43.266  3536  3646 D WM-SystemJobScheduler: Scheduling work ID d941c084-76a0-4e06-9b35-2135ffc9db47Job ID 30520
12-19 20:51:43.267  3536  3536 D WM-Processor: Processor 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112 executed; reschedule = false
12-19 20:51:43.267  3536  3536 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112, generation=0)
12-19 20:51:43.267  3536  3536 D WM-NetworkStateTracker: Unregistering network callback
12-19 20:51:43.272  3536  3536 D WM-SystemJobService: 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112 executed on JobScheduler
12-19 20:51:43.272  3536  3536 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112, generation=0)
12-19 20:51:43.289  3536  3646 D WM-GreedyScheduler: Starting tracking for d941c084-76a0-4e06-9b35-2135ffc9db47
12-19 20:51:43.293  3536  3536 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=d941c084-76a0-4e06-9b35-2135ffc9db47, generation=0)
12-19 20:51:43.293  3536  3646 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=true, isNotRoaming=true)
12-19 20:51:43.293  3536  3646 D WM-NetworkStateTracker: Registering network callback
12-19 20:51:43.298  3536  3646 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: d941c084-76a0-4e06-9b35-2135ffc9db47}
12-19 20:51:43.298  3536  3646 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=d941c084-76a0-4e06-9b35-2135ffc9db47, generation=0)
12-19 20:51:43.298  3536  3646 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-19 20:51:43.298  3536  3646 D WM-WorkerWrapper: Work interrupted for Work [ id=7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:43.299  3536  3660 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: CELLULAR Capabilities: SUPL&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=7898Kbps LinkDnBandwidth>=9363Kbps Specifier: <TelephonyNetworkSpecifier [mSubId = 1]> SubscriptionIds: {1} UnderlyingNetworks: Null]
12-19 20:51:43.301  3536  3646 D WM-GreedyScheduler: Cancelling work ID 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112
12-19 20:51:43.304  3536  3645 D WM-Processor: WorkerWrapper could not be found for 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112
12-19 20:51:43.304  3536  3645 D WM-StopWorkRunnable: StopWorkRunnable for 7ec526bf-a5b0-49fd-9cbb-6f7dbf6dc112; Processor.stopWork = false
12-19 20:51:43.307  3536  3645 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=d941c084-76a0-4e06-9b35-2135ffc9db47, generation=0)
12-19 20:51:43.308  3536  3645 D WM-Processor: Work WorkGenerationalId(workSpecId=d941c084-76a0-4e06-9b35-2135ffc9db47, generation=0) is already enqueued for processing
12-19 20:51:43.312  3536  3536 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-19 20:51:45.284  3536  3646 D WM-Processor: Processor cancelling d941c084-76a0-4e06-9b35-2135ffc9db47
12-19 20:51:45.284  3536  3646 D WM-WorkerWrapper: Work interrupted for Work [ id=d941c084-76a0-4e06-9b35-2135ffc9db47, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:45.299  3536  3646 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-19 20:51:45.300  3536  3646 D WM-WorkerWrapper: WorkSpec {WorkSpec: d941c084-76a0-4e06-9b35-2135ffc9db47} is already done. Not interrupting.
12-19 20:51:45.300  3536  3646 D WM-Processor: WorkerWrapper interrupted for d941c084-76a0-4e06-9b35-2135ffc9db47
12-19 20:51:45.300  3536  3536 D WM-Processor: Processor d941c084-76a0-4e06-9b35-2135ffc9db47 executed; reschedule = false
12-19 20:51:45.300  3536  3536 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=d941c084-76a0-4e06-9b35-2135ffc9db47, generation=0)
12-19 20:51:45.300  3536  3536 D WM-NetworkStateTracker: Unregistering network callback
12-19 20:51:45.301  3536  3536 D WM-SystemJobService: d941c084-76a0-4e06-9b35-2135ffc9db47 executed on JobScheduler
12-19 20:51:45.307  3536  3646 D WM-GreedyScheduler: Cancelling work ID d941c084-76a0-4e06-9b35-2135ffc9db47
12-19 20:51:45.308  3536  3536 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=d941c084-76a0-4e06-9b35-2135ffc9db47, generation=0)
12-19 20:51:45.317  3536  3646 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
12-19 20:51:45.322  3536  3646 D WM-SystemJobScheduler: Scheduling work ID 361c4eef-e3d5-4633-bcbe-c16b2d0d98acJob ID 30521
12-19 20:51:45.345  3536  3646 D WM-GreedyScheduler: Starting tracking for 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac
12-19 20:51:45.347  3536  3646 D WM-ConstraintTracker: NetworkStateTracker24: initial state = NetworkState(isConnected=true, isValidated=true, isMetered=true, isNotRoaming=true)
12-19 20:51:45.347  3536  3646 D WM-NetworkStateTracker: Registering network callback
12-19 20:51:45.357  3536  3536 D WM-SystemJobService: onStartJob for WorkGenerationalId(workSpecId=361c4eef-e3d5-4633-bcbe-c16b2d0d98ac, generation=0)
12-19 20:51:45.358  3536  3646 D WM-WorkConstraintsTrack: Constraints met for {WorkSpec: 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac}
12-19 20:51:45.359  3536  3646 D WM-GreedyScheduler: Constraints met: Scheduling work ID WorkGenerationalId(workSpecId=361c4eef-e3d5-4633-bcbe-c16b2d0d98ac, generation=0)
12-19 20:51:45.360  3536  3739 D WM-WorkerWrapper: org.matrix.android.sdk.internal.session.sync.job.SyncWorker returned a Success {mOutputData=Data {}}.
12-19 20:51:45.360  3536  3739 D WM-WorkerWrapper: Work interrupted for Work [ id=d941c084-76a0-4e06-9b35-2135ffc9db47, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:45.360  3536  3660 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: CELLULAR Capabilities: SUPL&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=7898Kbps LinkDnBandwidth>=9363Kbps Specifier: <TelephonyNetworkSpecifier [mSubId = 1]> SubscriptionIds: {1} UnderlyingNetworks: Null]
12-19 20:51:45.372  3536  3739 D WM-GreedyScheduler: Cancelling work ID d941c084-76a0-4e06-9b35-2135ffc9db47
12-19 20:51:45.382  3536  3645 D WM-Processor: Processor: processing WorkGenerationalId(workSpecId=361c4eef-e3d5-4633-bcbe-c16b2d0d98ac, generation=0)
12-19 20:51:45.385  3536  3645 D WM-Processor: Work WorkGenerationalId(workSpecId=361c4eef-e3d5-4633-bcbe-c16b2d0d98ac, generation=0) is already enqueued for processing
12-19 20:51:45.391  3536  3536 D WM-WorkerWrapper: Starting work for org.matrix.android.sdk.internal.session.sync.job.SyncWorker
12-19 20:51:47.186  3536  3571 I im.vector.app: Background concurrent mark compact GC freed 96MB AllocSpace bytes, 136(2720KB) LOS objects, 61% free, 61MB/157MB, paused 672us,1.189ms total 222.006ms
12-19 20:51:47.433  3536 27524 D UnifiedPush: Found distributor with package name im.vector.app
12-19 20:51:47.433  3536 27524 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-19 20:51:47.433  3536 27524 D UnifiedPush: Found saved distributor.
12-19 20:51:47.434  3536 27524 D UnifiedPush: Found distributor with package name im.vector.app
12-19 20:51:47.434  3536 27524 D UnifiedPush: Found distributor with package name io.heckel.ntfy
12-19 20:51:47.435  3536 27524 D UnifiedPush: Found saved distributor.
12-19 20:51:47.438  3536  3646 D WM-Processor: Processor cancelling 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac
12-19 20:51:47.438  3536  3646 D WM-WorkerWrapper: Work interrupted for Work [ id=361c4eef-e3d5-4633-bcbe-c16b2d0d98ac, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:47.445  3536  3646 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-19 20:51:47.445  3536  3646 D WM-Processor: WorkerWrapper interrupted for 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac
12-19 20:51:47.452  3536  3646 D WM-GreedyScheduler: Cancelling work ID 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac
12-19 20:51:47.453  3536  3646 D WM-Processor: Processor cancelling 8cd1ecad-d004-45b3-a2d6-c5fa34241e43
12-19 20:51:47.453  3536  3646 D WM-Processor: WorkerWrapper could not be found for 8cd1ecad-d004-45b3-a2d6-c5fa34241e43
12-19 20:51:47.453  3536  3646 D WM-GreedyScheduler: Cancelling work ID 8cd1ecad-d004-45b3-a2d6-c5fa34241e43
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper: Work [ id=361c4eef-e3d5-4633-bcbe-c16b2d0d98ac, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ] was cancelled
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper: java.util.concurrent.CancellationException: Task was cancelled.
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.cancellationExceptionWithCause(SourceFile:3)
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.getDoneValue(SourceFile:31)
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper:      at androidx.work.impl.utils.futures.AbstractFuture.get(SourceFile:92)
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper:      at androidx.work.impl.WorkerWrapper$2.run(SourceFile:5)
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper:      at androidx.work.impl.utils.SerialExecutorImpl$Task.run(SourceFile:3)
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper:      at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:644)
12-19 20:51:47.455  3536  3646 I WM-WorkerWrapper:      at java.lang.Thread.run(Thread.java:1117)
12-19 20:51:47.455  3536  3646 D WM-WorkerWrapper: Work interrupted for Work [ id=361c4eef-e3d5-4633-bcbe-c16b2d0d98ac, tags={ org.matrix.android.sdk.internal.session.sync.job.SyncWorker, MatrixSDK-ed3d9ecc56cada72a3feb689ba3953a3 } ]
12-19 20:51:47.457  3536  3646 D WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
12-19 20:51:47.458  3536  3646 D WM-GreedyScheduler: Cancelling work ID 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac
12-19 20:51:47.459  3536  3646 D WM-Processor: WorkerWrapper could not be found for 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac
12-19 20:51:47.459  3536  3646 D WM-StopWorkRunnable: StopWorkRunnable for 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac; Processor.stopWork = false
12-19 20:51:47.480  3536  3536 D WM-Processor: Processor 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac executed; reschedule = false
12-19 20:51:47.480  3536  3536 D WM-GreedyScheduler: Stopping tracking for WorkGenerationalId(workSpecId=361c4eef-e3d5-4633-bcbe-c16b2d0d98ac, generation=0)
12-19 20:51:47.480  3536  3536 D WM-NetworkStateTracker: Unregistering network callback
12-19 20:51:47.484  3536  3536 D WM-SystemJobService: 361c4eef-e3d5-4633-bcbe-c16b2d0d98ac executed on JobScheduler
12-19 20:51:47.484  3536  3536 D WM-SystemJobService: onStopJob for WorkGenerationalId(workSpecId=361c4eef-e3d5-4633-bcbe-c16b2d0d98ac, generation=0)

maybe you find something.

greetings

@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.

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

5 participants