search

openhab / openhab-addons

Add-ons for openHAB
https://www.openhab.org/
Eclipse Public License 2.0
1.88k stars 3.59k forks source link

[deconz] multiple buttonevents (HUE dimmer-switch, Ikea 5-button & Aquara Cube) #9604

Closed olemr closed 3 years ago

olemr commented 3 years ago

Expected Behavior

One single Item received update or Channel trigger per normal push duration of buttons.

Current Behavior

Each button event reported multiple times just a few ms apart (3x -6x)

2020-12-30 16:19:53.235 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 5002
2020-12-30 16:19:56.913 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 5002
2020-12-30 16:19:56.913 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 5002
2020-12-30 16:19:56.915 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 5002
2020-12-30 16:19:59.490 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 2002
2020-12-30 16:19:59.491 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 2002
2020-12-30 16:19:59.492 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 2002
2020-12-30 16:20:01.091 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 3002
2020-12-30 16:20:01.092 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 3002
2020-12-30 16:20:01.092 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 3002
2020-12-30 16:20:03.331 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 1002
2020-12-30 16:20:03.331 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 1002
2020-12-30 16:20:03.335 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent triggered 1002
2020-12-30 16:33:33.716 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent triggered 1002
2020-12-30 16:33:33.716 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent triggered 1002
2020-12-30 16:33:33.717 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent triggered 1002
2020-12-30 16:33:36.118 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent triggered 5002
2020-12-30 16:33:36.118 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent triggered 5002
2020-12-30 16:33:36.135 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent triggered 5002
2020-12-30 16:33:38.504 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent triggered 1002
2020-12-30 16:33:38.504 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent triggered 1002
2020-12-30 16:33:38.507 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent triggered 1002
2020-12-30 16:46:25.880 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:0017880103a4105e02fc00:buttonevent triggered 3002
2020-12-30 16:46:25.881 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:0017880103a4105e02fc00:buttonevent triggered 3002
2020-12-30 16:46:25.882 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:0017880103a4105e02fc00:buttonevent triggered 3002
2020-12-30 16:46:26.902 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:0017880103a4105e02fc00:buttonevent triggered 3002
2020-12-30 16:46:26.903 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:0017880103a4105e02fc00:buttonevent triggered 3002
2020-12-30 16:46:26.904 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:0017880103a4105e02fc00:buttonevent triggered 3002

- one turn on the Aquara cube:
2020-12-30 16:51:58.804 [INFO ] [openhab.event.ChannelTriggeredEvent ] - 
deconz:switch:homeserver:00158d00010250dc020012:buttonevent triggered 7000
2020-12-30 16:51:58.805 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc020012:gestureevent triggered 0
2020-12-30 16:51:58.806 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc020012:buttonevent triggered 7000
2020-12-30 16:51:58.807 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc020012:gestureevent triggered 0
2020-12-30 16:51:58.807 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc020012:buttonevent triggered 7000
2020-12-30 16:51:58.807 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc020012:gestureevent triggered 0
2020-12-30 16:51:59.285 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc03000c:buttonevent triggered 1613
2020-12-30 16:51:59.285 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc03000c:buttonevent triggered 1613
2020-12-30 16:51:59.286 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc03000c:gestureevent triggered 7
2020-12-30 16:51:59.287 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc03000c:gestureevent triggered 7
2020-12-30 16:51:59.287 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc03000c:buttonevent triggered 1613
2020-12-30 16:51:59.288 [INFO ] [openhab.event.ChannelTriggeredEvent ] - deconz:switch:homeserver:00158d00010250dc03000c:gestureevent triggered 7

Possible Solution

Introduce a debounce mechanism?

Steps to Reproduce (for Bugs)

I have these items in a OH2.5.11->OH3.0.0 imported .items file:

Number OMRRemoteEvent           { channel = "deconz:switch:homeserver:000b57fffe0ed885011000:button" }
DateTime OMRRemoteLastUpdatedDT { channel = "deconz:switch:homeserver:000b57fffe0ed885011000:last_updated" }
Number MDYRemoteEvent           { channel = "deconz:switch:homeserver:000b57fffe1d4613011000:button" }
DateTime MDYRemoteLastUpdatedDT { channel = "deconz:switch:homeserver:000b57fffe1d4613011000:last_updated" }
Number Hue1RemoteEvent           { channel = "deconz:switch:homeserver:0017880103a4105e02fc00:button" }
DateTime Hue1RemoteLastUpdatedDT { channel = "deconz:switch:homeserver:0017880103a4105e02fc00:last_updated" }
Number   Pink1CubeButtonEvent          (qubesEvent)        { channel = "deconz:switch:homeserver:00158d00010250dc020012:button" }
DateTime Pink1CubeButtonLastUpdatedDT  (qubesUpdatedDT)    { channel = "deconz:switch:homeserver:00158d00010250dc020012:last_updated" }

Rules:

Channel "deconz:switch:homeserver:000b57fffe0ed885011000:buttonevent" triggered
Channel "deconz:switch:homeserver:000b57fffe1d4613011000:buttonevent" triggered
Channel "deconz:switch:homeserver:0017880103a4105e02fc00:buttonevent" triggered
rule "Cube gesture changed"
when Member of qubesEvent changed
then
    var Cstate = triggeringItem.state

Context

I noticed this new behaviour on OH3.0.0 deconz 2.9.0 (same on 2.8.0)

Your Environment

J-N-K commented 3 years ago

Can you show a TRACE log of that?

olemr commented 3 years ago

Sure. This is me going from my computer to the HUE1 dimmer-switch in the bathroom, triggering a HUE motion sensor before pressing button 3002 once (shortest press I could muster).

