search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
71.34k stars 29.88k forks source link

Multiple automations with time conditional and brightness_pct does not work #20976

Closed 2rs closed 5 years ago

2rs commented 5 years ago

Home Assistant release with the issue:

homeassistant: 0.86.4

Last working Home Assistant release (if known): Had this problem since i started with HA and Tradfri in 2017

Operating environment (Hass.io/Docker/Windows/etc.):

Hass.io raspberry pi Component/platform:

Description of problem:

I have for some time been trying to set up automations for my lights (tradfri) with different brightness based on time when triggered by a PIR in the automations editor. I have set up different automations with time conditionals for after 07:00:00 before 23:59:59 and after 00:00:00 before 07:59:59 but when I insert brightness_pct: (value) on a line above the entity_I’d in the service data field the automation stops working. Without brightness it works as it is supposed to.

It works just setting an automation without a time conditional and a light_on with a brightness_pct value. It also behaves like this with the regular brightnes (0-255) value. An And if I'm not wrong, it even works with just one automation with a time condtional and an action with brightness, but when there are two automations with the same trigger event, a time condition and brightness it just does not work.

I have experienced this problem for a long time and thought it might be solved in future releases, but since it is still unsolved it looks like I have to file this bug report.

I am only using the automation ui. No errors in homeassistant-log.txt

Problem-relevant configuration.yaml entries and (fill out even if it seems unimportant):

Here is my automations.yaml. I am aware of the inconsistent apostrophe encapsulation on time values and have filed a separate repport on that. I have cleaned up this manualy but it did not change the behaviour on my automations.


- id: '1548785885000'
  alias: Lys plantelys off
  trigger:
  - at: '21:00:00'
    platform: time
  condition: []
  action:
  - data:
      entity_id: light.plantelys_group
    service: light.turn_off
- id: '1548787329983'
  alias: Lys plantelys on
  trigger:
  - at: 08:00:00
    platform: time
  condition: []
  action:
  - data:
      entity_id: light.plantelys_group
    service: light.turn_on
- id: '1548787451454'
  alias: Lys terrasse off
  trigger:
  - at: 00:00:00
    platform: time
  condition: []
  action:
  - data:
      entity_id: light.terrasse
    service: light.turn_off
- id: '1548787611548'
  alias: Lys terrasse on
  trigger:
  - event: sunset
    platform: sun
  condition: []
  action:
  - data:
      entity_id: light.terrasse
    service: light.turn_on
- id: '1548787710871'
  alias: Lys hage off
  trigger:
  - event: sunrise
    platform: sun
  condition: []
  action:
  - data:
      entity_id: switch.lys_hage
    service: switch.turn_off
- id: '1548787754899'
  alias: Lys hage on
  trigger:
  - event: sunset
    platform: sun
  condition: []
  action:
  - data:
      entity_id: switch.lys_hage
    service: switch.turn_on
- id: '1548787839196'
  alias: Lys veranda on
  trigger:
  - entity_id: binary_sensor.pir_veranda
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: sunset
    condition: sun
  - after: sunset
    before: sunrise
    condition: sun
  action:
  - data:
      entity_id: switch.lys_veranda
    service: switch.turn_on
- id: '1548787891801'
  alias: Lys veranda off
  trigger:
  - entity_id: binary_sensor.pir_veranda
    for: 00:05:00
    from: 'on'
    platform: state
    to: 'off'
  condition: []
  action:
  - data:
      entity_id: switch.lys_veranda
    service: switch.turn_off
- id: '1548788131090'
  alias: Lys entre on
  trigger:
  - entity_id: binary_sensor.pir_entre
    from: 'off'
    platform: state
    to: 'on'
  condition: []
  action:
  - data:
      entity_id: light.entre
    service: light.turn_on
- id: '1548788215043'
  alias: Lys entre off
  trigger:
  - entity_id: binary_sensor.pir_entre
    for: 00:02:00
    from: 'on'
    platform: state
    to: 'off'
  condition: []
  action:
  - data:
      entity_id: light.entre
    service: light.turn_off
- id: '1548788618767'
  alias: Lys bua off
  trigger:
  - entity_id: binary_sensor.pir_bua
    for: 00:15:00
    from: 'off'
    platform: state
    to: 'on'
  condition: []
  action:
  - data:
      entity_id: light.bua
    service: light.turn_off
- id: '1548795677739'
  alias: Lys bua on
  trigger:
  - entity_id: binary_sensor.pir_bua
    from: 'off'
    platform: state
    to: 'on'
  condition: []
  action:
  - data:
      entity_id: light.bua
    service: light.turn_on
- id: '1548795749589'
  alias: Lys aquarium off
  trigger:
  - at: '23:00:00'
    platform: time
  condition: []
  action:
  - data:
      entity_id: switch.lys_aquarium
    service: switch.turn_off
- id: '1548795820146'
  alias: Lys aquarium on
  trigger:
  - at: '12:00:00'
    platform: time
  condition: []
  action:
  - data:
      entity_id: switch.lys_aquarium
    service: switch.turn_on
- id: '1548795943113'
  alias: Lys kitchen on 7-23
  trigger:
  - entity_id: binary_sensor.pir_kitchen
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: 07:00:00
    before: '22:59:59'
    condition: time
  action:
  - data:
      brightness_pct: 100
      entity_id: light.kitchen
    service: light.turn_on
- id: '1548796005176'
  alias: Lys kitchen off
  trigger:
  - entity_id: binary_sensor.pir_kitchen
    for: 00:15:00
    from: 'on'
    platform: state
    to: 'off'
  condition: []
  action:
  - data:
      entity_id: light.kitchen
    service: light.turn_off
