claytonjn / hass-circadian_lighting

Circadian Lighting custom component for Home Assistant
Apache License 2.0
780 stars 87 forks source link

Delay when switching my Hue lights off #90

Open gcorgnet opened 4 years ago

gcorgnet commented 4 years ago

Hi,

First of all, great job on this component. I have started looking into it last night and I am enjoying it.

I noticed a behavior which seems to be cause by the usage of this component.

I have ~13 Hue bulbs in an open space. There are divided into their own rooms (eg Lounge, Kitchen, Dinin, etc..) I also have a Hue "Zone" emcompassing all of these bulbs (let's call it Lounge_Zone). Up until know, I have been using a Hue Tab (that large button thing) to turn off all the lights if any in the Lounge_Zone is on or turn on all the lights if none of them are (Hue Essentials lets you do this kind of conditional logic) Anyway, when there's at least one light on in Lounge_Zone, the tap does a "Switch Off" of Lounge_Zone. Since having set up CL, it has introduced a noticeable delay before the lights turn off (~5-7 secs)... I can imagine the wife not happy with that... Is there anything I could be doing wrong?

here's how my config is set up, in case that helps:

switch:
  - platform: circadian_lighting
    name: Lounge Circadian
    lights_ct:
      - light.couch
      - light.dining
      - light.lounge
      - light.kitchen
      - light.kitchen_bench

Each of the above light is in fact a Hue group (made of 2, 3 or 4 lights each)

Thanks

claytonjn commented 4 years ago

How do you have CL configured - individual lights or the zones? Multiple CL switches?

I would start by looking at debug logs, they might help you better understand what's going on.

gcorgnet commented 4 years ago

haha, was just updating my initial post to include my config. only one switch for this whole areas (but there will be others for other rooms). How do I get to the debug logs?

claytonjn commented 4 years ago

I would start by making one CL switch for each independently controlled set of lights. That might mean having one switch for each of those groups, or if for example you always turn on/off kitchen and kitchen_bench have those combined in a single switch. In my use I basically have one switch for each "fixture". If the lights are always controlled with the groups listed above leave them configured as such but if for example you sometimes control the lights in the dining group independently you're probably better off listing each light in the configuration.

Debugging instructions.

gcorgnet commented 4 years ago

Thanks for that. Splitting the light groups into separate switches did not solve my delay issue. Here's a log capture, where 14:30:09 is me turning all lights on (by Activating my "Circadian" scene on hue from a Tap). At 14:30:14, it's me turning them off again.

2020-05-01 14:30:09 DEBUG (SyncWorker_9) [custom_components.circadian_lighting.switch] light.kitchen change from <state light.kitchen=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.034568+12:00> to <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.031784+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_4) [custom_components.circadian_lighting.switch] light.kitchen change from <state light.kitchen=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.034568+12:00> to <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.031784+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_1) [custom_components.circadian_lighting.switch] light.dining change from <state light.dining=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.037537+12:00> to <state light.dining=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.034538+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_0) [custom_components.circadian_lighting.switch] light.dining change from <state light.dining=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.037537+12:00> to <state light.dining=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.034538+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_3) [custom_components.circadian_lighting.switch] light.couch change from <state light.couch=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.041076+12:00> to <state light.couch=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.037149+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_4) [custom_components.circadian_lighting.switch] Lounge Circadian off - not adjusting
2020-05-01 14:30:09 DEBUG (SyncWorker_16) [custom_components.circadian_lighting.switch] light.couch change from <state light.couch=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.041076+12:00> to <state light.couch=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.037149+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_13) [custom_components.circadian_lighting.switch] light.lounge change from <state light.lounge=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.047088+12:00> to <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.046991+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_8) [custom_components.circadian_lighting.switch] light.lounge change from <state light.lounge=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.047088+12:00> to <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.046991+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_0) [custom_components.circadian_lighting.switch] Lounge Circadian off - not adjusting
2020-05-01 14:30:09 DEBUG (SyncWorker_18) [custom_components.circadian_lighting.switch] light.kitchen_bench change from <state light.kitchen_bench=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.057457+12:00> to <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.053431+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_15) [custom_components.circadian_lighting.switch] light.kitchen_bench change from <state light.kitchen_bench=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:29:39.057457+12:00> to <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.053431+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_3) [custom_components.circadian_lighting.switch] Lounge Circadian off - not adjusting
2020-05-01 14:30:09 DEBUG (SyncWorker_8) [custom_components.circadian_lighting.switch] Lounge Circadian off - not adjusting
2020-05-01 14:30:09 DEBUG (SyncWorker_18) [custom_components.circadian_lighting.switch] Lounge Circadian off - not adjusting
2020-05-01 14:30:09 DEBUG (SyncWorker_9) [custom_components.circadian_lighting.switch] light.kitchen CT Adjusted - color_temp: 200, brightness: 254, transition: 1.0
2020-05-01 14:30:09 DEBUG (SyncWorker_1) [custom_components.circadian_lighting.switch] light.dining CT Adjusted - color_temp: 200, brightness: 254, transition: 1.0
2020-05-01 14:30:09 DEBUG (SyncWorker_16) [custom_components.circadian_lighting.switch] light.couch CT Adjusted - color_temp: 200, brightness: 254, transition: 1.0
2020-05-01 14:30:09 DEBUG (SyncWorker_13) [custom_components.circadian_lighting.switch] light.lounge CT Adjusted - color_temp: 200, brightness: 254, transition: 1.0
2020-05-01 14:30:09 DEBUG (SyncWorker_15) [custom_components.circadian_lighting.switch] light.kitchen_bench CT Adjusted - color_temp: 200, brightness: 254, transition: 1.0
2020-05-01 14:30:09 DEBUG (SyncWorker_9) [custom_components.circadian_lighting.switch] light.kitchen change from <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.031784+12:00> to <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.031784+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_14) [custom_components.circadian_lighting.switch] light.kitchen change from <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.031784+12:00> to <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.031784+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_9) [custom_components.circadian_lighting.switch] light.dining change from <state light.dining=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.034538+12:00> to <state light.dining=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.034538+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_1) [custom_components.circadian_lighting.switch] light.dining change from <state light.dining=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.034538+12:00> to <state light.dining=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.034538+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_19) [custom_components.circadian_lighting.switch] light.couch change from <state light.couch=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.037149+12:00> to <state light.couch=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.037149+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_13) [custom_components.circadian_lighting.switch] light.couch change from <state light.couch=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.037149+12:00> to <state light.couch=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.037149+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_17) [custom_components.circadian_lighting.switch] light.lounge change from <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.046991+12:00> to <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.046991+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_15) [custom_components.circadian_lighting.switch] light.lounge change from <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.046991+12:00> to <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.046991+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_14) [custom_components.circadian_lighting.switch] light.kitchen_bench change from <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.053431+12:00> to <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.053431+12:00>
2020-05-01 14:30:09 DEBUG (SyncWorker_9) [custom_components.circadian_lighting.switch] light.kitchen_bench change from <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=255, color_temp=192, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.053431+12:00> to <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.053431+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_13) [custom_components.circadian_lighting.switch] light.kitchen change from <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.031784+12:00> to <state light.kitchen=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.294735+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_17) [custom_components.circadian_lighting.switch] light.kitchen change from <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.031784+12:00> to <state light.kitchen=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.294735+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_19) [custom_components.circadian_lighting.switch] light.dining change from <state light.dining=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.034538+12:00> to <state light.dining=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.297510+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_5) [custom_components.circadian_lighting.switch] light.dining change from <state light.dining=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.034538+12:00> to <state light.dining=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.297510+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_15) [custom_components.circadian_lighting.switch] light.couch change from <state light.couch=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.037149+12:00> to <state light.couch=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.300297+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_13) [custom_components.circadian_lighting.switch] light.couch change from <state light.couch=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.037149+12:00> to <state light.couch=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.300297+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_14) [custom_components.circadian_lighting.switch] light.lounge change from <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.046991+12:00> to <state light.lounge=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.303114+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_9) [custom_components.circadian_lighting.switch] light.lounge change from <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.046991+12:00> to <state light.lounge=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.303114+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_2) [custom_components.circadian_lighting.switch] light.kitchen_bench change from <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.053431+12:00> to <state light.kitchen_bench=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.316782+12:00>
2020-05-01 14:30:14 DEBUG (SyncWorker_17) [custom_components.circadian_lighting.switch] light.kitchen_bench change from <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=200, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:09.053431+12:00> to <state light.kitchen_bench=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:30:14.316782+12:00>
claytonjn commented 4 years ago