2020-12-30 17:20:37.208 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"id":"34","lastannounced":null,"lastseen":"2020-12-30T16:20Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI control outlet","name":"HTPC/34","swversion":"1.4.020","type":"On/Off plug-in unit","uniqueid":"d0:cf:5e:ff:fe:cb:88:78-01"},"e":"changed","id":"34","r":"lights","t":"event","uniqueid":"d0:cf:5e:ff:fe:cb:88:78-01"}
2020-12-30 17:20:37.209 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"id":"34","lastannounced":null,"lastseen":"2020-12-30T16:20Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI control outlet","name":"HTPC/34","swversion":"1.4.020","type":"On/Off plug-in unit","uniqueid":"d0:cf:5e:ff:fe:cb:88:78-01"},"e":"changed","id":"34","r":"lights","t":"event","uniqueid":"d0:cf:5e:ff:fe:cb:88:78-01"}
2020-12-30 17:20:37.209 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 34 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:20:37.210 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 34 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:20:37.210 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"id":"34","lastannounced":null,"lastseen":"2020-12-30T16:20Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI control outlet","name":"HTPC/34","swversion":"1.4.020","type":"On/Off plug-in unit","uniqueid":"d0:cf:5e:ff:fe:cb:88:78-01"},"e":"changed","id":"34","r":"lights","t":"event","uniqueid":"d0:cf:5e:ff:fe:cb:88:78-01"}
2020-12-30 17:20:37.211 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 34 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:20:37.401 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"colorcapabilities":0,"ctmax":65279,"ctmin":0,"id":"79","lastannounced":null,"lastseen":"2020-12-30T16:20Z","manufacturername":"Philips","modelid":"LTG002","name":"MDY bed 79","swversion":"1.55.7_r28193","type":"Color temperature light","uniqueid":"00:17:88:01:08:44:0a:1f-0b"},"e":"changed","id":"79","r":"lights","t":"event","uniqueid":"00:17:88:01:08:44:0a:1f-0b"}
2020-12-30 17:20:37.401 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:0017880108440a1f0b received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='79', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:17:88:01:08:44:0a:1f-0b'}
2020-12-30 17:20:37.402 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"colorcapabilities":0,"ctmax":65279,"ctmin":0,"id":"79","lastannounced":null,"lastseen":"2020-12-30T16:20Z","manufacturername":"Philips","modelid":"LTG002","name":"MDY bed 79","swversion":"1.55.7_r28193","type":"Color temperature light","uniqueid":"00:17:88:01:08:44:0a:1f-0b"},"e":"changed","id":"79","r":"lights","t":"event","uniqueid":"00:17:88:01:08:44:0a:1f-0b"}
2020-12-30 17:20:37.402 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:0017880108440a1f0b received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='79', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:17:88:01:08:44:0a:1f-0b'}
2020-12-30 17:20:37.403 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"colorcapabilities":0,"ctmax":65279,"ctmin":0,"id":"79","lastannounced":null,"lastseen":"2020-12-30T16:20Z","manufacturername":"Philips","modelid":"LTG002","name":"MDY bed 79","swversion":"1.55.7_r28193","type":"Color temperature light","uniqueid":"00:17:88:01:08:44:0a:1f-0b"},"e":"changed","id":"79","r":"lights","t":"event","uniqueid":"00:17:88:01:08:44:0a:1f-0b"}
2020-12-30 17:20:37.403 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:0017880108440a1f0b received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='79', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:17:88:01:08:44:0a:1f-0b'}
2020-12-30 17:20:41.974 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:homeserver starts refreshing the fullStateCache
2020-12-30 17:20:44.637 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"e":"changed","id":"15","r":"sensors","state":{"lastupdated":"2020-12-30T16:20:44.634","presence":true},"t":"event","uniqueid":"00:17:88:01:04:b5:29:aa-02-0406"}
2020-12-30 17:20:44.637 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"e":"changed","id":"15","r":"sensors","state":{"lastupdated":"2020-12-30T16:20:44.634","presence":true},"t":"event","uniqueid":"00:17:88:01:04:b5:29:aa-02-0406"}
2020-12-30 17:20:44.637 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"e":"changed","id":"15","r":"sensors","state":{"lastupdated":"2020-12-30T16:20:44.634","presence":true},"t":"event","uniqueid":"00:17:88:01:04:b5:29:aa-02-0406"}
2020-12-30 17:20:44.637 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:presencesensor:homeserver:0017880104b529aa020406 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=true, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=null, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T16:20:44.634', xy=null}, e='changed', r='SENSORS', t='event', id='15', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:04:b5:29:aa-02-0406'}
2020-12-30 17:20:44.638 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:presencesensor:homeserver:0017880104b529aa020406 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=true, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=null, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T16:20:44.634', xy=null}, e='changed', r='SENSORS', t='event', id='15', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:04:b5:29:aa-02-0406'}
2020-12-30 17:20:44.638 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:presencesensor:homeserver:0017880104b529aa020406 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=true, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=null, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T16:20:44.634', xy=null}, e='changed', r='SENSORS', t='event', id='15', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:04:b5:29:aa-02-0406'}
2020-12-30 17:20:44.643 [INFO ] [openhab.core.model.script.HUE motion] - HueMotion1EventSW:ON
2020-12-30 17:20:49.662 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"config":{"battery":76,"group":"3154","on":true,"reachable":true},"e":"changed","id":"12","r":"sensors","t":"event","uniqueid":"00:17:88:01:03:a4:10:5e-02-fc00"}
2020-12-30 17:20:49.662 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"config":{"battery":76,"group":"3154","on":true,"reachable":true},"e":"changed","id":"12","r":"sensors","t":"event","uniqueid":"00:17:88:01:03:a4:10:5e-02-fc00"}
2020-12-30 17:20:49.663 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:switch:homeserver:0017880103a4105e02fc00 received SensorMessage{type='', config=SensorConfig{on=true, reachable=true, battery=76, temperature=null, heatsetpoint=null, mode=null, offset=null}, state=null, e='changed', r='SENSORS', t='event', id='12', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:03:a4:10:5e-02-fc00'}
2020-12-30 17:20:49.663 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:switch:homeserver:0017880103a4105e02fc00 received SensorMessage{type='', config=SensorConfig{on=true, reachable=true, battery=76, temperature=null, heatsetpoint=null, mode=null, offset=null}, state=null, e='changed', r='SENSORS', t='event', id='12', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:03:a4:10:5e-02-fc00'}
2020-12-30 17:20:49.663 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"config":{"battery":76,"group":"3154","on":true,"reachable":true},"e":"changed","id":"12","r":"sensors","t":"event","uniqueid":"00:17:88:01:03:a4:10:5e-02-fc00"}
2020-12-30 17:20:49.663 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:switch:homeserver:0017880103a4105e02fc00 received SensorMessage{type='', config=SensorConfig{on=true, reachable=true, battery=76, temperature=null, heatsetpoint=null, mode=null, offset=null}, state=null, e='changed', r='SENSORS', t='event', id='12', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:03:a4:10:5e-02-fc00'}
2020-12-30 17:20:49.758 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"e":"changed","id":"12","r":"sensors","state":{"buttonevent":3002,"eventduration":1,"lastupdated":"2020-12-30T16:20:49.756"},"t":"event","uniqueid":"00:17:88:01:03:a4:10:5e-02-fc00"}
2020-12-30 17:20:49.759 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"e":"changed","id":"12","r":"sensors","state":{"buttonevent":3002,"eventduration":1,"lastupdated":"2020-12-30T16:20:49.756"},"t":"event","uniqueid":"00:17:88:01:03:a4:10:5e-02-fc00"}
2020-12-30 17:20:49.759 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:switch:homeserver:0017880103a4105e02fc00 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=null, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=3002, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T16:20:49.756', xy=null}, e='changed', r='SENSORS', t='event', id='12', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:03:a4:10:5e-02-fc00'}
2020-12-30 17:20:49.760 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:switch:homeserver:0017880103a4105e02fc00 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=null, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=3002, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T16:20:49.756', xy=null}, e='changed', r='SENSORS', t='event', id='12', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:03:a4:10:5e-02-fc00'}
2020-12-30 17:20:49.760 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"e":"changed","id":"12","r":"sensors","state":{"buttonevent":3002,"eventduration":1,"lastupdated":"2020-12-30T16:20:49.756"},"t":"event","uniqueid":"00:17:88:01:03:a4:10:5e-02-fc00"}
2020-12-30 17:20:49.762 [INFO ] [org.openhab.core.model.script.Hue1  ] - Event: 3002
2020-12-30 17:20:49.763 [INFO ] [org.openhab.core.model.script.Hue1  ] - Vol-: 5%
2020-12-30 17:20:49.765 [INFO ] [rg.openhab.core.model.script.BadOppe] - Volume=17%
2020-12-30 17:20:49.766 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:switch:homeserver:0017880103a4105e02fc00 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=null, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=3002, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T16:20:49.756', xy=null}, e='changed', r='SENSORS', t='event', id='12', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:03:a4:10:5e-02-fc00'}
2020-12-30 17:20:49.768 [INFO ] [org.openhab.core.model.script.Hue1  ] - Event: 3002
2020-12-30 17:20:49.769 [INFO ] [org.openhab.core.model.script.Hue1  ] - Vol-: 5%
2020-12-30 17:20:49.771 [INFO ] [rg.openhab.core.model.script.BadOppe] - Volume=17%
2020-12-30 17:20:49.772 [INFO ] [org.openhab.core.model.script.Hue1  ] - Event: 3002
2020-12-30 17:20:49.774 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxTouch2Volume State=17
2020-12-30 17:20:49.776 [INFO ] [org.openhab.core.model.script.Hue1  ] - Vol-: 5%
2020-12-30 17:20:49.777 [INFO ] [rg.openhab.core.model.script.BadOppe] - Volume=12%
2020-12-30 17:20:49.780 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxTouch2Volume State=17
2020-12-30 17:20:49.782 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxTouch2Volume State=17
2020-12-30 17:20:49.784 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxTouch2Volume State=12
2020-12-30 17:20:49.796 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxTouch2Volume State=12
2020-12-30 17:20:55.969 [INFO ] [org.openhab.core.model.script.door  ] - BathroomDoorSensor: CLOSED
2020-12-30 17:20:58.699 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"e":"changed","id":"15","r":"sensors","state":{"lastupdated":"2020-12-30T16:20:58.690","presence":false},"t":"event","uniqueid":"00:17:88:01:04:b5:29:aa-02-0406"}
2020-12-30 17:20:58.699 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:presencesensor:homeserver:0017880104b529aa020406 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=false, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=null, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T16:20:58.690', xy=null}, e='changed', r='SENSORS', t='event', id='15', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:04:b5:29:aa-02-0406'}
2020-12-30 17:20:58.700 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"e":"changed","id":"15","r":"sensors","state":{"lastupdated":"2020-12-30T16:20:58.690","presence":false},"t":"event","uniqueid":"00:17:88:01:04:b5:29:aa-02-0406"}
2020-12-30 17:20:58.701 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:presencesensor:homeserver:0017880104b529aa020406 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=false, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=null, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T16:20:58.690', xy=null}, e='changed', r='SENSORS', t='event', id='15', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:04:b5:29:aa-02-0406'}
2020-12-30 17:20:58.701 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"e":"changed","id":"15","r":"sensors","state":{"lastupdated":"2020-12-30T16:20:58.690","presence":false},"t":"event","uniqueid":"00:17:88:01:04:b5:29:aa-02-0406"}
2020-12-30 17:20:58.703 [INFO ] [openhab.core.model.script.HUE motion] - HueMotion1EventSW:OFF
2020-12-30 17:20:58.703 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:presencesensor:homeserver:0017880104b529aa020406 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=false, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=null, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T16:20:58.690', xy=null}, e='changed', r='SENSORS', t='event', id='15', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:04:b5:29:aa-02-0406'}
2020-12-30 17:21:00.266 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"id":"35","lastannounced":null,"lastseen":"2020-12-30T16:21Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI control outlet","name":"MDYPC/35","swversion":"1.4.020","type":"On/Off plug-in unit","uniqueid":"d0:cf:5e:ff:fe:ef:2b:c2-01"},"e":"changed","id":"35","r":"lights","t":"event","uniqueid":"d0:cf:5e:ff:fe:ef:2b:c2-01"}
2020-12-30 17:21:00.266 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"id":"35","lastannounced":null,"lastseen":"2020-12-30T16:21Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI control outlet","name":"MDYPC/35","swversion":"1.4.020","type":"On/Off plug-in unit","uniqueid":"d0:cf:5e:ff:fe:ef:2b:c2-01"},"e":"changed","id":"35","r":"lights","t":"event","uniqueid":"d0:cf:5e:ff:fe:ef:2b:c2-01"}
2020-12-30 17:21:00.267 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 35 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:21:00.267 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 35 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:21:00.267 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"id":"35","lastannounced":null,"lastseen":"2020-12-30T16:21Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI control outlet","name":"MDYPC/35","swversion":"1.4.020","type":"On/Off plug-in unit","uniqueid":"d0:cf:5e:ff:fe:ef:2b:c2-01"},"e":"changed","id":"35","r":"lights","t":"event","uniqueid":"d0:cf:5e:ff:fe:ef:2b:c2-01"}
2020-12-30 17:21:00.267 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 35 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:21:13.821 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"44","lastannounced":null,"lastseen":"2020-12-30T16:21Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Mekkebenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"},"e":"changed","id":"44","r":"lights","t":"event","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"}
2020-12-30 17:21:13.821 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"44","lastannounced":null,"lastseen":"2020-12-30T16:21Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Mekkebenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"},"e":"changed","id":"44","r":"lights","t":"event","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"}
2020-12-30 17:21:13.821 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"44","lastannounced":null,"lastseen":"2020-12-30T16:21Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Mekkebenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"},"e":"changed","id":"44","r":"lights","t":"event","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"}
2020-12-30 17:21:13.822 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 44 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:21:13.822 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 44 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:21:13.822 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 44 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:21:15.037 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"45","lastannounced":null,"lastseen":"2020-12-30T16:21Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Klesbenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:f7:a3:47-0b"},"e":"changed","id":"45","r":"lights","t":"event","uniqueid":"00:17:88:01:03:f7:a3:47-0b"}
2020-12-30 17:21:15.037 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"45","lastannounced":null,"lastseen":"2020-12-30T16:21Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Klesbenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:f7:a3:47-0b"},"e":"changed","id":"45","r":"lights","t":"event","uniqueid":"00:17:88:01:03:f7:a3:47-0b"}
2020-12-30 17:21:15.038 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 45 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:21:15.038 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 45 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-30 17:21:15.038 [TRACE] [nternal.netutils.WebSocketConnection] - Raw data received by websocket OH-httpClient-nz-deconz-homeserver$237562973: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"45","lastannounced":null,"lastseen":"2020-12-30T16:21Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Klesbenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:f7:a3:47-0b"},"e":"changed","id":"45","r":"lights","t":"event","uniqueid":"00:17:88:01:03:f7:a3:47-0b"}
2020-12-30 17:21:15.038 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 45 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
J-N-K commented 3 years ago

It seems that the websocket receives the message twice. This is the same issue as #9264.

I found another bug that could lead to this situation ifthe thing is immediately re-initialized. Do you use textual configuration?

Please try update org.openhab.binding.deconz https://janessa.me/esh/org.openhab.binding.deconz-3.1.0-SNAPSHOT.jar. I guess you must restart openHAB afterwards to get rid of the schedulers lurking around from prior versions.

olemr commented 3 years ago

Do you use textual configuration?

My .items and .rules text files a few posts up.

Bridge is defined in \things\deconz.things: (carried over from 2.5.11) Bridge deconz:deconz:homeserver [ host="127.0.0.1", apikey="0950775C33" ]

There is an entry in my Inbox, not added: image

Which one is preferred?

image

J-N-K commented 3 years ago

I was just asking out of curiosity. Another example of an issue that only occurs with textual configuration. The reason is that editing the things-file leads to ultra-fast deletion and creation of the thing handler. This is not possible via UI and therefore not so time-critical.

Is the version I provided above fixing your issues?

olemr commented 3 years ago

ETA 1-2hrs. RL matters :-)

olemr commented 3 years ago

And, we have a winner! Pressed HUE1 3002 button 1 time, Ikea center and < button 2x and turned the Mi cube once. Events add up!

2020-12-30 22:20:39.442 [INFO ] [org.openhab.core.model.script.Hue1  ] - Event: 3000
2020-12-30 22:20:39.560 [INFO ] [org.openhab.core.model.script.Hue1  ] - Event: 3002
2020-12-30 22:20:39.569 [INFO ] [org.openhab.core.model.script.Hue1  ] - Vol-: 5%
2020-12-30 22:20:39.577 [INFO ] [rg.openhab.core.model.script.BadOppe] - Volume=17%
2020-12-30 22:20:39.587 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxTouch2Volume State=17
2020-12-30 22:20:39.596 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxTouch2Volume State=17