- id: '1548796266933'
  alias: Lys gang on 00-07
  trigger:
  - entity_id: binary_sensor.pir_gang
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: 00:00:00
    before: 06:59:59
    condition: time
  action:
  - data:
      brightness_pct: 10
      entity_id: light.gang
    service: light.turn_on
- id: '1548796332064'
  alias: Lys gang off
  trigger:
  - entity_id: binary_sensor.pir_gang
    for: 00:05:00
    from: 'on'
    platform: state
    to: 'off'
  condition: []
  action:
  - data:
      entity_id: light.gang
    service: light.turn_off
- id: '1548796401433'
  alias: Lys spisestue on 7-23
  trigger:
  - entity_id: binary_sensor.pir_stueost
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: 07:00:00
    before: '23:00:00'
    condition: time
  action:
  - data:
      brightness_pct: 100
      entity_id: light.spisestue
    service: light.turn_on
- id: '1548796464197'
  alias: Lys spsiestue off
  trigger:
  - entity_id: binary_sensor.pir_stueost
    for: 00:30:00
    from: 'on'
    platform: state
    to: 'off'
  condition: []
  action:
  - data:
      entity_id: light.spisestue
    service: light.turn_off
- id: '1548796566142'
  alias: Lys bod on
  trigger:
  - entity_id: binary_sensor.pir_bod
    from: 'off'
    platform: state
    to: 'on'
  condition: []
  action:
  - data:
      entity_id: light.bod
    service: light.turn_on
- id: '1548796621769'
  alias: Lys bod off
  trigger:
  - entity_id: binary_sensor.pir_bod
    for: 00:02:00
    from: 'on'
    platform: state
    to: 'off'
  condition: []
  action:
  - data:
      entity_id: light.bod
    service: light.turn_off
- id: '1548796686594'
  alias: Lys sauna on
  trigger:
  - entity_id: switch.ovn_sauna
    from: 'off'
    platform: state
    to: 'on'
  condition: []
  action:
  - data:
      entity_id: light.sauna
    service: light.turn_on
- id: '1548796746841'
  alias: Lys sauna off
  trigger:
  - entity_id: switch.ovn_sauna
    from: 'on'
    platform: state
    to: 'off'
  condition: []
  action:
  - data:
      entity_id: light.sauna
    service: light.turn_off
- id: '1548796819052'
  alias: Sauna auto off
  trigger:
  - entity_id: switch.ovn_sauna
    for: 04:00:00
    from: 'off'
    platform: state
    to: 'on'
  condition: []
  action:
  - data:
      entity_id: switch.ovn_sauna
    service: switch.turn_off
- id: '1548844649466'
  alias: Lys toalett on day
  trigger:
  - entity_id: binary_sensor.pir_toalett
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: 08:00:00
    before: '23:59:59'
    condition: time
  action:
  - data:
      brightness: 100
      entity_id: light.toalett
    service: light.turn_on
- id: '1548844718862'
  alias: Lys toalett off
  trigger:
  - entity_id: binary_sensor.pir_toalett
    for: 00:05:00
    from: 'on'
    platform: state
    to: 'off'
  condition: []
  action:
  - data:
      entity_id: light.toalett
    service: light.turn_off
- id: '1548899908163'
  alias: Lys gang on 07-24
  trigger:
  - entity_id: binary_sensor.pir_gang
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: 07:00:00
    before: '23:59:59'
    condition: time
  action:
  - data:
      brightness_pct: 100
      entity_id: light.gang
    service: light.turn_on
- id: '1548900953061'
  alias: Lys spisestue 23-7
  trigger:
  - entity_id: binary_sensor.pir_stueost
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: '23:00:00'
    before: 06:59:59
    condition: time
  action:
  - data:
      brightness_pct: 10
      entity_id: light.spisestue
    service: light.turn_on
- id: '1548901107723'
  alias: Lys kitchen 23-7
  trigger:
  - entity_id: binary_sensor.pir_stueost
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: '23:00:00'
    before: 06:59:59
    condition: time
  action:
  - data:
      brightness_pct: 10
      entity_id: light.kitchen
    service: light.turn_on
- id: '1548965122964'
  alias: Lys toalett on night
  trigger:
  - entity_id: binary_sensor.pir_toalett
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: 00:00:00
    before: 07:59:59
    condition: time
  action:
  - data:
      brightness_pct: 10
      entity_id: light_toalett
    service: light.turn_on

Traceback (if applicable):

Additional information: I placed this by error in the home-assistant-polymer, but closed it and reposted here.

amelchio commented 5 years ago

I am quite sure that this is not broken but your configuration dump is a lot to digest.

Can you cut your automations.yaml down to just two entries, verify that they do not fire and then post them here?

thebradleysanders commented 5 years ago

Try changing:

- data:
     brightness_pct: 10
     entity_id: light_toalett
     service: light.turn_on

TO:

- service: light.turn_on
   data:
      brightness_pct: 10
      entity_id: light_toalett
thebradleysanders commented 5 years ago

It might also help if you prefix your light entities with the light domain example: light.light_toalett

2rs commented 5 years ago

I am quite sure that this is not broken but your configuration dump is a lot to digest.

Can you cut your automations.yaml down to just two entries, verify that they do not fire and then post them here?

The two of them together, with brightness, does not fire. But if just one of them has brightness they do fire.

- id: '1548796401433'
  alias: Lys spisestue on 7-23
  trigger:
  - entity_id: binary_sensor.pir_stueost
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: 07:00:00
    before: '23:00:00'
    condition: time
  action:
  - data:
      brightness_pct: 100
      entity_id: light.spisestue
    service: light.turn_on
- id: '1548900953061'
  alias: Lys spisestue 23-7
  trigger:
  - entity_id: binary_sensor.pir_stueost
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: '23:00:00'
    before: 06:59:59
    condition: time
  action:
  - data:
      brightness_pct: 10
      entity_id: light.spisestue
    service: light.turn_on
amelchio commented 5 years ago

I did minimal changes to that and it does work for me (turning on one light will turn on the other).

Do the automations work if you trigger them by hand in the frontend UI?

automation issue20976:
- id: '1548796401433'
  alias: Lys spisestue on 7-23
  trigger:
  - entity_id: light.krea
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: 07:00:00
    before: '23:00:00'
    condition: time
  action:
  - data:
      brightness_pct: 100
      entity_id: light.kontor
    service: light.turn_on
- id: '1548900953061'
  alias: Lys spisestue 23-7
  trigger:
  - entity_id: light.krea
    from: 'off'
    platform: state
    to: 'on'
  condition:
  - after: '23:00:00'
    before: 06:59:59
    condition: time
  action:
  - data:
      brightness_pct: 10
      entity_id: light.kontor
    service: light.turn_on
2rs commented 5 years ago

Try changing:

- data:
     brightness_pct: 10
     entity_id: light_toalett
     service: light.turn_on

TO:

- service: light.turn_on
   data:
      brightness_pct: 10
      entity_id: light_toalett

This did not change the behaviour.

2rs commented 5 years ago

Do the automations work if you trigger them by hand in the frontend UI?

This is somewhat strange. After having realoded the automations it does not work if I first trigger the 00-07 by hand in the frontend, but if I first trigger the 07-24, which do work, thereafter triggering the 00-07 does work. And I can keep on juggeling between the 07-24 and 00-07.

I tried to change my automations to trigger by the state of a light rather than by the binary_sensor of a z-wave PIR, like you did, but it does not work. And to add to the confusion, now it does not even work with brightness in just one of the automations. I verified that it does work without brightness on both.

amelchio commented 5 years ago

So this does not turn on the light or what exactly does "does not work" mean?

 action:
  - data:
      brightness_pct: 10
      entity_id: light.spisestue
    service: light.turn_on
2rs commented 5 years ago

So this does not turn on the light or what exactly does "does not work" mean?

 action:
  - data:
      brightness_pct: 10
      entity_id: light.spisestue
    service: light.turn_on

That does not turn on the light if triggered by an automation, and there is a second automation triggered by the same state also with a brightness value.

if triggered manually i discovered that if I tried to trigger the automation 00-07 first then it would not trigger, but if I triggered the 07-24 first then the 00-07would trigger,

amelchio commented 5 years ago

if triggered manually i discovered that if I tried to trigger the automation 00-07 first then it would not trigger, but if I triggered the 07-24 first then the 00-07would trigger,

To keep it simpler, let's stick to this manual case.

Again, what does this mean exactly? Is the light off and it stays off? In both situations?

To simplify further: do you still see the issue if you move the action out of the automation and into a script?

2rs commented 5 years ago

if triggered manually i discovered that if I tried to trigger the automation 00-07 first then it would not trigger, but if I triggered the 07-24 first then the 00-07would trigger,

To keep it simpler, let's stick to this manual case.

Again, what does this mean exactly? Is the light off and it stays off? In both situations?

To simplify further: do you still see the issue if you move the action out of the automation and into a script?

It does not work when triggered by the PIR. The light stays off. I have changed the time settings so that i could check both withing a short timeframe, If the 00-07 is triggered manually, and it is the first that is triggered after a reload of automations it does not turn on light. But if the 7-24 has been triggered first manually, which does turn on light, then the 00-07 also turns on light and those two can be triggered manually in turns and does turn on the light at the correct brightness.

I have not tried this in a script. I want to keep things easy maintainable and only use the automations editor. Even though I have worked directly in the automations.yaml for testing and sorting out this issue.

amelchio commented 5 years ago

Rather than a script you can do light.turn_on from the Services Developer Tool, with data:

{
  "entity_id": "light.spisestue", "brightness_pct":10
}

This should be the same as triggering the automation but it is a much smaller test to debug.

Also try to enable debug logging and see what is logged when you trigger the automation and nothing happens, by adding this to configuration.yaml:

logger:
  default: debug
2rs commented 5 years ago

Turning on light vith a brightness value in the SDT does work. On several different entities and with different values.

As I have tried to explain the problem appears when there are two automations on the same trigger, with different time conditions, and a brightness value.

I don't know how else to explain it. But my laymans approach would be that this has to do with how things in the automations file is being treated.

2rs commented 5 years ago

Also try to enable debug logging and see what is logged when you trigger the automation and nothing happens, by adding this to configuration.yaml:

I think these are the relevant log entries. Or at least within the period from when i triggered the PIR all entries with tradfri until a different zwave node appears in the log.

2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.pir_gang, old_state=<state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=4, receivedDups=0, receivedUnsolicited=4, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:16:27:358 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>, new_state=<state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=5, receivedDups=0, receivedUnsolicited=5, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:19:07:998 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>>
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.pir_gang_alarm_type, old_state=<state sensor.pir_gang_alarm_type=0; node_id=9, value_index=0, value_instance=1, value_id=72057594194968577, unit_of_measurement=, friendly_name=PIR gang Alarm Type, hidden=True @ 2019-02-17T20:16:26.977647+01:00>, new_state=<state sensor.pir_gang_alarm_type=0; node_id=9, value_index=0, value_instance=1, value_id=72057594194968577, unit_of_measurement=, friendly_name=PIR gang Alarm Type, hidden=True @ 2019-02-17T20:19:08.247707+01:00>>
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1872301200] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'zwave.pir_gang', 'old_state': <state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=4, receivedDups=0, receivedUnsolicited=4, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:16:27:358 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>, 'new_state': <state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=5, receivedDups=0, receivedUnsolicited=5, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:19:07:998 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 218762, tzinfo=<UTC>), 'context': {'id': '5ab17fa93a4142f08668255250802f61', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : end
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : new notification
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1872301200] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.pir_gang_alarm_type', 'old_state': <state sensor.pir_gang_alarm_type=0; node_id=9, value_index=0, value_instance=1, value_id=72057594194968577, unit_of_measurement=, friendly_name=PIR gang Alarm Type, hidden=True @ 2019-02-17T20:16:26.977647+01:00>, 'new_state': <state sensor.pir_gang_alarm_type=0; node_id=9, value_index=0, value_instance=1, value_id=72057594194968577, unit_of_measurement=, friendly_name=PIR gang Alarm Type, hidden=True @ 2019-02-17T20:19:08.247707+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 247887, tzinfo=<UTC>), 'context': {'id': '30d3b51998924e01ad841fe5fcc0792a', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : Notification type : 2, nodeId : 9
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] addValueId : ValueID : 72057594194968609
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1841852816] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'zwave.pir_gang', 'old_state': <state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=4, receivedDups=0, receivedUnsolicited=4, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:16:27:358 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>, 'new_state': <state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=5, receivedDups=0, receivedUnsolicited=5, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:19:07:998 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 218762, tzinfo=<UTC>), 'context': {'id': '5ab17fa93a4142f08668255250802f61', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] addValueId : GetCommandClassId : 113, GetType : 1
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] addValueId : Notification : {'notificationType': 'ValueChanged', 'homeId': 3762115821, 'nodeId': 9, 'valueId': {'homeId': 3762115821, 'nodeId': 9, 'commandClass': 'COMMAND_CLASS_ALARM', 'instance': 1, 'index': 2, 'id': 72057594194968609, 'genre': 'User', 'type': 'Byte', 'value': 0, 'label': 'SourceNodeId', 'units': '', 'readOnly': True}}
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1841852816] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.pir_gang_alarm_type', 'old_state': <state sensor.pir_gang_alarm_type=0; node_id=9, value_index=0, value_instance=1, value_id=72057594194968577, unit_of_measurement=, friendly_name=PIR gang Alarm Type, hidden=True @ 2019-02-17T20:16:26.977647+01:00>, 'new_state': <state sensor.pir_gang_alarm_type=0; node_id=9, value_index=0, value_instance=1, value_id=72057594194968577, unit_of_measurement=, friendly_name=PIR gang Alarm Type, hidden=True @ 2019-02-17T20:19:08.247707+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 247887, tzinfo=<UTC>), 'context': {'id': '30d3b51998924e01ad841fe5fcc0792a', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : call callback context
2019-02-17 20:19:08 DEBUG (Dummy-6) [openzwave] zwcallback args=[{'notificationType': 'ValueChanged', 'homeId': 3762115821, 'nodeId': 9, 'valueId': {'homeId': 3762115821, 'nodeId': 9, 'commandClass': 'COMMAND_CLASS_ALARM', 'instance': 1, 'index': 2, 'id': 72057594194968609, 'genre': 'User', 'type': 'Byte', 'value': 0, 'label': 'SourceNodeId', 'units': '', 'readOnly': True}}]
2019-02-17 20:19:08 DEBUG (Dummy-6) [openzwave] Z-Wave Notification ValueChanged : {'notificationType': 'ValueChanged', 'homeId': 3762115821, 'nodeId': 9, 'valueId': {'homeId': 3762115821, 'nodeId': 9, 'commandClass': 'COMMAND_CLASS_ALARM', 'instance': 1, 'index': 2, 'id': 72057594194968609, 'genre': 'User', 'type': 'Byte', 'value': 0, 'label': 'SourceNodeId', 'units': '', 'readOnly': True}}
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6dc877d0: Type.ACK 2.05 Content (ID 23017, token b'\x00\x00*\xc1') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 125 byte(s) payload>
2019-02-17 20:19:08 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Exchange removed, message ID: 23017.
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6dc877d0: Type.ACK 2.05 Content (ID 23017, token b'\x00\x00*\xc1') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 125 byte(s) payload>
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.pir_gang_alarm_level, old_state=<state sensor.pir_gang_alarm_level=0; node_id=9, value_index=1, value_instance=1, value_id=72057594194968593, unit_of_measurement=, friendly_name=PIR gang Alarm Level, hidden=True @ 2019-02-17T20:16:27.393009+01:00>, new_state=<state sensor.pir_gang_alarm_level=0; node_id=9, value_index=1, value_instance=1, value_id=72057594194968593, unit_of_measurement=, friendly_name=PIR gang Alarm Level, hidden=True @ 2019-02-17T20:19:08.410039+01:00>>
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : end
2019-02-17 20:19:08 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Toalett","9002":1515468655,"9003":183005,"5850":0,"5851":0,"9039":227453,"9108":0,"9018":{"15002":{"9003":[65541]}}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : new notification
2019-02-17 20:19:08 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 183007]
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : Notification type : 2, nodeId : 9
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1872301200] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.pir_gang_alarm_level', 'old_state': <state sensor.pir_gang_alarm_level=0; node_id=9, value_index=1, value_instance=1, value_id=72057594194968593, unit_of_measurement=, friendly_name=PIR gang Alarm Level, hidden=True @ 2019-02-17T20:16:27.393009+01:00>, 'new_state': <state sensor.pir_gang_alarm_level=0; node_id=9, value_index=1, value_instance=1, value_id=72057594194968593, unit_of_measurement=, friendly_name=PIR gang Alarm Level, hidden=True @ 2019-02-17T20:19:08.410039+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 410237, tzinfo=<UTC>), 'context': {'id': '016feebb6bb34bfca97714b3093e87aa', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] addValueId : ValueID : 72057594194968737
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] addValueId : GetCommandClassId : 113, GetType : 1
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] addValueId : Notification : {'notificationType': 'ValueChanged', 'homeId': 3762115821, 'nodeId': 9, 'valueId': {'homeId': 3762115821, 'nodeId': 9, 'commandClass': 'COMMAND_CLASS_ALARM', 'instance': 1, 'index': 10, 'id': 72057594194968737, 'genre': 'User', 'type': 'Byte', 'value': 8, 'label': 'Burglar', 'units': '', 'readOnly': True}}
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1841852816] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.pir_gang_alarm_level', 'old_state': <state sensor.pir_gang_alarm_level=0; node_id=9, value_index=1, value_instance=1, value_id=72057594194968593, unit_of_measurement=, friendly_name=PIR gang Alarm Level, hidden=True @ 2019-02-17T20:16:27.393009+01:00>, 'new_state': <state sensor.pir_gang_alarm_level=0; node_id=9, value_index=1, value_instance=1, value_id=72057594194968593, unit_of_measurement=, friendly_name=PIR gang Alarm Level, hidden=True @ 2019-02-17T20:19:08.410039+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 410237, tzinfo=<UTC>), 'context': {'id': '016feebb6bb34bfca97714b3093e87aa', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : call callback context
2019-02-17 20:19:08 DEBUG (Dummy-6) [openzwave] zwcallback args=[{'notificationType': 'ValueChanged', 'homeId': 3762115821, 'nodeId': 9, 'valueId': {'homeId': 3762115821, 'nodeId': 9, 'commandClass': 'COMMAND_CLASS_ALARM', 'instance': 1, 'index': 10, 'id': 72057594194968737, 'genre': 'User', 'type': 'Byte', 'value': 8, 'label': 'Burglar', 'units': '', 'readOnly': True}}]
2019-02-17 20:19:08 DEBUG (Dummy-6) [openzwave] Z-Wave Notification ValueChanged : {'notificationType': 'ValueChanged', 'homeId': 3762115821, 'nodeId': 9, 'valueId': {'homeId': 3762115821, 'nodeId': 9, 'commandClass': 'COMMAND_CLASS_ALARM', 'instance': 1, 'index': 10, 'id': 72057594194968737, 'genre': 'User', 'type': 'Byte', 'value': 8, 'label': 'Burglar', 'units': '', 'readOnly': True}}
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6ef59c50: Type.CON GET (ID 23018, token b'\x00\x00*\xc2') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Exchange added, message ID: 23018.
2019-02-17 20:19:08 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:08 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002ac2, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.pir_gang_sourcenodeid, old_state=<state sensor.pir_gang_sourcenodeid=0; node_id=9, value_index=2, value_instance=1, value_id=72057594194968609, unit_of_measurement=, friendly_name=PIR gang SourceNodeId, hidden=True @ 2019-02-17T20:16:27.420321+01:00>, new_state=<state sensor.pir_gang_sourcenodeid=0; node_id=9, value_index=2, value_instance=1, value_id=72057594194968609, unit_of_measurement=, friendly_name=PIR gang SourceNodeId, hidden=True @ 2019-02-17T20:19:08.550047+01:00>>
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : end
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1872301200] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.pir_gang_sourcenodeid', 'old_state': <state sensor.pir_gang_sourcenodeid=0; node_id=9, value_index=2, value_instance=1, value_id=72057594194968609, unit_of_measurement=, friendly_name=PIR gang SourceNodeId, hidden=True @ 2019-02-17T20:16:27.420321+01:00>, 'new_state': <state sensor.pir_gang_sourcenodeid=0; node_id=9, value_index=2, value_instance=1, value_id=72057594194968609, unit_of_measurement=, friendly_name=PIR gang SourceNodeId, hidden=True @ 2019-02-17T20:19:08.550047+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 550244, tzinfo=<UTC>), 'context': {'id': '2ad886c484f241f4af7ba85ec1abde80', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : new notification
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : Notification type : 2, nodeId : 9
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1841852816] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.pir_gang_sourcenodeid', 'old_state': <state sensor.pir_gang_sourcenodeid=0; node_id=9, value_index=2, value_instance=1, value_id=72057594194968609, unit_of_measurement=, friendly_name=PIR gang SourceNodeId, hidden=True @ 2019-02-17T20:16:27.420321+01:00>, 'new_state': <state sensor.pir_gang_sourcenodeid=0; node_id=9, value_index=2, value_instance=1, value_id=72057594194968609, unit_of_measurement=, friendly_name=PIR gang SourceNodeId, hidden=True @ 2019-02-17T20:19:08.550047+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 550244, tzinfo=<UTC>), 'context': {'id': '2ad886c484f241f4af7ba85ec1abde80', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] addValueId : ValueID : 72057594193903616
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] addValueId : GetCommandClassId : 48, GetType : 0
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] addValueId : Notification : {'notificationType': 'ValueChanged', 'homeId': 3762115821, 'nodeId': 9, 'valueId': {'homeId': 3762115821, 'nodeId': 9, 'commandClass': 'COMMAND_CLASS_SENSOR_BINARY', 'instance': 1, 'index': 0, 'id': 72057594193903616, 'genre': 'User', 'type': 'Bool', 'value': True, 'label': 'Sensor', 'units': '', 'readOnly': True}}
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : call callback context
2019-02-17 20:19:08 DEBUG (Dummy-6) [openzwave] zwcallback args=[{'notificationType': 'ValueChanged', 'homeId': 3762115821, 'nodeId': 9, 'valueId': {'homeId': 3762115821, 'nodeId': 9, 'commandClass': 'COMMAND_CLASS_SENSOR_BINARY', 'instance': 1, 'index': 0, 'id': 72057594193903616, 'genre': 'User', 'type': 'Bool', 'value': True, 'label': 'Sensor', 'units': '', 'readOnly': True}}]
2019-02-17 20:19:08 DEBUG (Dummy-6) [openzwave] Z-Wave Notification ValueChanged : {'notificationType': 'ValueChanged', 'homeId': 3762115821, 'nodeId': 9, 'valueId': {'homeId': 3762115821, 'nodeId': 9, 'commandClass': 'COMMAND_CLASS_SENSOR_BINARY', 'instance': 1, 'index': 0, 'id': 72057594193903616, 'genre': 'User', 'type': 'Bool', 'value': True, 'label': 'Sensor', 'units': '', 'readOnly': True}}
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6f76d710: Type.ACK 2.05 Content (ID 23018, token b'\x00\x00*\xc2') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 121 byte(s) payload>
2019-02-17 20:19:08 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Exchange removed, message ID: 23018.
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6f76d710: Type.ACK 2.05 Content (ID 23018, token b'\x00\x00*\xc2') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 121 byte(s) payload>
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.pir_gang_burglar, old_state=<state sensor.pir_gang_burglar=0; node_id=9, value_index=10, value_instance=1, value_id=72057594194968737, unit_of_measurement=, friendly_name=PIR gang Burglar, hidden=True @ 2019-02-17T20:16:27.434278+01:00>, new_state=<state sensor.pir_gang_burglar=8; node_id=9, value_index=10, value_instance=1, value_id=72057594194968737, unit_of_measurement=, friendly_name=PIR gang Burglar, hidden=True @ 2019-02-17T20:19:08.687269+01:00>>
2019-02-17 20:19:08 DEBUG (Dummy-6) [libopenzwave] notif_callback : end
2019-02-17 20:19:08 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Bua","9002":1517409080,"9003":183007,"5850":0,"5851":0,"9039":227461,"9108":0,"9018":{"15002":{"9003":[65569]}}}
2019-02-17 20:19:08 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 183008]
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1872301200] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.pir_gang_burglar', 'old_state': <state sensor.pir_gang_burglar=0; node_id=9, value_index=10, value_instance=1, value_id=72057594194968737, unit_of_measurement=, friendly_name=PIR gang Burglar, hidden=True @ 2019-02-17T20:16:27.434278+01:00>, 'new_state': <state sensor.pir_gang_burglar=8; node_id=9, value_index=10, value_instance=1, value_id=72057594194968737, unit_of_measurement=, friendly_name=PIR gang Burglar, hidden=True @ 2019-02-17T20:19:08.687269+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 687461, tzinfo=<UTC>), 'context': {'id': 'c27767ebe0df47c2b3757d8f2434d3cb', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1841852816] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.pir_gang_burglar', 'old_state': <state sensor.pir_gang_burglar=0; node_id=9, value_index=10, value_instance=1, value_id=72057594194968737, unit_of_measurement=, friendly_name=PIR gang Burglar, hidden=True @ 2019-02-17T20:16:27.434278+01:00>, 'new_state': <state sensor.pir_gang_burglar=8; node_id=9, value_index=10, value_instance=1, value_id=72057594194968737, unit_of_measurement=, friendly_name=PIR gang Burglar, hidden=True @ 2019-02-17T20:19:08.687269+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 687461, tzinfo=<UTC>), 'context': {'id': 'c27767ebe0df47c2b3757d8f2434d3cb', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6f7e05f0: Type.CON GET (ID 23019, token b'\x00\x00*\xc3') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Exchange added, message ID: 23019.
2019-02-17 20:19:08 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:08 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002ac3, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.pir_gang, old_state=<state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=5, receivedDups=0, receivedUnsolicited=5, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:19:07:998 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>, new_state=<state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=6, receivedDups=0, receivedUnsolicited=6, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:19:08:575 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>>
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.pir_gang, old_state=<state binary_sensor.pir_gang=off; node_id=9, value_index=0, value_instance=1, value_id=72057594193903616, friendly_name=PIR gang Sensor @ 2019-02-17T20:16:28.015767+01:00>, new_state=<state binary_sensor.pir_gang=on; node_id=9, value_index=0, value_instance=1, value_id=72057594193903616, friendly_name=PIR gang Sensor @ 2019-02-17T20:19:08.817908+01:00>>
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6f76d6d0: Type.ACK 2.05 Content (ID 23019, token b'\x00\x00*\xc3') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 127 byte(s) payload>
2019-02-17 20:19:08 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Exchange removed, message ID: 23019.
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6f76d6d0: Type.ACK 2.05 Content (ID 23019, token b'\x00\x00*\xc3') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 127 byte(s) payload>
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1872301200] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'zwave.pir_gang', 'old_state': <state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=5, receivedDups=0, receivedUnsolicited=5, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:19:07:998 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>, 'new_state': <state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=6, receivedDups=0, receivedUnsolicited=6, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:19:08:575 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 803643, tzinfo=<UTC>), 'context': {'id': 'bb91830808874b66acce3037206ae0d1', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1872301200] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'binary_sensor.pir_gang', 'old_state': <state binary_sensor.pir_gang=off; node_id=9, value_index=0, value_instance=1, value_id=72057594193903616, friendly_name=PIR gang Sensor @ 2019-02-17T20:16:28.015767+01:00>, 'new_state': <state binary_sensor.pir_gang=on; node_id=9, value_index=0, value_instance=1, value_id=72057594193903616, friendly_name=PIR gang Sensor @ 2019-02-17T20:19:08.817908+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 818099, tzinfo=<UTC>), 'context': {'id': 'f00660f2486b4b25a6032d9b8486fe8d', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1841852816] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'zwave.pir_gang', 'old_state': <state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=5, receivedDups=0, receivedUnsolicited=5, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:19:07:998 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>, 'new_state': <state zwave.pir_gang=initializing; node_id=9, node_name=PIR gang, manufacturer_name=PIR, product_name=gang, query_stage=CacheLoad, is_awake=False, is_ready=False, is_failed=False, is_info_received=True, max_baud_rate=40000, is_zwave_plus=True, capabilities={'beaming', 'zwave_plus', 'routing'}, sentCnt=1, sentFailed=1, retries=0, receivedCnt=6, receivedDups=0, receivedUnsolicited=6, sentTS=2019-02-17 20:15:04:840 , receivedTS=2019-02-17 20:19:08:575 , lastRequestRTT=0, averageRequestRTT=0, lastResponseRTT=0, averageResponseRTT=0, battery_level=83, wake_up_interval=43200, friendly_name=PIR gang (9) @ 2019-02-17T20:04:57.159499+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 803643, tzinfo=<UTC>), 'context': {'id': 'bb91830808874b66acce3037206ae0d1', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1841852816] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'binary_sensor.pir_gang', 'old_state': <state binary_sensor.pir_gang=off; node_id=9, value_index=0, value_instance=1, value_id=72057594193903616, friendly_name=PIR gang Sensor @ 2019-02-17T20:16:28.015767+01:00>, 'new_state': <state binary_sensor.pir_gang=on; node_id=9, value_index=0, value_instance=1, value_id=72057594193903616, friendly_name=PIR gang Sensor @ 2019-02-17T20:19:08.817908+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 8, 818099, tzinfo=<UTC>), 'context': {'id': 'f00660f2486b4b25a6032d9b8486fe8d', 'user_id': None}}}
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event automation_triggered[L]: name=Lys gang on 07-24, entity_id=automation.lys_gang_on_07_24>
2019-02-17 20:19:08 INFO (MainThread) [homeassistant.components.automation] Executing Lys gang on 07-24
2019-02-17 20:19:08 INFO (MainThread) [homeassistant.helpers.script] Script Lys gang on 07-24: Running script
2019-02-17 20:19:08 INFO (MainThread) [homeassistant.helpers.script] Script Lys gang on 07-24: Executing step call service
2019-02-17 20:19:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=brightness_pct=100, entity_id=light.gang>
2019-02-17 20:19:08 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Plantelys","9002":1546892197,"9003":183008,"5850":1,"5851":0,"9039":227464,"9108":0,"9018":{"15002":{"9003":[65556]}}}
2019-02-17 20:19:08 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 put ['15004', 183001]: {'5851': 254}
2019-02-17 20:19:08 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 180975]
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6f826110: Type.CON PUT (ID 23020, token b'\x00\x00*\xc4') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s), 13 byte(s) payload>
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Exchange added, message ID: 23020.
2019-02-17 20:19:08 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:08 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002ac4, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:08 DEBUG (MainThread) [coap] Message to <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470> put into backlog
2019-02-17 20:19:08 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:08 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002ac5, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6ef683d0: Type.ACK 2.04 Changed (ID 23020, token b'\x00\x00*\xc4') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange removed, message ID: 23020.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6f76d1b0: Type.CON GET (ID 23021, token b'\x00\x00*\xc5') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange added, message ID: 23021.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6ef683d0: Type.ACK 2.04 Changed (ID 23020, token b'\x00\x00*\xc4') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>>
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.04 Changed, Received: 
2019-02-17 20:19:09 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=automation.lys_gang_on_07_24, old_state=<state automation.lys_gang_on_07_24=on; last_triggered=2019-02-17T20:15:56.684536+01:00, id=1548899908163, friendly_name=Lys gang on 07-24 @ 2019-02-17T20:03:46.055430+01:00>, new_state=<state automation.lys_gang_on_07_24=on; last_triggered=2019-02-17T20:19:09.025239+01:00, id=1548899908163, friendly_name=Lys gang on 07-24 @ 2019-02-17T20:03:46.055430+01:00>>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x755b7570: Type.ACK 2.05 Content (ID 23021, token b'\x00\x00*\xc5') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 129 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange removed, message ID: 23021.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x755b7570: Type.ACK 2.05 Content (ID 23021, token b'\x00\x00*\xc5') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 129 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1872301200] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'automation.lys_gang_on_07_24', 'old_state': <state automation.lys_gang_on_07_24=on; last_triggered=2019-02-17T20:15:56.684536+01:00, id=1548899908163, friendly_name=Lys gang on 07-24 @ 2019-02-17T20:03:46.055430+01:00>, 'new_state': <state automation.lys_gang_on_07_24=on; last_triggered=2019-02-17T20:19:09.025239+01:00, id=1548899908163, friendly_name=Lys gang on 07-24 @ 2019-02-17T20:03:46.055430+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 9, 26270, tzinfo=<UTC>), 'context': {'id': 'f00660f2486b4b25a6032d9b8486fe8d', 'user_id': None}}}
2019-02-17 20:19:09 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.1841852816] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'automation.lys_gang_on_07_24', 'old_state': <state automation.lys_gang_on_07_24=on; last_triggered=2019-02-17T20:15:56.684536+01:00, id=1548899908163, friendly_name=Lys gang on 07-24 @ 2019-02-17T20:03:46.055430+01:00>, 'new_state': <state automation.lys_gang_on_07_24=on; last_triggered=2019-02-17T20:19:09.025239+01:00, id=1548899908163, friendly_name=Lys gang on 07-24 @ 2019-02-17T20:03:46.055430+01:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2019, 2, 17, 19, 19, 9, 26270, tzinfo=<UTC>), 'context': {'id': 'f00660f2486b4b25a6032d9b8486fe8d', 'user_id': None}}}
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Entre","9002":1508789687,"9003":180975,"5850":0,"5851":0,"9039":202613,"9108":0,"9018":{"15002":{"9003":[65542,65543]}}}
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 183002]
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6ef681d0: Type.CON GET (ID 23022, token b'\x00\x00*\xc6') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange added, message ID: 23022.
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002ac6, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6f76d250: Type.ACK 2.05 Content (ID 23022, token b'\x00\x00*\xc6') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 123 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange removed, message ID: 23022.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6f76d250: Type.ACK 2.05 Content (ID 23022, token b'\x00\x00*\xc6') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 123 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Sauna","9002":1514980308,"9003":183002,"5850":0,"5851":0,"9039":227443,"9108":0,"9018":{"15002":{"9003":[65566]}}}
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 152945]
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6f7f12b0: Type.CON GET (ID 23023, token b'\x00\x00*\xc7') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange added, message ID: 23023.
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002ac7, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6ef5b0f0: Type.ACK 2.05 Content (ID 23023, token b'\x00\x00*\xc7') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 149 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange removed, message ID: 23023.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6ef5b0f0: Type.ACK 2.05 Content (ID 23023, token b'\x00\x00*\xc7') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 149 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Studio","9002":1497666393,"9003":152945,"5850":0,"5851":25,"9039":216460,"9108":0,"9018":{"15002":{"9003":[65536,65537,65540,65558,65557]}}}
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 183004]
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6f7e04d0: Type.CON GET (ID 23024, token b'\x00\x00*\xc8') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange added, message ID: 23024.
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002ac8, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6f75e530: Type.ACK 2.05 Content (ID 23024, token b'\x00\x00*\xc8') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 127 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange removed, message ID: 23024.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6f75e530: Type.ACK 2.05 Content (ID 23024, token b'\x00\x00*\xc8') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 127 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Kitchen","9002":1515291061,"9003":183004,"5850":1,"5851":254,"9039":227450,"9108":0,"9018":{"15002":{"9003":[65567]}}}
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 183001]
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6ef68090: Type.CON GET (ID 23025, token b'\x00\x00*\xc9') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange added, message ID: 23025.
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002ac9, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6f75e610: Type.ACK 2.05 Content (ID 23025, token b'\x00\x00*\xc9') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 154 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange removed, message ID: 23025.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6f75e610: Type.ACK 2.05 Content (ID 23025, token b'\x00\x00*\xc9') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 154 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Gang","9002":1510447189,"9003":183001,"5850":0,"5851":254,"9039":227440,"9108":0,"9018":{"15002":{"9003":[65560,65553,65551,65552,65554,65555]}}}
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 183003]
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6ed912f0: Type.CON GET (ID 23026, token b'\x00\x00*\xca') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange added, message ID: 23026.
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002aca, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6f75e770: Type.ACK 2.05 Content (ID 23026, token b'\x00\x00*\xca') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 128 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange removed, message ID: 23026.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6f75e770: Type.ACK 2.05 Content (ID 23026, token b'\x00\x00*\xca') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 128 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Dekor stue","9002":1515199912,"9003":183003,"5850":0,"5851":0,"9039":227447,"9108":0,"9018":{"15002":{"9003":[65565]}}}
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 131227]
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6ef68a70: Type.CON GET (ID 23027, token b'\x00\x00*\xcb') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange added, message ID: 23027.
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002acb, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6f75e970: Type.ACK 2.05 Content (ID 23027, token b'\x00\x00*\xcb') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 126 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange removed, message ID: 23027.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6f75e970: Type.ACK 2.05 Content (ID 23027, token b'\x00\x00*\xcb') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 126 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Terrasse","9002":1499028430,"9003":131227,"5850":0,"5851":0,"9039":221999,"9108":0,"9018":{"15002":{"9003":[65545]}}}
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Executing 192.168.1.16 get ['15004', 183006]
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Sending message <aiocoap.Message at 0x6ed835f0: Type.CON GET (ID 23028, token b'\x00\x00*\xcc') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 3 option(s)>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange added, message ID: 23028.
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Timeout is 93.0
2019-02-17 20:19:09 DEBUG (MainThread) [coap.requester] Sending request - Token: 00002acc, Remote: <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Incoming message <aiocoap.Message at 0x6f75e090: Type.ACK 2.05 Content (ID 23028, token b'\x00\x00*\xcc') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 121 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [coap] New unique message received
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Exchange removed, message ID: 23028.
2019-02-17 20:19:09 DEBUG (MainThread) [coap] Received Response: <aiocoap.Message at 0x6f75e090: Type.ACK 2.05 Content (ID 23028, token b'\x00\x00*\xcc') remote <aiocoap.transports.tinydtls.DTLSClientConnection object at 0x7346e470>, 2 option(s), 121 byte(s) payload>
2019-02-17 20:19:09 DEBUG (MainThread) [pytradfri.api.aiocoap_api] Status: 2.05 Content, Received: {"9001":"Bod","9002":1516813710,"9003":183006,"5850":0,"5851":0,"9039":227458,"9108":0,"9018":{"15002":{"9003":[65568]}}}
stale[bot] commented 5 years ago

There hasn't been any activity on this issue recently. Due to the high number of incoming GitHub notifications, we have to clean some of the old issues, as many of them have already been resolved with the latest updates. Please make sure to update to the latest Home Assistant version and check if that solves the issue. Let us know if that works for you by adding a comment 👍 This issue now has been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.