Skip to content

Error on top of debug log in case of 500 response #2211

@ErikDB87

Description

@ErikDB87

I assume the below exceptions are caused because of the 500 response, which is already logged at DEBUG level (2026-03-10 09:18:41.489 D [DefaultDispatcher-worker-1] HttpMessageProcessorEndpoint: HTTP response received: Response{protocol=http/1.1, code=500, message=openHAB is offline, url=https://home.myopenhab.org/gpstracker/owntracks}). I indeed restarted my openHAB server at that time, so it was offline. But since there's already a DEBUG log entry, isn't raising an error unnecessary?

2026-03-10 09:18:38.170 W [DefaultDispatcher-worker-5] MessageProcessor: Error sending message [MessageLocation id=[redacted] ts=2026-03-10T02:17:26Z,lat=[redacted],long=[redacted],created_at=2026-03-10T08:18:37.840Z,trigger=PING]. Re-queueing
org.owntracks.android.net.MessageProcessorEndpoint$OutgoingMessageSendingException: java.lang.Exception: HTTP request failed. Status: 500
	at org.owntracks.android.net.http.HttpMessageProcessorEndpoint.sendMessage-gIAlu-s(SourceFile:369)
	at org.owntracks.android.services.MessageProcessor.sendAvailableMessages(SourceFile:639)
	at org.owntracks.android.services.MessageProcessor.access$sendAvailableMessages(SourceFile:1)
	at org.owntracks.android.services.MessageProcessor$sendAvailableMessages$1.invokeSuspend(Unknown Source:11)
	at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(SourceFile:9)
	at kotlinx.coroutines.DispatchedTask.run(SourceFile:109)
	at androidx.work.Worker$2.run(SourceFile:21)
	at kotlinx.coroutines.scheduling.TaskImpl.run(SourceFile:3)
	at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.run(SourceFile:93)
Caused by: java.lang.Exception: HTTP request failed. Status: 500
	at org.owntracks.android.net.http.HttpMessageProcessorEndpoint.sendMessage-gIAlu-s(SourceFile:289)
	... 8 more
2026-03-10 09:18:38.171 I [DefaultDispatcher-worker-2] MessageProcessor$resendDelayWait: Waiting for 1s before retrying send
2026-03-10 09:18:39.175 D [DefaultDispatcher-worker-2] MessageProcessor: Retry wait finished after 1.003309218s. Cancelled=false}
2026-03-10 09:18:39.176 D [DefaultDispatcher-worker-2] MessageProcessor: Taken message off queue: [MessageLocation id=[redacted] ts=2026-03-10T02:17:26Z,lat=[redacted],long=[redacted],created_at=2026-03-10T08:18:37.840Z,trigger=PING]
2026-03-10 09:18:39.179 D [DefaultDispatcher-worker-2] HttpMessageProcessorEndpoint: Publishing Message JSON {"_type":"location","BSSID":"[redacted]","SSID":"[redacted]","_id":"[redacted]","acc":9,"alt":[redacted],"batt":100,"bs":2,"cog":308,"conn":"w","created_at":[redacted],"lat":[redacted],"lon":[redacted],"m":1,"source":"fused","t":"p","tid":"49","topic":"owntracks/[redacted]@gmail.com/[redacted]","tst":[redacted],"vac":1,"vel":3}
2026-03-10 09:18:39.237 D [DefaultDispatcher-worker-2] HttpMessageProcessorEndpoint: HTTP response received: Response{protocol=http/1.1, code=500, message=openHAB is offline, url=https://home.myopenhab.org/gpstracker/owntracks}
2026-03-10 09:18:39.237 E [DefaultDispatcher-worker-2] HttpMessageProcessorEndpoint: HTTP request failed. Status: 500
2026-03-10 09:18:39.239 E [DefaultDispatcher-worker-1] MessageProcessor$onMessageDeliveryFailed: Message delivery failed. queueLength: 1, message=[MessageLocation id=[redacted] ts=2026-03-10T02:17:26Z,lat=[redacted],long=[redacted],created_at=2026-03-10T08:18:37.840Z,trigger=PING]
2026-03-10 09:18:39.240 W [DefaultDispatcher-worker-2] MessageProcessor: Error sending message [MessageLocation id=[redacted] ts=2026-03-10T02:17:26Z,lat=[redacted],long=[redacted],created_at=2026-03-10T08:18:37.840Z,trigger=PING]. Re-queueing
org.owntracks.android.net.MessageProcessorEndpoint$OutgoingMessageSendingException: java.lang.Exception: HTTP request failed. Status: 500
	at org.owntracks.android.net.http.HttpMessageProcessorEndpoint.sendMessage-gIAlu-s(SourceFile:369)
	at org.owntracks.android.services.MessageProcessor.sendAvailableMessages(SourceFile:639)
	at org.owntracks.android.services.MessageProcessor.access$sendAvailableMessages(SourceFile:1)
	at org.owntracks.android.services.MessageProcessor$sendAvailableMessages$1.invokeSuspend(Unknown Source:11)
	at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(SourceFile:9)
	at kotlinx.coroutines.DispatchedTask.run(SourceFile:109)
	at androidx.work.Worker$2.run(SourceFile:21)
	at kotlinx.coroutines.scheduling.TaskImpl.run(SourceFile:3)
	at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.run(SourceFile:93)
Caused by: java.lang.Exception: HTTP request failed. Status: 500
	at org.owntracks.android.net.http.HttpMessageProcessorEndpoint.sendMessage-gIAlu-s(SourceFile:289)
	... 8 more

2026-03-10 09:18:39.244 I [DefaultDispatcher-worker-1] MessageProcessor$resendDelayWait: Waiting for 2s before retrying send
2026-03-10 09:18:41.250 D [DefaultDispatcher-worker-1] MessageProcessor: Retry wait finished after 2.004502499s. Cancelled=false}
2026-03-10 09:18:41.257 D [DefaultDispatcher-worker-1] MessageProcessor: Taken message off queue: [MessageLocation id=[redacted] ts=2026-03-10T02:17:26Z,lat=[redacted],long=[redacted],created_at=2026-03-10T08:18:37.840Z,trigger=PING]
2026-03-10 09:18:41.262 D [DefaultDispatcher-worker-1] HttpMessageProcessorEndpoint: Publishing Message JSON {"_type":"location","BSSID":"[redacted]","SSID":"[redacted]","_id":"[redacted]","acc":9,"alt":[redacted],"batt":100,"bs":2,"cog":308,"conn":"w","created_at":1773130717,"lat":[redacted],"lon":[redacted],"m":1,"source":"fused","t":"p","tid":"49","topic":"owntracks/[redacted]@gmail.com/[redacted]","tst":[redacted],"vac":1,"vel":3}
2026-03-10 09:18:41.489 D [DefaultDispatcher-worker-1] HttpMessageProcessorEndpoint: HTTP response received: Response{protocol=http/1.1, code=500, message=openHAB is offline, url=https://home.myopenhab.org/gpstracker/owntracks}
2026-03-10 09:18:41.490 E [DefaultDispatcher-worker-1] HttpMessageProcessorEndpoint: HTTP request failed. Status: 500
2026-03-10 09:18:41.494 E [DefaultDispatcher-worker-5] MessageProcessor$onMessageDeliveryFailed: Message delivery failed. queueLength: 1, message=[MessageLocation id=[redacted] ts=2026-03-10T02:17:26Z,lat=[redacted],long=[redacted],created_at=2026-03-10T08:18:37.840Z,trigger=PING]
2026-03-10 09:18:41.495 W [DefaultDispatcher-worker-1] MessageProcessor: Error sending message [MessageLocation id=[redacted] ts=2026-03-10T02:17:26Z,lat=[redacted],long=[redacted],created_at=2026-03-10T08:18:37.840Z,trigger=PING]. Re-queueing
org.owntracks.android.net.MessageProcessorEndpoint$OutgoingMessageSendingException: java.lang.Exception: HTTP request failed. Status: 500
	at org.owntracks.android.net.http.HttpMessageProcessorEndpoint.sendMessage-gIAlu-s(SourceFile:369)
	at org.owntracks.android.services.MessageProcessor.sendAvailableMessages(SourceFile:639)
	at org.owntracks.android.services.MessageProcessor.access$sendAvailableMessages(SourceFile:1)
	at org.owntracks.android.services.MessageProcessor$sendAvailableMessages$1.invokeSuspend(Unknown Source:11)
	at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(SourceFile:9)
	at kotlinx.coroutines.DispatchedTask.run(SourceFile:109)
	at androidx.work.Worker$2.run(SourceFile:21)
	at kotlinx.coroutines.scheduling.TaskImpl.run(SourceFile:3)
	at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.run(SourceFile:93)
Caused by: java.lang.Exception: HTTP request failed. Status: 500
	at org.owntracks.android.net.http.HttpMessageProcessorEndpoint.sendMessage-gIAlu-s(SourceFile:289)
	... 8 more

Metadata

Metadata

Assignees

No one assigned

    Labels

    No labels
    No labels

    Type

    No type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions