Problem with Homematic and HM-ES-TX-WM

@gerrieg

If have just moved to the latest snapshot and i am still in the process to migrate alle my thing. Today i tried to include the above metioned thing into openhab, but i get an error in the log

2017-07-22 19:21:13.950 [ERROR] [ematic.handler.HomematicThingHandler] - A blank string is not a valid number
java.lang.NumberFormatException: A blank string is not a valid number
        at org.apache.commons.lang.math.NumberUtils.createNumber(NumberUtils.java:501)[29:org.apache.commons.lang:2.6.0]
        at org.openhab.binding.homematic.internal.communicator.parser.CommonRpcParser.toNumber(CommonRpcParser.java:56)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.parser.CommonRpcParser.convertToType(CommonRpcParser.java:120)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.parser.GetParamsetParser.parse(GetParamsetParser.java:48)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.client.RpcClient.setChannelDatapointValues(RpcClient.java:164)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.setChannelDatapointValues(AbstractHomematicGateway.java:508)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.loadChannelValues(AbstractHomematicGateway.java:489)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.handler.HomematicThingHandler.loadHomematicChannelValues(HomematicThingHandler.java:264)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.handler.HomematicThingHandler.updateChannelState(HomematicThingHandler.java:247)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.handler.HomematicThingHandler.updateDatapointState(HomematicThingHandler.java:228)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.handler.HomematicBridgeHandler.onStateUpdated(HomematicBridgeHandler.java:243)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.eventReceived(AbstractHomematicGateway.java:656)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleEvent(RpcResponseHandler.java:91)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleMethodCall(RpcResponseHandler.java:46)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleMethodCall(RpcResponseHandler.java:62)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.server.BinRpcResponseHandler.run(BinRpcResponseHandler.java:46)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
        at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]

This error after all tries must be from the HM-ES-TX-WM and there the power channel with an IR sensor for electricity. I am running homegear latest nightly and an uptodate firmware.

Is there any advice how to track down this error?

Thanks
Thomas

Hi Thomas!

I have this device too, but it is connected to a CCU2 and it works fine. So maybe a Homegear issue. To find out, switch to TRACE mode and post the event causing the error: http://docs.openhab.org/addons/bindings/homematic/readme.html#debugging-and-tracing

BR
Gerhard

Hi Gerhard,

just created the trace.

20:48:00.279 [TRACE] [nicator.server.BinRpcResponseHandler] - Event BinRpcMessage: system.multicall()
[
        {
                methodName=event
                params=
                [
                        RF-895298B3-A5AA-26E6-54D1-4339B9E56827
                        MEQ0381486:1
                        BOOT
                        false
                ]
        }
        {
                methodName=event
                params=
                [
                        RF-895298B3-A5AA-26E6-54D1-4339B9E56827
                        MEQ0381486:1
                        ENERGY_COUNTER
                        0.0
                ]
        }
        {
                methodName=event
                params=
                [
                        RF-895298B3-A5AA-26E6-54D1-4339B9E56827
                        MEQ0381486:1
                        POWER
                        0.0
                ]
        }
]

20:48:00.282 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Boolean) value 'false' for 'MEQ0381486:1#BOOT' from gateway with id '895298B3-A5AA-26E6-54D1-4339B9E56827'
20:48:00.285 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value '0.0' for 'MEQ0381486:1#ENERGY_COUNTER' from gateway with id '895298B3-A5AA-26E6-54D1-4339B9E56827'
20:48:00.288 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value '0.0' for 'MEQ0381486:1#POWER' from gateway with id '895298B3-A5AA-26E6-54D1-4339B9E56827'
20:48:00.291 [DEBUG] [ommunicator.AbstractHomematicGateway] - Loading values for channel HmChannel[number=1,type=POWERMETER_IEC1,initialized=false] of device 'MEQ0381486'
20:48:00.293 [TRACE] [nal.communicator.client.BinRpcClient] - Client BinRpcRequest:
getParamset()
MEQ0381486:1
MASTER