2020-12-30 22:21:27.250 [INFO ] [openhab.core.model.script.MDY remote] - Event: 1002
2020-12-30 22:21:32.204 [INFO ] [openhab.core.model.script.MDY remote] - Event: 4002
2020-12-30 22:21:32.210 [INFO ] [openhab.core.model.script.MDY remote] - <
2020-12-30 22:21:32.234 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxBoomSovVolume State=16
2020-12-30 22:21:32.238 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxBoomSovVolume State=18
2020-12-30 22:21:35.134 [INFO ] [openhab.core.model.script.MDY remote] - Event: 4002
2020-12-30 22:21:35.135 [INFO ] [openhab.core.model.script.MDY remote] - <
2020-12-30 22:21:35.145 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxBoomSovVolume State=20
2020-12-30 22:21:35.158 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxBoomSovVolume State=20
2020-12-30 22:21:39.302 [INFO ] [openhab.core.model.script.MDY remote] - Event: 1002

2020-12-30 22:23:56.212 [INFO ] [rg.openhab.core.model.script.Cube P1] - Event: 7000.0
2020-12-30 22:23:56.214 [INFO ] [rg.openhab.core.model.script.Cube P1] - Face UP: 7
2020-12-30 22:23:56.215 [INFO ] [rg.openhab.core.model.script.Cube P1] - Last Face: 0
2020-12-30 22:23:56.216 [INFO ] [rg.openhab.core.model.script.Cube P1] - Woke up
2020-12-30 22:23:56.631 [INFO ] [.model.script.Pink1CubeRotAngleEvent] - Cube-Rotation: 2176
2020-12-30 22:23:56.635 [INFO ] [enhab.core.model.script.KjøkkenBooms] - Volume=22%
2020-12-30 22:23:56.642 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxBoomsVolume State=22
2020-12-30 22:23:56.649 [INFO ] [e.model.script.SqueezeBoxBoomsVolume] - Update: SqueezeBoxBoomsVolume.state=22
2020-12-30 22:23:56.654 [INFO ] [e.model.script.SqueezeBoxBoomsVolume] - Update: SqueezeBoxBoomsVolume.state=22
2020-12-30 22:23:56.656 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxBoomsVolume State=22
J-N-K commented 3 years ago

Thanks. Will prepare a PR then.

olemr commented 3 years ago

Hmm, tried turning some lights OFF and ON again, and these started popping up. Related?

2020-12-30 22:30:09.227 [WARN ] [nternal.netutils.WebSocketConnection] - Received message for other session.
2020-12-30 22:30:09.229 [WARN ] [nternal.netutils.WebSocketConnection] - Received message for other session.
2020-12-30 22:30:09.240 [WARN ] [nternal.netutils.WebSocketConnection] - Received message for other session.
2020-12-30 22:30:09.500 [WARN ] [nternal.netutils.WebSocketConnection] - Received message for other session.
2020-12-30 22:30:09.532 [WARN ] [nternal.netutils.WebSocketConnection] - Received message for other session.
2020-12-30 22:30:19.811 [WARN ] [nternal.netutils.WebSocketConnection] - Received message for other session.
2020-12-30 22:30:20.996 [WARN ] [nternal.netutils.WebSocketConnection] - Received message for other session.

Turned on TRACE logging here ...