I'm not really sure what could be causing the delay based on those logs. Everything fired at the exact second you turned on/off, and none of the logs at 14:30:14 show CT adjusted so CL didn't actually do anything.

Are you 100% sure the delay is added by CL? I have over 50 Hue lights in my house mostly controlled by the Friends of Hue switches and don't notice any delays with CL vs without.

gcorgnet commented 4 years ago

Thanks for looking into it. What made me think that CL was causing this delay is that (back when I had one CL switch for the whole 13 lights) as soon as I toggled the CL switch off in HA, the delay was gone. I have also temporarily disabled the automation that's trying to update my Circadien Scenes in the bridge to avoid putting more load on it) There's nothing else I coudl think of causing this new issue.

gcorgnet commented 4 years ago

Here's another one. I pressed the "All Off" switch at the same time the INFO line appeared (so at 14:44:41). Logs from CL only started showing up a few seconds later...

2020-05-01 14:44:41 INFO (SyncWorker_10) [homeassistant.components.command_line.switch] Running state command: curl http://10.1.1.38/api/WB121cuj7dNhNYSxZ56KJ7A4NxSVsjs9SHRXEWmG/sensors/12/
2020-05-01 14:44:44 DEBUG (SyncWorker_10) [custom_components.circadian_lighting.switch] light.kitchen change from <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.217483+12:00> to <state light.kitchen=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.229125+12:00>
2020-05-01 14:44:44 DEBUG (SyncWorker_12) [custom_components.circadian_lighting.switch] light.kitchen change from <state light.kitchen=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.217483+12:00> to <state light.kitchen=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.229125+12:00>
2020-05-01 14:44:44 DEBUG (SyncWorker_7) [custom_components.circadian_lighting.switch] light.dining change from <state light.dining=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.219809+12:00> to <state light.dining=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.230731+12:00>
2020-05-01 14:44:44 DEBUG (SyncWorker_9) [custom_components.circadian_lighting.switch] light.dining change from <state light.dining=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.219809+12:00> to <state light.dining=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Dining, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.230731+12:00>
2020-05-01 14:44:44 DEBUG (SyncWorker_11) [custom_components.circadian_lighting.switch] light.couch change from <state light.couch=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.221814+12:00> to <state light.couch=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.232288+12:00>
2020-05-01 14:44:44 DEBUG (SyncWorker_10) [custom_components.circadian_lighting.switch] light.couch change from <state light.couch=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.221814+12:00> to <state light.couch=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Couch, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.232288+12:00>
2020-05-01 14:44:44 DEBUG (SyncWorker_6) [custom_components.circadian_lighting.switch] light.lounge change from <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.223865+12:00> to <state light.lounge=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.233963+12:00>
2020-05-01 14:44:44 DEBUG (SyncWorker_3) [custom_components.circadian_lighting.switch] light.lounge change from <state light.lounge=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.223865+12:00> to <state light.lounge=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Lounge, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.233963+12:00>
2020-05-01 14:44:44 DEBUG (SyncWorker_12) [custom_components.circadian_lighting.switch] light.kitchen_bench change from <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.229993+12:00> to <state light.kitchen_bench=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.239380+12:00>
2020-05-01 14:44:44 DEBUG (SyncWorker_7) [custom_components.circadian_lighting.switch] light.kitchen_bench change from <state light.kitchen_bench=on; min_mireds=153, max_mireds=500, brightness=254, color_temp=205, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:43:59.229993+12:00> to <state light.kitchen_bench=off; min_mireds=153, max_mireds=500, is_hue_group=True, friendly_name=Kitchen Bench, supported_features=43, custom_ui_state_card=state-card-custom-ui, show_last_changed=True, state_card_mode=single-line @ 2020-05-01T14:44:44.239380+12:00>

Something else must be happening in the meantime? It's interesting that the lights have no issues turning on (no delay)