20:48:00.295 [TRACE] [al.communicator.client.SocketHandler] - Returning socket for port 2001
20:48:00.302 [TRACE] [nal.communicator.client.BinRpcClient] - Client BinRpcResponse:
{
        AES_ACTIVE=false
        ENERGY_COUNTER_STRING=1-0:1.8
        METER_CONSTANT_GAS=0.01
        METER_CONSTANT_IR=100
        METER_CONSTANT_LED=10000
        METER_SENSIBILITY_IR=-198
        METER_TYPE=1
        POWER_STRING=1-0:1.7
        TX_THRESHOLD_POWER=0.0
}

20:48:00.305 [TRACE] [nal.communicator.client.BinRpcClient] - Client BinRpcRequest:
getParamset()
MEQ0381486:1
VALUES

20:48:00.306 [TRACE] [al.communicator.client.SocketHandler] - Returning socket for port 2001
20:48:00.313 [TRACE] [nal.communicator.client.BinRpcClient] - Client BinRpcResponse:
{
        BOOT=false
        ENERGY_COUNTER=0.0
        GAS_ENERGY_COUNTER=0.0
        GAS_POWER=0.0
        IEC_ENERGY_COUNTER=
        IEC_POWER=0.0
        POWER=0.0
}

20:48:00.316 [ERROR] [ematic.handler.HomematicThingHandler] - A blank string is not a valid number
java.lang.NumberFormatException: A blank string is not a valid number
        at org.apache.commons.lang.math.NumberUtils.createNumber(NumberUtils.java:501)[29:org.apache.commons.lang:2.6.0]
        at org.openhab.binding.homematic.internal.communicator.parser.CommonRpcParser.toNumber(CommonRpcParser.java:56)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.parser.CommonRpcParser.convertToType(CommonRpcParser.java:120)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.parser.GetParamsetParser.parse(GetParamsetParser.java:48)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.client.RpcClient.setChannelDatapointValues(RpcClient.java:164)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.setChannelDatapointValues(AbstractHomematicGateway.java:508)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.loadChannelValues(AbstractHomematicGateway.java:489)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.handler.HomematicThingHandler.loadHomematicChannelValues(HomematicThingHandler.java:264)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.handler.HomematicThingHandler.updateChannelState(HomematicThingHandler.java:247)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.handler.HomematicThingHandler.updateDatapointState(HomematicThingHandler.java:228)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.handler.HomematicBridgeHandler.onStateUpdated(HomematicBridgeHandler.java:243)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.eventReceived(AbstractHomematicGateway.java:656)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleEvent(RpcResponseHandler.java:91)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleMethodCall(RpcResponseHandler.java:46)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleMethodCall(RpcResponseHandler.java:62)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at org.openhab.binding.homematic.internal.communicator.server.BinRpcResponseHandler.run(BinRpcResponseHandler.java:46)[187:org.openhab.binding.homematic:2.2.0.201707190537]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
        at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]

In parallel i have postet in the homegear forum.

I did not see why there is an error

Thomas

The problem is

IEC_ENERGY_COUNTER=

Homegear should send here a value. But anyway, i will implement a fix in OH too. Thanks for the info!

fixed with: https://github.com/openhab/openhab2-addons/pull/2419

1 Like

Hi Gerhard, Hi all!

After some update I encountered mostly the same thing. My Homematic energy meter measures well inside CCU2 (with HomeMatic on a Raspi) but the data is not longer transfered to openhab2.
I upgraded to openhab 2.3 without any progress in this matter. Currently i have no idea how to continue.
Attached my log to show the problems.
Do you have any ideas?
Thanks in advance, Kay

@gerrieg: Any hints?

2018-06-11 23:10:15.536 [TRACE] [nal.communicator.server.XmlRpcServer] - Server parsed XmlRpcMessage:
system.multicall()
[
	{
		methodName=event
		params=
		[
			RF-c1e6902b
			NEQ0863610:1
			BOOT
			false
		]
	}
]

