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>