Exceptions since 04 march 2025

helmar74 · March 4, 2025, 2:10pm

Hi,

since today I get a Exception in openhab.log which is related to amazon echo control addon.
openhab.log (126.9 KB)

For me it looks like Amazon changed something on there side?
Anyone an idea how to fix?

sihui · March 4, 2025, 2:17pm

Same here.

2025-03-04 14:29:02.175 [WARN ] [rol.internal.util.HttpRequestBuilder] - Parsing json failed: false
com.google.gson.JsonSyntaxException: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 8 column 1 path $
	at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:397) ~[?:?]
	at com.google.gson.Gson.fromJson(Gson.java:1227) ~[?:?]
	at com.google.gson.Gson.fromJson(Gson.java:1137) ~[?:?]
	at com.google.gson.Gson.fromJson(Gson.java:1047) ~[?:?]
	at org.smarthomej.binding.amazonechocontrol.internal.util.HttpRequestBuilder$Builder.lambda$0(HttpRequestBuilder.java:265) ~[?:?]
	at java.util.concurrent.CompletableFuture$UniApply.tryFire(CompletableFuture.java:646) [?:?]
	at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510) [?:?]
	at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2147) [?:?]
	at org.smarthomej.binding.amazonechocontrol.internal.util.HttpRequestBuilder$HttpResponseListener.onComplete(HttpRequestBuilder.java:364) [bundleFile:?]
	at org.eclipse.jetty.client.ResponseNotifier.notifyComplete(ResponseNotifier.java:218) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.ResponseNotifier.notifyComplete(ResponseNotifier.java:210) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.HttpReceiver.terminateResponse(HttpReceiver.java:481) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.HttpReceiver.terminateResponse(HttpReceiver.java:461) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.HttpReceiver.responseSuccess(HttpReceiver.java:424) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.messageComplete(HttpReceiverOverHTTP.java:374) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.http.HttpParser.parseFields(HttpParser.java:1188) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1560) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.parse(HttpReceiverOverHTTP.java:208) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:148) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:80) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:131) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:172) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.54.v20240208]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.54.v20240208]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 8 column 1 path $
	at com.google.gson.stream.JsonReader.beginObject(JsonReader.java:393) ~[?:?]
	at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:386) ~[?:?]
	... 36 more

Siam · March 4, 2025, 2:21pm

I have exactly the same issue since today

Manni51 · March 4, 2025, 6:00pm

I have same issue.

candiesdoodle · March 5, 2025, 7:48am

Same here. logs are spammed with this error

glhopital · March 5, 2025, 12:59pm

Same here also. A change in the JSON answer provided by the API may have happened.

binderth · March 5, 2025, 1:52pm

I’m on OH 4.3.2-release with the SmarthomeJ-version and there’s no WARN and no ERRORs in my logs since.
on which version are you all? And what region? I’m in Germany and
connected to: https://eu-api-alexa.amazon.com

helmar74 · March 5, 2025, 1:57pm

4.3.3

Siam · March 5, 2025, 2:24pm

4.3.2 and 4.3.3-1 it seems that this error always occurs when alexa received a command to speak and i’m in Germany

helmar74 · March 5, 2025, 2:25pm

Yes it looks so. But speaking of alexa work’s

JensD · March 5, 2025, 4:16pm

Happens to me when sending text commands to the Echo, e.g. “Play my favorite songs”

Daad66 · March 5, 2025, 9:34pm

Same error on my 4.3.3 version also in Germany …
right before I can see:

2025-03-05 22:19:18.397 [WARN ] [core.thing.internal.ThingManagerImpl] - Failed to normalize configuration for thing 'amazonechocontrol:echo:**************:**************': {thing/channel=Type description amazonechocontrol:lastSpokenText for amazonechocontrol:echo:**************:****************:lastSpokenText not found, although we checked the presence before.}**
2025-03-05 22:19:22.932 [WARN ] [core.thing.internal.ThingManagerImpl] - Failed to normalize configuration for thing 'amazonechocontrol:echo:**************:**************': {thing/channel=Type description amazonechocontrol:lastSpokenText for amazonechocontrol:echo:**************:**************:lastSpokenText not found, although we checked the presence before.}
2025-03-05 22:19:27.481 [WARN ] [mazonechocontrol.internal.Connection] - Parsing json failed:






<!doctype html><html class="a-no-js a-touch a-mobile" data-19ax5a9jf="mongoose">
  <head>