2018-06-11 23:10:15.642 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Boolean) value 'false' for 'NEQ0863610:1#BOOT' from gateway with id 'c1e6902b'
2018-06-11 23:10:15.660 [TRACE] [nal.communicator.server.XmlRpcServer] - Server XmlRpcResponse:
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodResponse><params><param><value><array><data><value>event</value></data></array></value></param></params></methodResponse>
2018-06-11 23:10:15.690 [TRACE] [nal.communicator.server.XmlRpcServer] - Server parsed XmlRpcMessage:
system.multicall()
[
	{
		methodName=event
		params=
		[
			RF-c1e6902b
			NEQ0863610:1
			ENERGY_COUNTER
			4599.9
		]
	}
	{
		methodName=event
		params=
		[
			RF-c1e6902b
			NEQ0863610:1
			POWER
			335.0
		]
	}
]

2018-06-11 23:10:15.728 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value '4599.9' for 'NEQ0863610:1#ENERGY_COUNTER' from gateway with id 'c1e6902b'
2018-06-11 23:10:15.748 [DEBUG] [ommunicator.AbstractHomematicGateway] - Loading values for channel HmChannel[number=1,type=POWERMETER_IEC1,initialized=false] of device 'NEQ0863610'
2018-06-11 23:10:15.768 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getParamset</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>MASTER</value></param></params></methodCall>
2018-06-11 23:10:15.807 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><params><param>
	<value><struct><member><name>AES_ACTIVE</name><value><boolean>0</boolean></value></member><member><name>ENERGY_COUNTER_STRING</name><value></value></member><member><name>METER_CONSTANT_GAS</name><value><double>0.010000</double></value></member><member><name>METER_CONSTANT_IR</name><value><i4>75</i4></value></member><member><name>METER_CONSTANT_LED</name><value><i4>10000</i4></value></member><member><name>METER_SENSIBILITY_IR</name><value><i4>20</i4></value></member><member><name>METER_TYPE</name><value><i4>1</i4></value></member><member><name>POWER_STRING</name><value></value></member><member><name>TX_THRESHOLD_POWER</name><value><double>0.000000</double></value></member></struct></value>
</param></params></methodResponse>

2018-06-11 23:10:15.905 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getParamset</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>VALUES</value></param></params></methodCall>
2018-06-11 23:10:15.941 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><fault>
	<value><struct><member><name>faultCode</name><value><i4>-1</i4></value></member><member><name>faultString</name><value>Failure</value></member></struct></value>
</fault></methodResponse>

2018-06-11 23:10:15.985 [DEBUG] [ternal.communicator.client.RpcClient] - RpcResponse unknown RPC failure (-1 Failure), fetching values with another API method for device: NEQ0863610, channel: 1, paramset: VALUES
2018-06-11 23:10:16.008 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>BOOT</value></param></params></methodCall>
2018-06-11 23:10:16.044 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><params><param>
	<value><boolean>0</boolean></value>
</param></params></methodResponse>

2018-06-11 23:10:16.079 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>POWER</value></param></params></methodCall>
2018-06-11 23:10:16.115 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><params><param>
	<value><double>335.000000</double></value>
</param></params></methodResponse>

2018-06-11 23:10:16.151 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>IEC_POWER</value></param></params></methodCall>
2018-06-11 23:10:16.189 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><fault>
	<value><struct><member><name>faultCode</name><value><i4>-1</i4></value></member><member><name>faultString</name><value>Failure</value></member></struct></value>
</fault></methodResponse>