2020-12-30 22:30:40.899 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609363112003-1/1891998366 received raw data: {"attr":{"colorcapabilities":0,"ctmax":65279,"ctmin":0,"id":"78","lastannounced":null,"lastseen":"2020-12-30T21:30Z","manufacturername":"Philips","modelid":"LTG002","name":"OMR bed 78","swversion":"1.55.7_r28193","type":"Color temperature light","uniqueid":"00:17:88:01:08:4a:a2:48-0b"},"e":"changed","id":"78","r":"lights","t":"event","uniqueid":"00:17:88:01:08:4a:a2:48-0b"}
2020-12-30 22:30:40.899 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609363112003-1/1675165150 received raw data: {"attr":{"colorcapabilities":0,"ctmax":65279,"ctmin":0,"id":"78","lastannounced":null,"lastseen":"2020-12-30T21:30Z","manufacturername":"Philips","modelid":"LTG002","name":"OMR bed 78","swversion":"1.55.7_r28193","type":"Color temperature light","uniqueid":"00:17:88:01:08:4a:a2:48-0b"},"e":"changed","id":"78","r":"lights","t":"event","uniqueid":"00:17:88:01:08:4a:a2:48-0b"}
2020-12-30 22:30:40.901 [WARN ] [nternal.netutils.WebSocketConnection] - Received message for other session.
2020-12-30 22:30:40.902 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:00178801084aa2480b received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='78', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:17:88:01:08:4a:a2:48-0b'}
2020-12-30 22:30:40.903 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:00178801084aa2480b received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='78', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:17:88:01:08:4a:a2:48-0b'}
2020-12-30 22:30:46.149 [INFO ] [rg.openhab.core.model.script.Bedroom] - BuildInBedroomRoofSwitch State=ON
2020-12-30 22:30:46.152 [INFO ] [rg.openhab.core.model.script.Bedroom] - radio ON
2020-12-30 22:30:46.153 [INFO ] [rg.openhab.core.model.script.Bedroom] - bedRoomLights.state=ON
2020-12-30 22:30:46.168 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxBoomSovVolume State=16
2020-12-30 22:30:46.175 [INFO ] [g.openhab.core.model.script.SoundVol] - SqueezeBoxBoomSovVolume State=16
2020-12-30 22:30:48.168 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609363112003-1/1675165150 received raw data: {"config":{"battery":69,"group":"3154","on":true,"reachable":true},"e":"changed","id":"12","r":"sensors","t":"event","uniqueid":"00:17:88:01:03:a4:10:5e-02-fc00"}
2020-12-30 22:30:48.168 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609363112003-1/1891998366 received raw data: {"config":{"battery":69,"group":"3154","on":true,"reachable":true},"e":"changed","id":"12","r":"sensors","t":"event","uniqueid":"00:17:88:01:03:a4:10:5e-02-fc00"}
2020-12-30 22:30:48.170 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:switch:homeserver:0017880103a4105e02fc00 received SensorMessage{type='', config=SensorConfig{on=true, reachable=true, battery=69, temperature=null, heatsetpoint=null, mode=null, offset=null}, state=null, e='changed', r='SENSORS', t='event', id='12', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:03:a4:10:5e-02-fc00'}
2020-12-30 22:30:48.169 [WARN ] [nternal.netutils.WebSocketConnection] - Received message for other session.
2020-12-30 22:30:48.172 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:switch:homeserver:0017880103a4105e02fc00 received SensorMessage{type='', config=SensorConfig{on=true, reachable=true, battery=69, temperature=null, heatsetpoint=null, mode=null, offset=null}, state=null, e='changed', r='SENSORS', t='event', id='12', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:03:a4:10:5e-02-fc00'}
2020-12-30 22:30:50.089 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609363112003-1/1675165150 received raw data: {"e":"changed","id":"15","r":"sensors","state":{"lastupdated":"2020-12-30T21:30:50.086","presence":false},"t":"event","uniqueid":"00:17:88:01:04:b5:29:aa-02-0406"}
2020-12-30 22:30:50.089 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609363112003-1/1891998366 received raw data: {"e":"changed","id":"15","r":"sensors","state":{"lastupdated":"2020-12-30T21:30:50.086","presence":false},"t":"event","uniqueid":"00:17:88:01:04:b5:29:aa-02-0406"}
2020-12-30 22:30:50.090 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:presencesensor:homeserver:0017880104b529aa020406 received SensorMessage{type='', config=null, state=SensorState{dark=null, daylight=null, lightlevel=null, lux=null, temperature=null, humidity=null, open=null, fire=null, water=null, alarm=null, tampered=null, vibration=null, carbonmonoxide=null, pressure=null, presence=false, power=null, battery=null, consumption=null, voltage=null, current=null, status=null, buttonevent=null, gesture=null, valve=null, windowopen='null', lastupdated='2020-12-30T21:30:50.086', xy=null}, e='changed', r='SENSORS', t='event', id='15', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00
J-N-K commented 3 years ago

Well. There are still two sessions. Without a full TRACE log I can‘t say what‘s happening there. I introduced that messages not belonging to the current session are discarded instead of processed.

olemr commented 3 years ago

OK, I will do a new restart with TRACE on.

olemr commented 3 years ago

decTrace.txt Full TRACE startup log. ZIP file. rename from .txt to .zip

Flipped lights here: 

2020-12-30 22:48:23.792 [INFO ] [penhab.core.model.script.KeyFob1Rule] - Update: 2.0
2020-12-30 22:48:23.795 [INFO ] [penhab.core.model.script.KeyFob1Rule] - O1xClick
J-N-K commented 3 years ago

Thanks. Will check tomorrow.

J-N-K commented 3 years ago

The first strange thing:

2020-12-30 22:46:24.427 [TRACE] [internal.handler.DeconzBridgeHandler] - deconz:deconz:homeserver starts refreshing the fullStateCache
2020-12-30 22:46:24.463 [DEBUG] [internal.handler.DeconzBridgeHandler] - Start initializing bridge deconz:deconz:homeserver

Why is anything on the thing handler called before it is initialized? @openhab/core-maintainers shouldn't the framework prevent that?

That method is called from the thing itself (obviously this cannot be the case here, since initialize is called first, which produces the second log-message), a child thing (as far as I understand the child things are initialized after the bridge, so this cannot be a child thing initializing) or the discovery service. IMO what is true for the child things should also be true for the discovery service: use the handler only after it has been initialized.

J-N-K commented 3 years ago

@olemr 

2020-12-30 22:48:23.831 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:000b57fffe103e8101 received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=LightState{reachable=true, on=false, bri=203, alert='null', colormode='ct', effect='null', effectSpeed=null, hue=null, sat=null, ct=398, xy=null, transitiontime=null}, e='changed', r='LIGHTS', t='event', id='48', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:0b:57:ff:fe:10:3e:81-01'}
2020-12-30 22:48:23.835 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609364784340-1 connection errored: Value must be between 0 and 100
2020-12-30 22:48:23.847 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609364784340-1 closed connection: 1006 / Value must be between 0 and 100
2020-12-30 22:48:23.849 [DEBUG] [nternal.netutils.WebSocketConnection] - Trying to connect Websocket$1609364784340-1 to ws://127.0.0.1:443

I guess this is why you run into these issues: You linked a colortemperature to a Dimmer item. The resulting exception leads to a websocket re-connection.

J-N-K commented 3 years ago

@olemr Can you update again? Should be fixed now.

Edit: if the wrong link is still in place that would be great. to test if it works.

olemr commented 3 years ago

Thank you for following up so closely! Just did the update. Enabled TRACE logging, flipped the lights. (01xClick in log) WARN complaints in the log are gone.

A little background history: The light groups I'm flicking OFF/ON is controlled directly via sendHttpPutRequest to the Deconz REST API. This is because I'm still in the process of moving back to using bindings, after both the HUE binding (missing groups/0 issue) and the Deconz binding (high CPU usage/crash) failed on me while on 2.5.11.

Since I updated to 3.0.0 release on Dec. 27, I am still trying to decide which binding to use for lights & groups. Deconz binding is best for sensors, due to the WEB socket no-polling interface. (I have used deconz for all my sensors for many months (a year+ ?) and haven't had any issues.) (I'm still going to use the HUE binding for the lights I have on my native Phillips HUE Bridge.) Since the HUE binding failed first, I moved 3 bulbs over to deconz, and that was when I discovered the high CPU usage. One of these 3 bulbs is now part of the group I'm turning OFF/ON.

Switch BulbStairsState            (livingRoom)           { channel = "deconz:colortemperaturelight:homeserver:000b57fffe103e8101:brightness" }
Dimmer BulbStairsBright           (livingRoom)           { channel = "deconz:colortemperaturelight:homeserver:000b57fffe103e8101:brightness" }
Dimmer BulbStairsColorTemp        (livingRoom)           { channel = "deconz:colortemperaturelight:homeserver:000b57fffe103e8101:color_temperature" }

Regarding linking a Dimmer to a color_temperature channel; this have worked fine since day 1, and still working fine for HUE items. If this cannot be a dimmer, then what?

HUE: image

Deconz: image

TRACE log:

2020-12-31 12:27:24.799 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"44","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Mekkebenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"},"e":"changed","id":"44","r":"lights","t":"event","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"}
2020-12-31 12:27:24.800 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 44 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:26.016 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"45","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Klesbenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:f7:a3:47-0b"},"e":"changed","id":"45","r":"lights","t":"event","uniqueid":"00:17:88:01:03:f7:a3:47-0b"}
2020-12-31 12:27:26.018 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 45 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:31.259 [INFO ] [penhab.core.model.script.KeyFob1Rule] - Update: 2.0
2020-12-31 12:27:31.260 [INFO ] [penhab.core.model.script.KeyFob1Rule] - O1xClick
2020-12-31 12:27:31.263 [INFO ] [org.openhab.core.model.script.deconz] - deconzState triggerd by vStuaLightMSState=OFF
2020-12-31 12:27:31.268 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"30","r":"lights","state":{"alert":null,"bri":203,"colormode":"xy","effect":"0","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.4871,0.3987]},"t":"event","uniqueid":"00:0b:57:ff:fe:a7:ea:12-01"}
2020-12-31 12:27:31.268 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 30 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:31.269 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"48","r":"lights","state":{"alert":null,"bri":203,"colormode":"ct","ct":398,"on":false,"reachable":true},"t":"event","uniqueid":"00:0b:57:ff:fe:10:3e:81-01"}
2020-12-31 12:27:31.269 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:000b57fffe103e8101 received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=LightState{reachable=true, on=false, bri=203, alert='null', colormode='ct', effect='null', effectSpeed=null, hue=null, sat=null, ct=398, xy=null, transitiontime=null}, e='changed', r='LIGHTS', t='event', id='48', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:0b:57:ff:fe:10:3e:81-01'}
2020-12-31 12:27:31.270 [WARN ] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 encountered an error while processing the message {"e":"changed","id":"48","r":"lights","state":{"alert":null,"bri":203,"colormode":"ct","ct":398,"on":false,"reachable":true},"t":"event","uniqueid":"00:0b:57:ff:fe:10:3e:81-01"}: Value must be between 0 and 100
2020-12-31 12:27:31.271 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"49","r":"lights","state":{"alert":null,"bri":203,"colormode":"xy","effect":"0","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.4871,0.3987]},"t":"event","uniqueid":"00:0b:57:ff:fe:9d:ea:59-01"}
2020-12-31 12:27:31.271 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 49 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:31.271 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"51","r":"lights","state":{"alert":null,"bri":203,"colormode":"xy","effect":"0","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.4871,0.3987]},"t":"event","uniqueid":"00:0b:57:ff:fe:ac:19:a6-01"}
2020-12-31 12:27:31.272 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 51 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:31.273 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"50","r":"lights","state":{"alert":null,"bri":203,"colormode":"xy","effect":"0","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.4871,0.3987]},"t":"event","uniqueid":"00:0b:57:ff:fe:ae:5d:1c-01"}
2020-12-31 12:27:31.273 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 50 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:31.274 [INFO ] [org.openhab.core.model.script.Stairs] - BulbStairsBright.state=0
2020-12-31 12:27:31.274 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"52","r":"lights","state":{"alert":null,"bri":203,"colormode":"ct","ct":398,"on":false,"reachable":true},"t":"event","uniqueid":"00:0b:57:ff:fe:e8:3e:a5-01"}
2020-12-31 12:27:31.275 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 52 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:31.316 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"15","r":"groups","state":{"all_on":false,"any_on":false},"t":"event"}
2020-12-31 12:27:31.317 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 15 with resource type GROUPS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:31.319 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"27171","r":"groups","state":{"all_on":false,"any_on":true},"t":"event"}
2020-12-31 12:27:31.320 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 27171 with resource type GROUPS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:31.320 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"27","r":"groups","state":{"all_on":false,"any_on":false},"t":"event"}
2020-12-31 12:27:31.321 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 27 with resource type GROUPS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:31.764 [INFO ] [nhab.core.model.script.groupSwitches] - AllDR State=OFF
2020-12-31 12:27:35.653 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"id":"74","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"dresden elektronik","modelid":"ConBee","name":"Configuration tool 74","swversion":"0x26390500","type":"Configuration tool","uniqueid":"00:21:2e:ff:ff:02:2e:82-01"},"e":"changed","id":"74","r":"lights","t":"event","uniqueid":"00:21:2e:ff:ff:02:2e:82-01"}
2020-12-31 12:27:35.654 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 74 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:37.964 [INFO ] [penhab.core.model.script.KeyFob1Rule] - Update: 2.0
2020-12-31 12:27:37.965 [INFO ] [penhab.core.model.script.KeyFob1Rule] - O1xClick
2020-12-31 12:27:37.967 [INFO ] [org.openhab.core.model.script.deconz] - deconzState triggerd by vStuaLightMSState=ON
2020-12-31 12:27:37.972 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"30","r":"lights","state":{"alert":null,"bri":203,"colormode":"xy","effect":"0","hue":0,"on":true,"reachable":true,"sat":0,"xy":[0.4871,0.3987]},"t":"event","uniqueid":"00:0b:57:ff:fe:a7:ea:12-01"}
2020-12-31 12:27:37.972 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 30 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:37.973 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"48","r":"lights","state":{"alert":null,"bri":203,"colormode":"ct","ct":398,"on":true,"reachable":true},"t":"event","uniqueid":"00:0b:57:ff:fe:10:3e:81-01"}
2020-12-31 12:27:37.974 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:000b57fffe103e8101 received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=LightState{reachable=true, on=true, bri=203, alert='null', colormode='ct', effect='null', effectSpeed=null, hue=null, sat=null, ct=398, xy=null, transitiontime=null}, e='changed', r='LIGHTS', t='event', id='48', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:0b:57:ff:fe:10:3e:81-01'}
2020-12-31 12:27:37.975 [WARN ] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 encountered an error while processing the message {"e":"changed","id":"48","r":"lights","state":{"alert":null,"bri":203,"colormode":"ct","ct":398,"on":true,"reachable":true},"t":"event","uniqueid":"00:0b:57:ff:fe:10:3e:81-01"}: Value must be between 0 and 100
2020-12-31 12:27:37.975 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"49","r":"lights","state":{"alert":null,"bri":203,"colormode":"xy","effect":"0","hue":0,"on":true,"reachable":true,"sat":0,"xy":[0.4871,0.3987]},"t":"event","uniqueid":"00:0b:57:ff:fe:9d:ea:59-01"}
2020-12-31 12:27:37.975 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 49 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:37.977 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"51","r":"lights","state":{"alert":null,"bri":203,"colormode":"xy","effect":"0","hue":0,"on":true,"reachable":true,"sat":0,"xy":[0.4871,0.3987]},"t":"event","uniqueid":"00:0b:57:ff:fe:ac:19:a6-01"}
2020-12-31 12:27:37.977 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 51 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:37.978 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"50","r":"lights","state":{"alert":null,"bri":203,"colormode":"xy","effect":"0","hue":0,"on":true,"reachable":true,"sat":0,"xy":[0.4871,0.3987]},"t":"event","uniqueid":"00:0b:57:ff:fe:ae:5d:1c-01"}
2020-12-31 12:27:37.978 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 50 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:37.979 [INFO ] [org.openhab.core.model.script.Stairs] - BulbStairsBright.state=80
2020-12-31 12:27:37.979 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"52","r":"lights","state":{"alert":null,"bri":203,"colormode":"ct","ct":398,"on":true,"reachable":true},"t":"event","uniqueid":"00:0b:57:ff:fe:e8:3e:a5-01"}
2020-12-31 12:27:37.980 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 52 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:38.025 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"15","r":"groups","state":{"all_on":true,"any_on":true},"t":"event"}
2020-12-31 12:27:38.025 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 15 with resource type GROUPS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:38.028 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"27171","r":"groups","state":{"all_on":true,"any_on":true},"t":"event"}
2020-12-31 12:27:38.028 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 27171 with resource type GROUPS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:38.030 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"e":"changed","id":"27","r":"groups","state":{"all_on":true,"any_on":true},"t":"event"}
2020-12-31 12:27:38.030 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 27 with resource type GROUPS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:38.184 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":8,"id":"51","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI bulb E27 CWS opal 600lm","name":"Trumpet 3","swversion":"1.3.009","type":"Color light","uniqueid":"00:0b:57:ff:fe:ac:19:a6-01"},"e":"changed","id":"51","r":"lights","t":"event","uniqueid":"00:0b:57:ff:fe:ac:19:a6-01"}
2020-12-31 12:27:38.184 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 51 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:38.215 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":16,"ctmax":454,"ctmin":250,"id":"48","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI bulb E27 WS opal 980lm","name":"Stue pendel","swversion":"1.2.217","type":"Color temperature light","uniqueid":"00:0b:57:ff:fe:10:3e:81-01"},"e":"changed","id":"48","r":"lights","t":"event","uniqueid":"00:0b:57:ff:fe:10:3e:81-01"}
2020-12-31 12:27:38.216 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:000b57fffe103e8101 received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='48', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:0b:57:ff:fe:10:3e:81-01'}
2020-12-31 12:27:38.296 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":8,"id":"50","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI bulb E27 CWS opal 600lm","name":"Trumpet 2","swversion":"1.3.009","type":"Color light","uniqueid":"00:0b:57:ff:fe:ae:5d:1c-01"},"e":"changed","id":"50","r":"lights","t":"event","uniqueid":"00:0b:57:ff:fe:ae:5d:1c-01"}
2020-12-31 12:27:38.296 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 50 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:38.327 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":8,"id":"49","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI bulb E27 CWS opal 600lm","name":"Trumpet 1","swversion":"1.3.009","type":"Color light","uniqueid":"00:0b:57:ff:fe:9d:ea:59-01"},"e":"changed","id":"49","r":"lights","t":"event","uniqueid":"00:0b:57:ff:fe:9d:ea:59-01"}
2020-12-31 12:27:38.328 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 49 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:38.359 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":16,"ctmax":454,"ctmin":250,"id":"52","lastannounced":"2020-12-14T06:50:23Z","lastseen":"2020-12-31T11:27Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI bulb E14 WS opal 400lm","name":"Stua Subwoofer","swversion":"1.2.221","type":"Color temperature light","uniqueid":"00:0b:57:ff:fe:e8:3e:a5-01"},"e":"changed","id":"52","r":"lights","t":"event","uniqueid":"00:0b:57:ff:fe:e8:3e:a5-01"}
2020-12-31 12:27:38.360 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 52 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:38.468 [INFO ] [nhab.core.model.script.groupSwitches] - AllDR State=NULL
2020-12-31 12:27:44.877 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"id":"71","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"IKEA of Sweden","modelid":"FYRTUR block-out roller blind","name":"Blind Kjellerstua Right","swversion":"2.2.009","type":"Window covering device","uniqueid":"08:6b:d7:ff:fe:51:92:71-01"},"e":"changed","id":"71","r":"lights","t":"event","uniqueid":"08:6b:d7:ff:fe:51:92:71-01"}
2020-12-31 12:27:44.878 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 71 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:27:47.150 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"68","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Strip Baderomsinnredning 68","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:04:9a:5a:db-0b"},"e":"changed","id":"68","r":"lights","t":"event","uniqueid":"00:17:88:01:04:9a:5a:db-0b"}
2020-12-31 12:27:47.151 [TRACE] [z.internal.handler.LightThingHandler] - deconz:extendedcolorlight:homeserver:00178801049a5adb0b received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='68', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:17:88:01:04:9a:5a:db-0b'}
2020-12-31 12:27:51.616 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"id":"38","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI control outlet","name":"Boom arbeidsbenk/38","swversion":"1.4.020","type":"On/Off plug-in unit","uniqueid":"d0:cf:5e:ff:fe:7a:b6:b8-01"},"e":"changed","id":"38","r":"lights","t":"event","uniqueid":"d0:cf:5e:ff:fe:7a:b6:b8-01"}
2020-12-31 12:27:51.617 [TRACE] [z.internal.handler.LightThingHandler] - deconz:onofflight:homeserver:d0cf5efffe7ab6b801 received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='38', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='d0:cf:5e:ff:fe:7a:b6:b8-01'}
2020-12-31 12:27:52.722 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":0,"ctmax":65279,"ctmin":0,"id":"78","lastannounced":null,"lastseen":"2020-12-31T11:27Z","manufacturername":"Philips","modelid":"LTG002","name":"OMR bed 78","swversion":"1.55.7_r28193","type":"Color temperature light","uniqueid":"00:17:88:01:08:4a:a2:48-0b"},"e":"changed","id":"78","r":"lights","t":"event","uniqueid":"00:17:88:01:08:4a:a2:48-0b"}
2020-12-31 12:27:52.723 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:00178801084aa2480b received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='78', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:17:88:01:08:4a:a2:48-0b'}
2020-12-31 12:28:27.051 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"config":{"battery":86,"group":"3154","on":true,"reachable":true},"e":"changed","id":"12","r":"sensors","t":"event","uniqueid":"00:17:88:01:03:a4:10:5e-02-fc00"}
2020-12-31 12:28:27.052 [TRACE] [ernal.handler.SensorBaseThingHandler] - deconz:switch:homeserver:0017880103a4105e02fc00 received SensorMessage{type='', config=SensorConfig{on=true, reachable=true, battery=86, temperature=null, heatsetpoint=null, mode=null, offset=null}, state=null, e='changed', r='SENSORS', t='event', id='12', manufacturername='', modelid='', name='', swversion='', ep='', lastseen='null', uniqueid='00:17:88:01:03:a4:10:5e-02-fc00'}
2020-12-31 12:28:32.063 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"id":"70","lastannounced":null,"lastseen":"2020-12-31T11:28Z","manufacturername":"NAMRON AS","modelid":"4512700","name":"Master Bath Roof 70","swversion":"2.5.3_r47","type":"Dimmable light","uniqueid":"68:0a:e2:ff:fe:3a:34:a5-01"},"e":"changed","id":"70","r":"lights","t":"event","uniqueid":"68:0a:e2:ff:fe:3a:34:a5-01"}
2020-12-31 12:28:32.063 [TRACE] [z.internal.handler.LightThingHandler] - deconz:dimmablelight:homeserver:680ae2fffe3a34a501 received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='70', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='68:0a:e2:ff:fe:3a:34:a5-01'}
2020-12-31 12:28:32.977 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"id":"72","lastannounced":null,"lastseen":"2020-12-31T11:28Z","manufacturername":"IKEA of Sweden","modelid":"FYRTUR block-out roller blind","name":"Blind Soverom Right","swversion":"2.2.009","type":"Window covering device","uniqueid":"08:6b:d7:ff:fe:57:da:24-01"},"e":"changed","id":"72","r":"lights","t":"event","uniqueid":"08:6b:d7:ff:fe:57:da:24-01"}
2020-12-31 12:28:32.977 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 72 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:28:35.298 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"69","lastannounced":null,"lastseen":"2020-12-31T11:28Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Strip Baderomsinnredning 69","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:04:07:95:bf-0b"},"e":"changed","id":"69","r":"lights","t":"event","uniqueid":"00:17:88:01:04:07:95:bf-0b"}
2020-12-31 12:28:35.298 [TRACE] [z.internal.handler.LightThingHandler] - deconz:extendedcolorlight:homeserver:00178801040795bf0b received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='69', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:17:88:01:04:07:95:bf-0b'}
2020-12-31 12:28:37.090 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"id":"74","lastannounced":null,"lastseen":"2020-12-31T11:28Z","manufacturername":"dresden elektronik","modelid":"ConBee","name":"Configuration tool 74","swversion":"0x26390500","type":"Configuration tool","uniqueid":"00:21:2e:ff:ff:02:2e:82-01"},"e":"changed","id":"74","r":"lights","t":"event","uniqueid":"00:21:2e:ff:ff:02:2e:82-01"}
2020-12-31 12:28:37.090 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 74 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:28:38.692 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":0,"ctmax":65279,"ctmin":0,"id":"79","lastannounced":null,"lastseen":"2020-12-31T11:28Z","manufacturername":"Philips","modelid":"LTG002","name":"MDY bed 79","swversion":"1.55.7_r28193","type":"Color temperature light","uniqueid":"00:17:88:01:08:44:0a:1f-0b"},"e":"changed","id":"79","r":"lights","t":"event","uniqueid":"00:17:88:01:08:44:0a:1f-0b"}
2020-12-31 12:28:38.693 [TRACE] [z.internal.handler.LightThingHandler] - deconz:colortemperaturelight:homeserver:0017880108440a1f0b received LightMessage{hascolor=null, ctmax=null, ctmin=null, type=null, state=null, e='changed', r='LIGHTS', t='event', id='79', manufacturername='', modelid='', name='', swversion='', ep='', uniqueid='00:17:88:01:08:44:0a:1f-0b'}
2020-12-31 12:28:45.801 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"44","lastannounced":null,"lastseen":"2020-12-31T11:28Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Mekkebenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"},"e":"changed","id":"44","r":"lights","t":"event","uniqueid":"00:17:88:01:03:cd:f0:e0-0b"}
2020-12-31 12:28:45.802 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 44 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:28:47.018 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"colorcapabilities":31,"ctmax":500,"ctmin":153,"id":"45","lastannounced":null,"lastseen":"2020-12-31T11:28Z","manufacturername":"Philips","modelid":"LST002","name":"HUE Vaskerom Klesbenk","swversion":"1.24.0_r20239","type":"Extended color light","uniqueid":"00:17:88:01:03:f7:a3:47-0b"},"e":"changed","id":"45","r":"lights","t":"event","uniqueid":"00:17:88:01:03:f7:a3:47-0b"}
2020-12-31 12:28:47.019 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 45 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
2020-12-31 12:28:49.693 [TRACE] [nternal.netutils.WebSocketConnection] - Websocket$1609413876480-1 received raw data: {"attr":{"id":"67","lastannounced":null,"lastseen":"2020-12-31T11:28Z","manufacturername":"IKEA of Sweden","modelid":"FYRTUR block-out roller blind","name":"Blind Soverom Left","swversion":"2.2.009","type":"Window covering device","uniqueid":"08:6b:d7:ff:fe:57:c4:64-01"},"e":"changed","id":"67","r":"lights","t":"event","uniqueid":"08:6b:d7:ff:fe:57:c4:64-01"}
2020-12-31 12:28:49.694 [DEBUG] [nternal.netutils.WebSocketConnection] - Couldn't find listener for id 67 with resource type LIGHTS. Either no thing for this id has been defined or this is a bug.
J-N-K commented 3 years ago

It's a number. You pass the color temperature to that channel (e.g. 3900). You should still be able to use a slider in the UI for that.

olemr commented 3 years ago

It's a number. You pass the color temperature to that channel (e.g. 3900). You should still be able to use a slider in the UI for that.

It's seeping in now. :-) Thanks. Easy to assume it's identical to HUE (and from when I used the Trådfri HUB).

olemr commented 3 years ago

One last thing. I am now on HOT .jars for both HUE and Deconz. If I'm not too keen on using SNAPSHOT releases, what/when will the next release be?

J-N-K commented 3 years ago

Please do not close issues, they‘ll be automatically closed when the PR is merged.

olemr commented 3 years ago

Sorry. For others coming here noting the difference from the HUE color_temperatur channel, this could be helpful: https://community.openhab.org/t/color-temperature-control-values/83926/12?u=omr

This setting in the sitemap works, but is non linear:

Number BulbStairsColorTemp        (livingRoom)           { channel = "deconz:colortemperaturelight:homeserver:000b57fffe103e8101:color_temperature" }
Slider item=BulbStairsColorTemp        label="Trapp (varme)"           icon="slider" minValue=2000 maxValue=6500 step=10