Partial no updates from homematic binding


(Alexander) #1

Hello,

i am playing around with the homematic binding the last days and got a problem with it dont really understand.

i am using two things with it right now, a radiator valve and a door sensor. if a value of the radiator valve changes, it gets updated in openhab. if the door sensor changes its state, there is no update to openhab - i have to “reload from gateway” to get the new values.

in the log i got no errors, so i even integrated homematic in an test-installation of iobroker - there the state of the sensor changes immidiatly, so it should be a problem on openhab.

do you have ideas to solve this?

thanks, alex


(Martin Herbst) #2

Hi Alexander,

I never had any problems with the Homematic door sensors that I am using. But my openHAB production environment is quite old.

Can you provide us with some additional information:

  • Which openHAB version are you using?
  • There are different types of HomeMatic door senses. Which one are you using?
  • Are there any messages in openhab.log resp. events.log when you open/close a door?

(Alexander) #3

Hi,

I am using 2.4M3.

The Sensor is HmIP-SRH and there are no entries in the log-files. Its just like the state didnt change.


(Hans-Jörg Merk) #4

I am running 2.4M3 with homematic binding and homegear on the same machine. In that constellation, my MAX! contacts attached to a reflashed Cube (now a Cun) report any changes and I can see those immediately in openHAB.

Please give a bit more precise description on your installation.


(Alexander) #5

System is Running in a Debian Linux and Homematic ins CCU3.

The HM-CC-RT-DN is working Fine and all values get an Update on change.


(Hans-Jörg Merk) #6

I guess you should open an issue at


as the homematic binding is an ESH binding.
Out of the docs I did not find any hint on supporting CCU3…


(Martin Herbst) #7

I don’t think that the problem is caused by CCU3 I am running a system that is quite similar to the CCU3 hardware (the new RPI-RF-MOD on Raspi).
I remember that there was a problem with the HMIP devices. https://github.com/eclipse/smarthome/issues/6053 . I am not sure whether the solution of this issue is not contained in M3 or not.


(Alexander) #8

maybe its similar, but in my case all things are correctly online and i also get the values - but for this one device just if i catch them from the ccu.

do you have an advise for me?

ps: just changed from snapshot to m3 because i hat even troubles at the snapshot-release with homematic.


(Martin Herbst) #9

In this case I would recommend to create an issue for this problem. Maybe the last fix for the IP devices was not sufficient.
It would probably help if you active the debug log and then attach its output to the issue.
I am also using some door contacts, but no IP contacts and they are working correctly. Therefore I assume that there is a problem with the IP devices.


(Alexander) #10

thanks you.

of course, i already checked the log - even at level debug, but theres nothing. only entry i get is:

[ommunicator.AbstractHomematicGateway] - Received new (String) value 'ccu3' for 'CENTRAL:0#PONG' from gateway with id 'ccu3'

(Hans-Jörg Merk) #11

You could set log level to TRACE, which might give some more relevant information


(Alexander) #12

even did it - but nothing about the door sensor:

==> /var/log/openhab2/openhab.log <==

2018-09-16 20:59:13.736 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71'

2018-09-16 20:59:13.736 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.core.internal.items.ItemUpdater@15bdf866'

2018-09-16 20:59:13.736 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling invocation of 'receive()' on 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71' for asynchronous execution

==> /var/log/openhab2/events.log <==

2018-09-16 20:59:13.736 [vent.ItemStateChangedEvent] - ZWaveSerialController_StartFrames changed from 504 to 505

==> /var/log/openhab2/openhab.log <==

2018-09-16 20:59:13.736 [TRACE] [.internal.common.SafeCallManagerImpl] - Submitted invocation of 'receive()' on 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71' for asynchronous execution

2018-09-16 20:59:13.736 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling timeout watcher in 5000ms

2018-09-16 20:59:13.737 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71'

2018-09-16 20:59:13.737 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.eclipse.smarthome.io.monitor.internal.EventLogger@23a03161'

2018-09-16 20:59:13.737 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71'

2018-09-16 20:59:13.737 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.io.monitor.internal.EventLogger@23a03161'

2018-09-16 20:59:13.842 [DEBUG] [i.internal.protocol.KodiClientSocket] - send message: {"jsonrpc":"2.0","id":286,"method":"Application.GetProperties","params":{"properties":["version","name"]}}

2018-09-16 20:59:13.845 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"id":286,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":6,"revision":"20171115-d69a54a","tag":"stable"}}}