2018-06-11 23:10:16.233 [ERROR] [ematic.handler.HomematicThingHandler] - -1 Failure (sending <?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>IEC_POWER</value></param></params></methodCall>)
org.openhab.binding.homematic.internal.communicator.client.UnknownRpcFailureException: -1 Failure (sending <?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>IEC_POWER</value></param></params></methodCall>)
	at org.openhab.binding.homematic.internal.communicator.parser.RpcResponseParser.parse(RpcResponseParser.java:43) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.sendMessage(XmlRpcClient.java:89) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.sendMessage(XmlRpcClient.java:63) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.RpcClient.setChannelDatapointValues(RpcClient.java:211) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.RpcClient.setChannelDatapointValues(RpcClient.java:193) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.setChannelDatapointValues(AbstractHomematicGateway.java:508) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.loadChannelValues(AbstractHomematicGateway.java:479) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.handler.HomematicThingHandler.loadHomematicChannelValues(HomematicThingHandler.java:371) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.handler.HomematicThingHandler.updateChannelState(HomematicThingHandler.java:350) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.handler.HomematicThingHandler.updateDatapointState(HomematicThingHandler.java:327) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.handler.HomematicBridgeHandler.onStateUpdated(HomematicBridgeHandler.java:265) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.lambda$1(AbstractHomematicGateway.java:727) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.misc.DelayedExecuter.start(DelayedExecuter.java:61) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.eventReceived(AbstractHomematicGateway.java:724) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleEvent(RpcResponseHandler.java:94) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleMethodCall(RpcResponseHandler.java:47) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleMethodCall(RpcResponseHandler.java:64) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.server.XmlRpcServer$ResponseHandler.handle(XmlRpcServer.java:119) [203:org.openhab.binding.homematic:2.3.0]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [84:org.eclipse.jetty.server:9.3.21.v20170918]
	at org.eclipse.jetty.server.Server.handle(Server.java:534) [84:org.eclipse.jetty.server:9.3.21.v20170918]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:333) [84:org.eclipse.jetty.server:9.3.21.v20170918]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [84:org.eclipse.jetty.server:9.3.21.v20170918]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [76:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [76:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [76:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2018-06-11 23:10:16.732 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value '335.0' for 'NEQ0863610:1#POWER' from gateway with id 'c1e6902b'
2018-06-11 23:10:16.751 [DEBUG] [ommunicator.AbstractHomematicGateway] - Loading values for channel HmChannel[number=1,type=POWERMETER_IEC1,initialized=false] of device 'NEQ0863610'
2018-06-11 23:10:16.772 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getParamset</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>MASTER</value></param></params></methodCall>
2018-06-11 23:10:16.835 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><params><param>
	<value><struct><member><name>AES_ACTIVE</name><value><boolean>0</boolean></value></member><member><name>ENERGY_COUNTER_STRING</name><value></value></member><member><name>METER_CONSTANT_GAS</name><value><double>0.010000</double></value></member><member><name>METER_CONSTANT_IR</name><value><i4>75</i4></value></member><member><name>METER_CONSTANT_LED</name><value><i4>10000</i4></value></member><member><name>METER_SENSIBILITY_IR</name><value><i4>20</i4></value></member><member><name>METER_TYPE</name><value><i4>1</i4></value></member><member><name>POWER_STRING</name><value></value></member><member><name>TX_THRESHOLD_POWER</name><value><double>0.000000</double></value></member></struct></value>
</param></params></methodResponse>

2018-06-11 23:10:16.929 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getParamset</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>VALUES</value></param></params></methodCall>
2018-06-11 23:10:16.968 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><fault>
	<value><struct><member><name>faultCode</name><value><i4>-1</i4></value></member><member><name>faultString</name><value>Failure</value></member></struct></value>
</fault></methodResponse>

2018-06-11 23:10:17.013 [DEBUG] [ternal.communicator.client.RpcClient] - RpcResponse unknown RPC failure (-1 Failure), fetching values with another API method for device: NEQ0863610, channel: 1, paramset: VALUES
2018-06-11 23:10:17.037 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>BOOT</value></param></params></methodCall>
2018-06-11 23:10:17.074 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><params><param>
	<value><boolean>0</boolean></value>
</param></params></methodResponse>

2018-06-11 23:10:17.111 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>POWER</value></param></params></methodCall>
2018-06-11 23:10:17.149 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><params><param>
	<value><double>335.000000</double></value>
</param></params></methodResponse>

2018-06-11 23:10:17.184 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>IEC_POWER</value></param></params></methodCall>
2018-06-11 23:10:17.222 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><fault>
	<value><struct><member><name>faultCode</name><value><i4>-1</i4></value></member><member><name>faultString</name><value>Failure</value></member></struct></value>
</fault></methodResponse>

2018-06-11 23:10:17.266 [ERROR] [ematic.handler.HomematicThingHandler] - -1 Failure (sending <?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>IEC_POWER</value></param></params></methodCall>)
org.openhab.binding.homematic.internal.communicator.client.UnknownRpcFailureException: -1 Failure (sending <?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>getValue</methodName>
<params><param><value>NEQ0863610:1</value></param><param><value>IEC_POWER</value></param></params></methodCall>)
	at org.openhab.binding.homematic.internal.communicator.parser.RpcResponseParser.parse(RpcResponseParser.java:43) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.sendMessage(XmlRpcClient.java:89) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.sendMessage(XmlRpcClient.java:63) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.RpcClient.setChannelDatapointValues(RpcClient.java:211) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.RpcClient.setChannelDatapointValues(RpcClient.java:193) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.setChannelDatapointValues(AbstractHomematicGateway.java:508) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.loadChannelValues(AbstractHomematicGateway.java:479) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.handler.HomematicThingHandler.loadHomematicChannelValues(HomematicThingHandler.java:371) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.handler.HomematicThingHandler.updateChannelState(HomematicThingHandler.java:350) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.handler.HomematicThingHandler.updateDatapointState(HomematicThingHandler.java:327) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.handler.HomematicBridgeHandler.onStateUpdated(HomematicBridgeHandler.java:265) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.lambda$1(AbstractHomematicGateway.java:727) ~[203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.misc.DelayedExecuter.start(DelayedExecuter.java:61) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.eventReceived(AbstractHomematicGateway.java:724) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleEvent(RpcResponseHandler.java:94) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleMethodCall(RpcResponseHandler.java:47) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.server.RpcResponseHandler.handleMethodCall(RpcResponseHandler.java:64) [203:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.server.XmlRpcServer$ResponseHandler.handle(XmlRpcServer.java:119) [203:org.openhab.binding.homematic:2.3.0]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [84:org.eclipse.jetty.server:9.3.21.v20170918]
	at org.eclipse.jetty.server.Server.handle(Server.java:534) [84:org.eclipse.jetty.server:9.3.21.v20170918]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:333) [84:org.eclipse.jetty.server:9.3.21.v20170918]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [84:org.eclipse.jetty.server:9.3.21.v20170918]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [76:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [76:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [76:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [87:org.eclipse.jetty.util:9.3.21.v20170918]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2018-06-11 23:10:17.747 [TRACE] [nal.communicator.server.XmlRpcServer] - Server XmlRpcResponse:
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodResponse><params><param><value><array><data><value>event</value></data></array></value></param></params></methodResponse>
2018-06-11 23:10:20.770 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcRequest (port 2001):
<?xml version="1.0" encoding="ISO-8859-1"?>
<methodCall><methodName>ping</methodName>
<params><param><value>c1e6902b</value></param></params></methodCall>
2018-06-11 23:10:20.820 [TRACE] [nal.communicator.client.XmlRpcClient] - Client XmlRpcResponse (port 2001):
<?xml version="1.0" encoding="iso-8859-1"?>
<methodResponse><params><param>
	<value><array><data><value><boolean>1</boolean></value></data></array></value>
</param></params></methodResponse>

Can you please post the output of http://OPENHAB_SERVER:OPENHAB_PORT/rest/things/homematic%3AHM-ES-TX-WM%3ARF-c1e6902b%3ANEQ0863610

Hi Gerald, many thanks for your reply.

If I request as mentioned the thing is not found.
Output:

{"error":{"message":"Thing homematic:HM-ES-TX-WM:RF-c1e6902b:NEQ0863610 does not exist!","http-code":404}}

I got confused.

Maybe this is not relevant, but I modified the request a little bit to be according to my naming. I just removed this “RF-” prefix before the name of my bridge. Then I get the following output:

{"statusInfo":{"status":"OFFLINE","statusDetail":"COMMUNICATION_ERROR","description":"-1 Failure (sending \u003c?xml version\u003d\"1.0\" encoding\u003d\"ISO-8859-1\"?\u003e\n\u003cmethodCall\u003e\u003cmethodName\u003egetValue\u003c/methodName\u003e\n\u003cparams\u003e\u003cparam\u003e\u003cvalue\u003eNEQ0863610:1\u003c/value\u003e\u003c/param\u003e\u003cparam\u003e\u003cvalue\u003eIEC_POWER\u003c/value\u003e\u003c/param\u003e\u003c/params\u003e\u003c/methodCall\u003e)"},"editable":true,"label":"Stromzaehler","bridgeUID":"homematic:bridge:c1e6902b","configuration":{"HMP_1_ENERGY_COUNTER_STRING":"","HMP_2_POWER_STRING":"","HMP_1_AES_ACTIVE":false,"HMP_1_METER_CONSTANT_GAS":0.01,"HMP_1_POWER_STRING":"","HMP_2_TX_THRESHOLD_POWER":100.0,"HMP_2_AES_ACTIVE":false,"HMP_1_METER_SENSIBILITY_IR":0,"HMP_1_TX_THRESHOLD_POWER":100.0,"HMP_1_METER_TYPE":"IR-SENSOR","HMP_1_METER_CONSTANT_IR":100,"HMP_2_ENERGY_COUNTER_STRING":"","HMP_1_METER_CONSTANT_LED":10000,"HMP_2_METER_TYPE":"4"},"properties":{"serialNumber":"NEQ0863610","modelId":"HM-ES-TX-WM","vendor":"eQ-3 AG","aesKey":"0","firmwareVersion":"1.2","batteryType":"4x AA/Mignon/LR06"},"UID":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610","thingTypeUID":"homematic:HM-ES-TX-WM","channels":[{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#CONFIG_PENDING","id":"0#CONFIG_PENDING","channelTypeUID":"homematic:HM-ES-TX-WM_0_CONFIG_PENDING","itemType":"Switch","kind":"STATE","label":"Config Pending","description":"Zeigt eine ausstehende Konfigurations Aktualisierung an","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#RSSI_DEVICE","id":"0#RSSI_DEVICE","channelTypeUID":"homematic:HM-ES-TX-WM_0_RSSI_DEVICE","itemType":"Number","kind":"STATE","label":"Rssi Device","description":"Empfangsstärke","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#DELETE_DEVICE_MODE","id":"0#DELETE_DEVICE_MODE","channelTypeUID":"homematic:HM-ES-TX-WM_0_DELETE_DEVICE_MODE","itemType":"String","kind":"STATE","label":"Delete Device Mode","description":"Löschmodus","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#DEVICE_IN_BOOTLOADER","id":"0#DEVICE_IN_BOOTLOADER","channelTypeUID":"homematic:HM-ES-TX-WM_0_DEVICE_IN_BOOTLOADER","itemType":"Switch","kind":"STATE","label":"Device In Bootloader","description":"Gerät im bootloader","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#UPDATE_PENDING","id":"0#UPDATE_PENDING","channelTypeUID":"homematic:HM-ES-TX-WM_0_UPDATE_PENDING","itemType":"Switch","kind":"STATE","label":"Update Pending","description":"Zeigt eine ausstehende Aktualisierung an","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#FIRMWARE","id":"0#FIRMWARE","channelTypeUID":"homematic:HM-ES-TX-WM_0_FIRMWARE","itemType":"String","kind":"STATE","label":"Firmware","description":"Version der Firmware","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#RSSI_PEER","id":"0#RSSI_PEER","channelTypeUID":"homematic:HM-ES-TX-WM_0_RSSI_PEER","itemType":"Number","kind":"STATE","label":"Rssi Peer","description":"Signalstärke Peer","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#BATTERY_TYPE","id":"0#BATTERY_TYPE","channelTypeUID":"homematic:HM-ES-TX-WM_0_BATTERY_TYPE","itemType":"String","kind":"STATE","label":"Battery Type","description":"Typ der Batterie","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#UNREACH","id":"0#UNREACH","channelTypeUID":"homematic:HM-ES-TX-WM_0_UNREACH","itemType":"Switch","kind":"STATE","label":"Unreach","description":"Gerätekommunikationsstatus","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":["StromzaehlerSignalStaerke"],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#SIGNAL_STRENGTH","id":"0#SIGNAL_STRENGTH","channelTypeUID":"system:signal-strength","itemType":"Number","kind":"STATE","label":"Signalstärke","defaultTags":[],"properties":{},"configuration":{}},{"linkedItems":["StromzaehlerBatterieLeer"],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#LOWBAT","id":"0#LOWBAT","channelTypeUID":"system:low-battery","itemType":"Switch","kind":"STATE","label":"Niedriger Batteriestatus","defaultTags":[],"properties":{},"configuration":{}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#RSSI","id":"0#RSSI","channelTypeUID":"homematic:HM-ES-TX-WM_0_RSSI","itemType":"Number","kind":"STATE","label":"Rssi","description":"Vereinheitlichte Empfangsstärke","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#DELETE_DEVICE","id":"0#DELETE_DEVICE","channelTypeUID":"homematic:HM-ES-TX-WM_0_DELETE_DEVICE","itemType":"Switch","kind":"STATE","label":"Delete Device","description":"Gerät löschen","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:0#STICKY_UNREACH","id":"0#STICKY_UNREACH","channelTypeUID":"homematic:HM-ES-TX-WM_0_STICKY_UNREACH","itemType":"Switch","kind":"STATE","label":"Sticky Unreach","description":"Gerätekommunikationsstatus","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:1#BOOT","id":"1#BOOT","channelTypeUID":"homematic:HM-ES-TX-WM_1_BOOT","itemType":"Switch","kind":"STATE","label":"Boot","description":"Boot","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":["StromVerbrauchAktuell","Stromzaehler_1_Power"],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:1#POWER","id":"1#POWER","channelTypeUID":"homematic:HM-ES-TX-WM_1_POWER","itemType":"Number","kind":"STATE","label":"Power","description":"Leistung","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:1#GAS_ENERGY_COUNTER","id":"1#GAS_ENERGY_COUNTER","channelTypeUID":"homematic:HM-ES-TX-WM_1_GAS_ENERGY_COUNTER","itemType":"Number","kind":"STATE","label":"Gas Energy Counter","description":"Energie Gas-Zähler Gerät","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":[],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:1#GAS_POWER","id":"1#GAS_POWER","channelTypeUID":"homematic:HM-ES-TX-WM_1_GAS_POWER","itemType":"Number","kind":"STATE","label":"Gas Power","description":"Gas Verbrauch","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}},{"linkedItems":["StromVerbrauchGesamt","Stromzaehler_1_EnergyCounter"],"uid":"homematic:HM-ES-TX-WM:c1e6902b:NEQ0863610:1#ENERGY_COUNTER","id":"1#ENERGY_COUNTER","channelTypeUID":"homematic:HM-ES-TX-WM_1_ENERGY_COUNTER","itemType":"Number","kind":"STATE","label":"Energy Counter","description":"Energie-Zähler Gerät","defaultTags":[],"properties":{},"configuration":{"receiveDelay":0.0,"delay":0.0}}],"location":"Waschhaus"}

This is looking maybe more like the output you’ve expected.

Kind regards Kay.

PS: This problem occured under OH2.2. After a reboot of the pi which drives OH and some other services like influx and mariadb because of some hangs (after file system was full) most of the “things” added formerly with paperui disappeared. To repair this damage I added these things now with some things files.
Behind of the brigde (even a raspi) many other things and items are working now as in the past - except this energy meter. Because of some other problems in OH2.2 with the also used milight binding I upgraded to OH2.3 - with no difference for this problem.

The only difference is the firmware version. You have 1.2 and i 1.1. I updated to 1.2 now and restarted openhab. No problem, OH can read the values and updates are also received. So i have no idea why you get this error.

You can try to remove the device from the CCU with a factory reset and add it again. Maybe this will help.

Hi Gerhard, many thanks for the information.
The approach to remove and add again I already tried two days ago.
Today I took a new pi (randomly leftover) installed all from scratch on a clean image and now the “Thing” is looking as expected and the communication is working (at least for the energy counter).

So your answer helped a lot to guide me to right way. Many thanks.
Is there a way to “clean” my openhab config? I can remove the things and items and services but maybe to problem is sitting somewhere in the paperui discovered and configured items. Currently its hard to estimate the effort to migrate all the settings from the old damaged OH to new one…

Kind regards
Kay

You can try to clean these directories in /var/lib/openhab2:
cache
jsondb
tmp