search

owntracks / android

OwnTracks Android App
http://owntracks.org
Eclipse Public License 1.0
1.27k stars 470 forks source link

Transition messages fail to send until the device stops moving #1688

Closed wir3z closed 1 month ago

wir3z commented 1 month ago

The app is properly reporting location movements, and triggers on region transitions, but errors on sending the transition message until the phone stops moving. Looking at the logs, the transition message gets perpetually rescheduled until that time. I've attached a sample of the log below.

If I leave home, and drive to work, once I stop moving at work, I will get two transitions from the app delivered at the same time.

2024-04-23 07:39:07.596 W [DefaultDispatcher-worker-8] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:39:07.614 I [DefaultDispatcher-worker-7] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 1s before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:39:08.717 W [DefaultDispatcher-worker-9] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:39:08.726 I [DefaultDispatcher-worker-7] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2s before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:39:11.069 W [DefaultDispatcher-worker-7] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:39:11.085 I [DefaultDispatcher-worker-8] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 4s before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:39:15.298 W [DefaultDispatcher-worker-8] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:39:15.305 I [DefaultDispatcher-worker-9] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 8s before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:39:23.545 W [DefaultDispatcher-worker-8] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:39:23.557 I [DefaultDispatcher-worker-7] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 16s before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:39:39.775 W [DefaultDispatcher-worker-7] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:39:39.794 I [DefaultDispatcher-worker-9] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 32s before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:40:12.030 W [DefaultDispatcher-worker-4] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:40:12.039 I [DefaultDispatcher-worker-13] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 1m 4s before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:41:16.276 W [DefaultDispatcher-worker-9] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:41:16.288 I [DefaultDispatcher-worker-12] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:43:16.507 W [DefaultDispatcher-worker-2] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:43:16.522 I [DefaultDispatcher-worker-2] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:45:16.886 W [DefaultDispatcher-worker-15] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:45:16.893 I [DefaultDispatcher-worker-9] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:47:17.177 W [DefaultDispatcher-worker-6] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:47:17.184 I [DefaultDispatcher-worker-2] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:49:17.553 W [DefaultDispatcher-worker-7] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:49:17.569 I [DefaultDispatcher-worker-6] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:20.17.868 W [DefaultDispatcher-worker-6] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:20.17.883 I [DefaultDispatcher-worker-8] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:53:18.163 W [DefaultDispatcher-worker-6] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:53:18.186 I [DefaultDispatcher-worker-1] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:55:18.375 W [DefaultDispatcher-worker-5] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:55:18.392 I [DefaultDispatcher-worker-8] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:57:18.626 W [DefaultDispatcher-worker-4] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:57:18.640 I [DefaultDispatcher-worker-13] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 07:59:19.160 W [DefaultDispatcher-worker-4] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 07:59:19.188 I [DefaultDispatcher-worker-6] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 08:01:19.489 W [DefaultDispatcher-worker-11] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 08:01:19.505 I [DefaultDispatcher-worker-5] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 08:03:19.797 W [DefaultDispatcher-worker-3] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 08:03:19.811 I [DefaultDispatcher-worker-10] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 08:05:20.171 W [DefaultDispatcher-worker-13] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 08:05:20.187 I [DefaultDispatcher-worker-13] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 08:07:20.637 W [DefaultDispatcher-worker-4] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 08:07:20.652 I [DefaultDispatcher-worker-9] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 08:09:20.956 W [DefaultDispatcher-worker-14] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 08:09:20.971 I [DefaultDispatcher-worker-6] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 08:10:40.352 W [DefaultDispatcher-worker-2] DeviceGeocoder/doLookup/27: Rate-limited, not querying until 2024-04-23T14:10:47.702Z
2024-04-23 08:11:21.307 W [DefaultDispatcher-worker-9] MessageProcessor/sendAvailableMessages/246: Error sending message [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]. Re-queueing
2024-04-23 08:11:21.322 I [DefaultDispatcher-worker-2] MessageProcessor$sendAvailableMessages/invokeSuspend/264: Waiting for 2m before retrying [MessageLocation id=8edb836e ts=2024-04-23T13:39:07Z,lat=20.1712948,long=10.1138726,created_at=2024-04-23T13:39:07.345Z,trigger=DEFAULT]
2024-04-23 08:13:23.250 I [DefaultDispatcher-worker-2] MessageProcessor/processIncomingMessage/340: Received incoming message: MessageCmd on  with id=c11e8206
growse commented 1 month ago

Hmm, "Error sending message" normally indicates connection trouble. Wonder if it's related to #1686?

Can you upgrade to beta-2 (when it's out, shortly) and see if you see the same thing?

wir3z commented 1 month ago

Sure will do!

wir3z commented 1 month ago

After pulling up to beta-2, this seems to be resolved. I'll continue to monitor over the weekend, and close this one out if it looks good. Thanks!

wir3z commented 1 month ago

Closing this one. Beta2 has been flawless and responsive after the SHA 5a90786eba69f78c2eb42682986e5d35d83e9ee8 commit.