2018-09-16 20:59:13.846 [DEBUG] [i.internal.protocol.KodiClientSocket] - callMethod returns {"id":286,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":6,"revision":"20171115-d69a54a","tag":"stable"}}}

2018-09-16 20:59:16.590 [TRACE] [me.storage.json.internal.JsonStorage] - deserialized value 'DiscoveryResult [thingUID=astro:sun:local, properties={geolocation=53.13853299128647,8.91320351511238,0}, representationProperty=geolocation, flag=NEW, label=Local Sun, bridgeUID=null, ttl=-1, timestamp=1536959192131]' from Json

2018-09-16 20:59:17.099 [TRACE] [nal.communicator.client.BinRpcClient] - Client BinRpcRequest:

ping()

ccu3

2018-09-16 20:59:17.100 [TRACE] [al.communicator.client.SocketHandler] - Returning socket for port 2001

2018-09-16 20:59:17.103 [TRACE] [nal.communicator.client.BinRpcClient] - Client BinRpcResponse:

[

	true

]

2018-09-16 20:59:17.104 [TRACE] [nicator.server.BinRpcResponseHandler] - Event BinRpcMessage: system.multicall()

[

	{

		methodName=event

		params=

		[

			RF-ccu3

			CENTRAL

			PONG

			ccu3

		]

	}

]

2018-09-16 20:59:17.105 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (String) value 'ccu3' for 'CENTRAL:0#PONG' from gateway with id 'ccu3'

2018-09-16 20:59:17.104 [TRACE] [nal.communicator.client.BinRpcClient] - Client BinRpcRequest:

listBidcosInterfaces()

2018-09-16 20:59:17.106 [TRACE] [al.communicator.client.SocketHandler] - Returning socket for port 2001

2018-09-16 20:59:17.109 [TRACE] [nal.communicator.client.BinRpcClient] - Client BinRpcResponse:

[

	{

		ADDRESS=PEQ1099331

		CONNECTED=true

		DEFAULT=true

		DESCRIPTION=

		DUTY_CYCLE=31

		FIRMWARE_VERSION=3.4.8

		TYPE=CCU2

	}

]

2018-09-16 20:59:17.111 [TRACE] [me.core.internal.events.EventHandler] - Handle OSGi event (event: org.osgi.service.event.Event [topic=smarthome] {topic=smarthome/items/Duty_Cycle/state, source=homematic:bridge:ccu3:DUTY_CYCLE_RATIO, type=ItemStateEvent, payload={"type":"Decimal","value":"31"}, timestamp=1537124357110})

2018-09-16 20:59:17.111 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.openhab.io.openhabcloud.internal.CloudService@53c45f44'

2018-09-16 20:59:17.111 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling invocation of 'receive()' on 'org.openhab.io.openhabcloud.internal.CloudService@53c45f44' for asynchronous execution

2018-09-16 20:59:17.112 [TRACE] [.internal.common.SafeCallManagerImpl] - Submitted invocation of 'receive()' on 'org.openhab.io.openhabcloud.internal.CloudService@53c45f44' for asynchronous execution

2018-09-16 20:59:17.112 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.io.monitor.internal.EventLogger@23a03161'

2018-09-16 20:59:17.112 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling timeout watcher in 5000ms

2018-09-16 20:59:17.112 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling invocation of 'receive()' on 'org.eclipse.smarthome.io.monitor.internal.EventLogger@23a03161' for asynchronous execution

2018-09-16 20:59:17.113 [TRACE] [.internal.common.SafeCallManagerImpl] - Submitted invocation of 'receive()' on 'org.eclipse.smarthome.io.monitor.internal.EventLogger@23a03161' for asynchronous execution

2018-09-16 20:59:17.113 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling timeout watcher in 5000ms

2018-09-16 20:59:17.113 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71'

2018-09-16 20:59:17.113 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.openhab.io.openhabcloud.internal.CloudService@53c45f44'

2018-09-16 20:59:17.113 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling invocation of 'receive()' on 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71' for asynchronous execution

2018-09-16 20:59:17.114 [TRACE] [.internal.common.SafeCallManagerImpl] - Submitted invocation of 'receive()' on 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71' for asynchronous execution

2018-09-16 20:59:17.114 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.openhab.io.openhabcloud.internal.CloudService@53c45f44'

==> /var/log/openhab2/events.log <==

2018-09-16 20:59:17.113 [thome.event.ItemStateEvent] - Received event of type 'ItemStateEvent' under the topic 'smarthome/items/Duty_Cycle/state' with payload: '{"type":"Decimal","value":"31"}'

2018-09-16 20:59:17.114 [thome.event.ItemStateEvent] - Duty_Cycle updated to 31

==> /var/log/openhab2/openhab.log <==

==> /var/log/openhab2/events.log <==

==> /var/log/openhab2/openhab.log <==

2018-09-16 20:59:17.114 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.model.rule.runtime.internal.engine.RuleEngineImpl@6337b94d'

2018-09-16 20:59:17.114 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.eclipse.smarthome.io.monitor.internal.EventLogger@23a03161'

2018-09-16 20:59:17.114 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling timeout watcher in 5000ms

2018-09-16 20:59:17.114 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling invocation of 'receive()' on 'org.eclipse.smarthome.model.rule.runtime.internal.engine.RuleEngineImpl@6337b94d' for asynchronous execution

2018-09-16 20:59:17.114 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.io.monitor.internal.EventLogger@23a03161'

2018-09-16 20:59:17.115 [TRACE] [.internal.common.SafeCallManagerImpl] - Submitted invocation of 'receive()' on 'org.eclipse.smarthome.model.rule.runtime.internal.engine.RuleEngineImpl@6337b94d' for asynchronous execution

2018-09-16 20:59:17.115 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling timeout watcher in 5000ms

2018-09-16 20:59:17.115 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.openhab.core.events.internal.EventBridge@202d3bf7'

2018-09-16 20:59:17.115 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.eclipse.smarthome.model.rule.runtime.internal.engine.RuleEngineImpl@6337b94d'

2018-09-16 20:59:17.115 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling invocation of 'receive()' on 'org.openhab.core.events.internal.EventBridge@202d3bf7' for asynchronous execution

2018-09-16 20:59:17.115 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.model.rule.runtime.internal.engine.RuleEngineImpl@6337b94d'

2018-09-16 20:59:17.116 [TRACE] [.internal.common.SafeCallManagerImpl] - Submitted invocation of 'receive()' on 'org.openhab.core.events.internal.EventBridge@202d3bf7' for asynchronous execution

2018-09-16 20:59:17.116 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling timeout watcher in 5000ms

2018-09-16 20:59:17.116 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.core.internal.items.ItemUpdater@15bdf866'

2018-09-16 20:59:17.116 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.openhab.core.events.internal.EventBridge@202d3bf7'

2018-09-16 20:59:17.116 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71'

2018-09-16 20:59:17.117 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling invocation of 'receive()' on 'org.eclipse.smarthome.core.internal.items.ItemUpdater@15bdf866' for asynchronous execution

2018-09-16 20:59:17.117 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.io.rest.sse.internal.listeners.SseEventSubscriber@66a2bb71'

2018-09-16 20:59:17.117 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.openhab.core.events.internal.EventBridge@202d3bf7'

2018-09-16 20:59:17.117 [TRACE] [.internal.common.SafeCallManagerImpl] - Submitted invocation of 'receive()' on 'org.eclipse.smarthome.core.internal.items.ItemUpdater@15bdf866' for asynchronous execution

2018-09-16 20:59:17.117 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling timeout watcher in 5000ms

2018-09-16 20:59:17.117 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.core.autoupdate.internal.AutoUpdateBinding@29b3080c'

2018-09-16 20:59:17.118 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling invocation of 'receive()' on 'org.eclipse.smarthome.core.autoupdate.internal.AutoUpdateBinding@29b3080c' for asynchronous execution

2018-09-16 20:59:17.118 [TRACE] [.internal.common.SafeCallManagerImpl] - Submitted invocation of 'receive()' on 'org.eclipse.smarthome.core.autoupdate.internal.AutoUpdateBinding@29b3080c' for asynchronous execution

2018-09-16 20:59:17.118 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling timeout watcher in 5000ms

2018-09-16 20:59:17.119 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@6477f578'

2018-09-16 20:59:17.119 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.eclipse.smarthome.core.autoupdate.internal.AutoUpdateBinding@29b3080c'

2018-09-16 20:59:17.119 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.core.autoupdate.internal.AutoUpdateBinding@29b3080c'

2018-09-16 20:59:17.119 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling invocation of 'receive()' on 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@6477f578' for asynchronous execution

2018-09-16 20:59:17.119 [TRACE] [.internal.common.SafeCallManagerImpl] - Submitted invocation of 'receive()' on 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@6477f578' for asynchronous execution

2018-09-16 20:59:17.119 [TRACE] [.internal.common.SafeCallManagerImpl] - Scheduling timeout watcher in 5000ms

2018-09-16 20:59:17.120 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.eclipse.smarthome.core.internal.items.ItemUpdater@15bdf866'

2018-09-16 20:59:17.121 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.core.internal.items.ItemUpdater@15bdf866'

2018-09-16 20:59:17.120 [TRACE] [.internal.common.SafeCallManagerImpl] - Finished invocation of 'receive()' on 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@6477f578'

2018-09-16 20:59:17.121 [TRACE] [.internal.common.SafeCallManagerImpl] - Triggering submissions for 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@6477f578'

2018-09-16 20:59:18.635 [DEBUG] [core.karaf.internal.FeatureInstaller] - Running scheduled sync job

2018-09-16 20:59:20.552 [TRACE] [nicator.server.BinRpcResponseHandler] - Event BinRpcMessage: system.multicall()

[

	{

		methodName=event

		params=

		[

			RF-ccu3

			CENTRAL

			PONG

			hm-rpc.0

		]

	}

]

2018-09-16 20:59:20.552 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (String) value 'hm-rpc.0' for 'CENTRAL:0#PONG' from gateway with id 'ccu3'

(Alexander) #13

(Hans-Jörg Merk) #14

wrong place, should be eclipse/smarthome