<script type='text/javascript'>var ue_t0=ue_t0||+new Date();</script>
<script type='text/javascript'>
window.ue_ihb = (window.ue_ihb || window.ueinit || 0) + 1;
if (window.ue_ihb === 1) {

[...]

<noscript>
    <img height="1" width="1" style='display:none;visibility:hidden;' src='//fls-eu.amazon.com/1/batch/1/OP/A1PA6795UKMFR9:261-6472194-4270649:ZFRBEW6EFRP0DN0H4KEQ$uedata=s:%2Fap%2Fuedata%3Fnoscript%26id%3DZFRBEW6EFRP0DN0H4KEQ:0' alt=""/>
</noscript>

<script>window.ue && ue.count && ue.count('CSMLibrarySize', 49430)</script></body>
</html>

com.google.gson.JsonSyntaxException: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 8 column 1 path $
        at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:397) ~[?:?]
        at com.google.gson.Gson.fromJson(Gson.java:1227) ~[?:?]
        at com.google.gson.Gson.fromJson(Gson.java:1137) ~[?:?]
        at com.google.gson.Gson.fromJson(Gson.java:1047) ~[?:?]
        at com.google.gson.Gson.fromJson(Gson.java:982) ~[?:?]
        at org.openhab.binding.amazonechocontrol.internal.Connection.parseJson(Connection.java:981) ~[?:?]
        at org.openhab.binding.amazonechocontrol.internal.Connection.getPlayer(Connection.java:1115) ~[?:?]
        at org.openhab.binding.amazonechocontrol.internal.handler.EchoHandler.updateState(EchoHandler.java:843) ~[?:?]
        at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.refreshData(AccountHandler.java:571) ~[?:?]
        at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.checkData(AccountHandler.java:455) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 8 column 1 path $
        at com.google.gson.stream.JsonReader.beginObject(JsonReader.java:393) ~[?:?]
        at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:386) ~[?:?]
        ... 15 more
2

m4rk · March 6, 2025, 4:15pm

Same for me.
Speaking is working but log fills up with errors.

Disabling the account thing and the errors stop coming.
Enabling the thing and errors start again 10secs later.

I use amazon.de.

mitch-geht-ab · March 7, 2025, 10:00am

@ML1982

opened a bug report on github

github.com/openhab/openhab-addons

[AmazonEchoControl] a lot of Exceptions since 04 march 2025 in openhab log

opened 06:04AM - 07 Mar 25 UTC

ML19821

bug

I'm not sure when it started, but everything seems to be running fine. However, …my OpenHAB log is filled with errors like this: There are two related community threads with many examples: [Amazon Binding: A Lot of Errors in Log File](https://community.openhab.org/t/amazon-binding-a-lot-of-errors-in-log-file/162922) [Exceptions Since 04 March 2025](https://community.openhab.org/t/exceptions-since-04-march-2025/162879) Here's my example. The full log is longer, but I’m unsure if it contains credentials, so I’m sharing only the beginning: [log.txt](https://github.com/user-attachments/files/19121143/log.txt)

m4rk · March 13, 2025, 9:30am

Is anyone also having amazon OTP issues? (.de)

I have noticed several OTP numbers coming to my phone that I didn’t initiate. They seem to come roughly every 24hrs for a period and then stop.

Also, I noticed that when I try to change my amazon password the OTP does not arrive at my phone by text . Requesting a voice call with the OTP does work.

I wondered if the binding could be causing these issues and the text OTP gets blocked for 24hrs?

Despite this the binding is working although it is filling my logs with many errors, making working on OH difficult.

I have now disabled the binding and will monitor the OTP issue.

Update… When I did manage to log back in on my Amazon account and tried to change amazon password I received an error message >>>

We’re sorry!
An error occurred when we tried to process your request. Rest assured, we’re already working on the problem and expect to resolve it shortly.

Coincidence ?

TRIROG · March 14, 2025, 10:26am

Same issues - filling up of LOGFILE running OH 2.5 with 2.5.12 v of the binding! please also consider fix for older version

hmerk · March 14, 2025, 11:20am

With openHAB 5 on the horizon, it is unlikely the there will be any fixes for openHAB 2.x and openHAB 3.x.

Harald_Pott · March 14, 2025, 12:16pm

I have this error, too. Multiple OTP messages plus errors in the openhab.log. I am on openHAB 4.3.3.

m4rk · March 15, 2025, 6:37am

Ok. So not just me. The OTP sent to the phone will worry people that their amazon account password was hacked, especially when further OTP dont arrive. Eg trying to reset password

The issue needs fixing faster than normal?

The OTP messages stopped after I disabled the binding and eventually, after some time, I began to receive them again through my own normal account actions.

Ps. I set up the passkey option and opted for a voice call with the OTP to get around the need for a text OTP.

FROeHlyEisvogel · March 16, 2025, 7:14am

Same problem here with warnings filling the log and OTP codes send to my phone.