alexa_media_player
alexa_media_player copied to clipboard
Unable to find service notify.alexa_media_last_called if last_called service is refreshing
This has recently started becoming an issue (within the last month or so?) but when I go to troubleshoot it minutes later, the service is there and working.
Logger: homeassistant.components.script.good_morning
Source: helpers/script.py:1718
Integration: Script (documentation, issues)
First occurred: 12:38:52 PM (2 occurrences)
Last logged: 12:38:52 PM
Good Morning: Choose at step 4: default: Error executing script. Service not found for call_service at pos 1: Unable to find service notify.alexa_media_last_called
Good Morning: Error executing script. Service not found for choose at pos 4: Unable to find service notify.alexa_media_last_called
alias: Good Morning
sequence:
- service: alexa_media.update_last_called
data: {}
- service: input_boolean.turn_off
data: {}
target:
entity_id: input_boolean.sleep_mode
- choose:
- conditions:
- condition: state
entity_id: binary_sensor.night_mode
state: 'off'
sequence:
- service: cover.set_cover_position
data:
position: '{{ states("input_number.bedroom_blind") }}'
entity_id: cover.bedroom_blind
default:
- service: light.turn_on
data:
transition: 90
brightness_pct: 70
target:
area_id: bedroom
- choose:
- conditions:
- condition: state
entity_id: input_boolean.coffee_prepared
state: 'on'
sequence:
- service: script.activate_alexa_actionable_notification
data:
text: Would you like me to start your coffee?
event_id: actionable_notification_make_coffee
alexa_device: '{{ states("sensor.last_alexa") }}'
- wait_for_trigger:
- platform: event
event_type: alexa_actionable_notification
timeout: '00:00:15'
- delay:
hours: 0
minutes: 0
seconds: 10
milliseconds: 0
- conditions:
- condition: numeric_state
entity_id: input_number.coffee
below: '18'
sequence:
- service: notify.alexa_media_last_called
data:
data:
type: tts
message: You forgot to buy coffee!
- conditions:
- condition: numeric_state
entity_id: script.bought_coffee_filters
below: '1'
sequence:
- service: notify.alexa_media_last_called
data:
data:
type: tts
message: You don't have any coffee filters!
default:
- service: notify.alexa_media_last_called
data:
message: I'm sorry, It looks like you'll have to make your own coffee
data:
type: tts
- delay:
hours: 0
minutes: 0
seconds: 6
milliseconds: 0
- service: notify.alexa_media_last_called
data:
message: >
The temperature outside is {{
states("sensor.outside_weather_temperature")|round(1)|replace('.0','')
}} {% if states("sensor.outside_weather_temperature")|replace('.0','')
!= "1" -%} degrees {%- else -%} degree {%- endif -%} {% if
states("sensor.norton_court_realfeel_temperature") != "unknown" and
states("sensor.norton_court_realfeel_temperature") !=
states("sensor.lumi_lumi_weather_temperature") %} but it feels like {{
states("sensor.norton_court_realfeel_temperature") }} {%- if
states("sensor.norton_court_realfeel_temperature")|replace('.0','') !=
"1" %} degrees.{%- else %} degree.{%- endif -%} {%- else %}. {%- endif
%} The living room is currently {{
state_attr("climate.living_room","current_temperature")|round(1,"half")|replace('.0','')
}} degrees and the heat is {%- if is_state("climate.living_room", "off")
%} turned off. {%- else %} set to {{
state_attr("climate.living_room","temperature")|round(1,"half")|replace('.0','')
}} {%- endif %}
data:
type: tts
- delay:
hours: 0
minutes: 0
seconds: 15
milliseconds: 0
- choose:
- conditions:
- condition: or
conditions:
- condition: state
entity_id: sensor.season
state: fall
- condition: state
entity_id: sensor.season
state: winter
- condition: numeric_state
entity_id: climate.living_room
attribute: temperature
below: '20'
sequence:
- service: climate.set_preset_mode
data:
preset_mode: none
entity_id: climate.living_room
- service: climate.set_temperature
data:
temperature: 20
target:
entity_id: climate.living_room
- service: notify.alexa_media_last_called
data:
message: >
I've set the heat to {{
state_attr("climate.living_room","temperature")|replace('.0','')
}}
data:
type: tts
- delay:
hours: 0
minutes: 0
seconds: 4
milliseconds: 0
default: []
- choose:
- conditions:
- condition: state
entity_id: sensor.garbage
state: '0'
- condition: state
entity_id: input_boolean.garbage_is_out
state: 'off'
- condition: time
before: '08:30:00'
weekday:
- wed
after: '00:00:00'
sequence:
- service: notify.alexa_media_last_called
data:
message: You might still have time to put the garbage out.
data:
type: tts
default: []
- choose:
- conditions:
- condition: state
entity_id: sensor.recycling
state: '0'
- condition: state
entity_id: input_boolean.recycling_is_out
state: 'off'
- condition: time
before: '08:30:00'
weekday:
- thu
- fri
after: '00:00:00'
sequence:
- service: notify.alexa_media_last_called
data:
message: You might still have time to put the recycling out
data:
type: tts
default: []
- service: input_boolean.turn_off
data: {}
target:
entity_id: input_boolean.sleep_mode
mode: single
icon: hass:weather-sunset-up
That's not really much for me to go on. Once created it should always be there. Do you have logs showing the call that is failing?
I will ramp up logging and get more info. I restarted HA today around 2 pm and around 3 pm went to the bedroom and realized I had not triggered Good Morning when I got up as the blind was still closed. I uttered Good Morning, the blind opened but the media_player sequences did not execute. I will check those logs but will dig deeper to try and get to the bottom of it. Stay tuned...
Logging was already at debug. Is there another component I should set to debug?
logger:
# default: critical
default: fatal
# default: error
# default: warning
# default: warn
# default: info
# default: debug
# default: notset
logs:
# AIOGitHubAPI: debug
# alexapy: debug
# bellows.zigbee.application: debug
# bellows.ezsp: debug
custom_components.alexa_media: debug
# custom_components.ble_monitor: info
# custom_components.entity_controller: debug
# custom_components.entity_controller.fireplace_light_coontrol: debug
# custom_components.hacs: debug
# custom_components.hass_agent_notifier: debug
# custom_components.homeseer: debug
# custom_components.magic_areas: debug
# custom_components.neviweb: debug
# custom_components.neviweb130: debug
# google.cloud.pubsub_v1: debug
# google.cloud.pubsub_v1.subscriber._protocol.leaser: debug
# google_nest_sdm: debug
# google_nest_sdm.device: debug
# google_nest_sdm.device_manager: debug
# google_nest_sdm.google_nest_subscriber: debug
# google_nest_sdm.event: debug
# google.cloud.pubsub_v1.subscriber._protocol.streaming_pull_manager: debug
# hacs: debug
# hass_nabucasa: debug
# snitun: debug
# homeassistant.components.switch: warn
# homeassistant.components.life360.device_tracker: debug
# homeassistant.components.mqtt: warn
# homeassistant.components.nest: debug
# homeassistant.components.nest.sensor_sdm: debug
# homeassistant.components.traccar.device_tracker: debug
# homeassistant.components.zha: debug
# homeassistant.components.webhook: debug
# homeassistant.core: debug
# pyhs3: debug
# queueman: debug
# zigpy: debug
# zigpy_cc: debug
# zigpy_deconz.zigbee.application: debug
# zigpy_deconz.api: debug
# zigpy_xbee.zigbee.application: debug
# zigpy_xbee.api: debug
# zigpy_zigate: debug
# zhaquirks: debug
Logger: homeassistant.components.script.good_morning
Source: helpers/script.py:1718
Integration: Script (documentation, issues)
First occurred: 5:27:43 PM (2 occurrences)
Last logged: 5:27:43 PM
Good Morning: Choose at step 4: default: Error executing script. Service not found for call_service at pos 1: Unable to find service notify.alexa_media_last_called
Good Morning: Error executing script. Service not found for choose at pos 4: Unable to find service notify.alexa_media_last_called
I need to see the logs before the function call is made. Do you have startup logs? You need to provide the full logs and not just an excerpt of the error.
Okay, what overall level should I set?
FYI. I was unsuccessful in recreating the issue tonight...
Debug is fine.
It failed this morning at 8:41 am.
This error was on my console when I sat down, which is the first time I've this one:
This error originated from a custom integration.
Logger: custom_components.alexa_media
Source: helpers/update_coordinator.py:237
Integration: Alexa Media Player (documentation, issues)
First occurred: 7:13:10 AM (1 occurrences)
Last logged: 7:13:10 AM
Error fetching alexa_media data: Error communicating with API:
Full logs: Full Log.txt
I reloaded the Alexa Media intergration and triggered Good Morning just now from Office Echo Dot Right at 9:09:42:
2022-07-28 09:09:41 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1659024579694, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1659024579669#A2U21SRK4QGSE1#G091AA0805061S0N'}}
2022-07-28 09:09:42 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************S0N', 'timestamp': 1659024580934, 'summary': 'good morning'}
2022-07-28 09:09:42 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1659024580948, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1659024580892#A2U21SRK4QGSE1#G091AA08051216SA'}}
2022-07-28 09:09:42 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************S0N', 'timestamp': 1659024580934, 'summary': 'good morning'}
2022-07-28 09:09:42 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1659024581210, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1659024580934#A2U21SRK4QGSE1#G091AA0805061S0N'}}
2022-07-28 09:09:42 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************S0N', 'timestamp': 1659024580934, 'summary': 'good morning'}
2022-07-28 09:09:42 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_VOLUME_CHANGE : {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2U21SRK4QGSE1', 'deviceSerialNumber': 'G************S0N'}, 'volumeSetting': 15, 'isMuted': False}
2022-07-28 09:09:42 DEBUG (MainThread) [custom_components.alexa_media] Updating media_player volume: {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2U21SRK4QGSE1', 'deviceSerialNumber': 'G************S0N'}, 'volumeSetting': 15, 'isMuted': False}
2022-07-28 09:09:42 DEBUG (MainThread) [custom_components.alexa_media.services] Service update_last_called for: []
2022-07-28 09:09:42 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************S0N', 'timestamp': 1659024580934, 'summary': 'good morning'}
2022-07-28 09:09:44 DEBUG (MainThread) [custom_components.alexa_media.notify] Message: I'm sorry, It looks like you'll have to make your own coffee, kwargs: {'target': ['G091ET10051402M6'], 'data': {'type': 'tts'}}
2022-07-28 09:09:44 DEBUG (MainThread) [custom_components.alexa_media.notify] Target type: <class 'list'>
2022-07-28 09:09:44 DEBUG (MainThread) [custom_components.alexa_media.notify] Processing: G091ET10051402M6
2022-07-28 09:09:44 DEBUG (MainThread) [custom_components.alexa_media.notify] Processed Target by string: ['G091ET10051402M6']
2022-07-28 09:09:44 DEBUG (MainThread) [custom_components.alexa_media.notify] TTS by <Entity Bedroom Echo Dot Right: standby> : I'm sorry, It looks like you'll have to make your own coffee
2022-07-28 09:09:45 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1659024585041, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1659024584997#A2H4LV5GIZ1JFT#G091ET10051402M6'}}
2022-07-28 09:09:45 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1659024584997, 'summary': ''}
2022-07-28 09:09:45 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: last_called changed: {'serialNumber': 'G************S0N', 'timestamp': 1659024580934, 'summary': 'good morning'} to {'serialNumber': 'G************2M6', 'timestamp': 1659024584997, 'summary': ''}
2022-07-28 09:09:50 DEBUG (MainThread) [custom_components.alexa_media.notify] Message: The temperature outside is 24.6 degrees but it feels like 22.5 degrees. The living room is currently 23.5 degrees and the heat is set to 16, kwargs: {'target': ['G091ET10051402M6'], 'data': {'type': 'tts'}}
2022-07-28 09:09:50 DEBUG (MainThread) [custom_components.alexa_media.notify] Target type: <class 'list'>
2022-07-28 09:09:50 DEBUG (MainThread) [custom_components.alexa_media.notify] Processing: G091ET10051402M6
2022-07-28 09:09:50 DEBUG (MainThread) [custom_components.alexa_media.notify] Processed Target by string: ['G091ET10051402M6']
2022-07-28 09:09:50 DEBUG (MainThread) [custom_components.alexa_media.notify] TTS by <Entity Bedroom Echo Dot Right: standby> : The temperature outside is 24.6 degrees but it feels like 22.5 degrees. The living room is currently 23.5 degrees and the heat is set to 16
2022-07-28 09:09:51 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1659024591579, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1659024591540#A2H4LV5GIZ1JFT#G091ET10051402M6'}}
2022-07-28 09:09:51 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1659024591540, 'summary': ''}
2022-07-28 09:09:51 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: last_called changed: {'serialNumber': 'G************2M6', 'timestamp': 1659024584997, 'summary': ''} to {'serialNumber': 'G************2M6', 'timestamp': 1659024591540, 'summary': ''}
2022-07-28 09:09:52 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated 22 notifications for 8 devices at 2022-07-28 09:09:52.935523-07:00
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1659024591540, 'summary': ''}
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Left: Locale en-us timezone Canada/Pacific
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Left: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Garage Echo Dot: Locale en-us timezone America/Los_Angeles
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Garage Echo Dot: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Daniel's Echo Auto: Locale en-us timezone None
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Daniel's Echo Auto: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Toshiba Fire TV: Locale en-ca timezone America/Vancouver
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Toshiba Fire TV: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Office: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Living Room: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Kitchen Echo Dot: Locale en-us timezone America/Vancouver
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Kitchen Echo Dot: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Main floor: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Right: Locale en-us timezone Canada/Pacific
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Right: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Left: Locale en-us timezone America/Vancouver
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Left: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Right: Locale en-us timezone America/Vancouver
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Right: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Left: Locale en-us timezone America/Vancouver
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Left: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Right: Locale en-us timezone America/Vancouver
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Right: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Sound System: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] This Device: Locale en-ca timezone America/Vancouver
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] This Device: DND False
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Excluding Daniel's Alexa Apps for lacking capability
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Existing: [<Entity Living Room Echo Dot Left: standby>, <Entity Garage Echo Dot: standby>, <Entity Daniel's Echo Auto: standby>, <Entity Toshiba Fire TV: standby>, <Entity Office: standby>, <Entity Living Room: standby>, <Entity Kitchen Echo Dot: standby>, <Entity Main floor: standby>, <Entity Living Room Echo Dot Right: standby>, <Entity Office Echo Dot Left: standby>, <Entity Office Echo Dot Right: standby>, <Entity Bedroom Echo Dot Left: standby>, <Entity Bedroom Echo Dot Right: standby>, <Entity Sound System: standby>, <Entity This Device: standby>] New: []; Filtered out by not being in include: [] or in exclude: []
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media] Finished fetching alexa_media data in 1.634 seconds (success: True)
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media.alexa_entity] Coordinator has no data for c********************************96d
2022-07-28 09:09:53 DEBUG (MainThread) [custom_components.alexa_media.alexa_entity] Coordinator has no data for c********************************96d
After an HA reboot due to other integration updates, it is working again...
Ok, so I looked at your full logs. I'm guessing what is happening is that you are trying to call the last_called sensor at the exact moment it's being recreated in HA due to a detected change. During that moment, it is deregistering the old one and has to wait 2 seconds before it can create a new one. Now, it's possible that HA has changed the mechanism for creating/updating a service call to avoid the 2 second delay, but I haven't looked at it recently. I may or may not take this up to fix since I don't use the last_called service. However, perhaps someone else with this use case will provide a PR to address this if it's possible.
In the meantime, I'd suggest making sure your node red service can handle the error and then retrying.
"In the meantime, I'd suggest making sure your node red service can handle the error and then retrying" - where do you see a node red service call?
That aside you gave me reason to think about my calling the update last called at the start of routines to ensure last_called is current. I've noticed that last_called updates nearly instanstantly when I ask an echo something so maybe just removing those update last called service calls might be the answer. I think the documentation suggested it and now that you mention waiting 2 seconds I think that is/was also in the documentation when calling that service...
"In the meantime, I'd suggest making sure your node red service can handle the error and then retrying" - where do you see a node red service call?
That aside you gave me reason to think about my calling the update last called at the start of routines to ensure last_called is current. I've noticed that last_called updates nearly instanstantly when I ask an echo something so maybe just removing those update last called service calls might be the answer. I think the documentation suggested it and now that you mention waiting 2 seconds I think that is/was also in the documentation when calling that service...
Sorry I didn't recognize the image. I don't know what service you're using to call it but it said nodes so I assumed.
HA scripts and automations...
sequence:
- service: notify.alexa_media_last_called
data:
message:
It happened again this morning. The third iteration of Good Morning did execute...
HA logs... HA Log Aug 9,2022.txt
Another failure this morning...
This portion executed:
but this did not say anything:
and the rest of script did not run, despite continue_on_error: true
being specified:
Logs:
2022-08-12 10:00:53.845 DEBUG (MainThread) [custom_components.alexa_media] Finished fetching alexa_media data in 1.839 seconds (success: True)
2022-08-12 10:01:41.425 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_VOLUME_CHANGE : {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************2M6'}, 'volumeSetting': 21, 'isMuted': False}
2022-08-12 10:01:41.425 DEBUG (MainThread) [custom_components.alexa_media] Updating media_player volume: {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************2M6'}, 'volumeSetting': 21, 'isMuted': False}
2022-08-12 10:01:41.434 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Bedroom Echo Dot Right volume updated: 21
2022-08-12 10:01:41.434 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: No PUSH_AUDIO_PLAYER_STATE/PUSH_MEDIA_CHANGE/PUSH_MEDIA_PROGRESS_CHANGE in dict_keys(['PUSH_VOLUME_CHANGE', 'PUSH_EQUALIZER_STATE_CHANGE', 'PUSH_ACTIVITY']);forcing refresh
2022-08-12 10:01:41.434 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Bedroom Echo Dot Right: standby>
2022-08-12 10:01:41.435 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Bedroom Echo Dot Right: Last_called check: self: G************2M6 reported: G************6SA
2022-08-12 10:01:41.445 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_EQUALIZER_STATE_CHANGE : {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************2M6'}, 'bass': 0, 'midrange': 0, 'treble': 0}
2022-08-12 10:01:41.445 DEBUG (MainThread) [custom_components.alexa_media] Updating media_player availability {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************2M6'}, 'bass': 0, 'midrange': 0, 'treble': 0}
2022-08-12 10:01:41.462 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: No PUSH_AUDIO_PLAYER_STATE/PUSH_MEDIA_CHANGE/PUSH_MEDIA_PROGRESS_CHANGE in dict_keys(['PUSH_VOLUME_CHANGE', 'PUSH_EQUALIZER_STATE_CHANGE', 'PUSH_ACTIVITY']);forcing refresh
2022-08-12 10:01:41.543 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_EQUALIZER_STATE_CHANGE : {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************9C4'}, 'bass': 0, 'midrange': 0, 'treble': 0}
2022-08-12 10:01:41.543 DEBUG (MainThread) [custom_components.alexa_media] Updating media_player availability {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************9C4'}, 'bass': 0, 'midrange': 0, 'treble': 0}
2022-08-12 10:01:41.553 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_VOLUME_CHANGE : {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************9C4'}, 'volumeSetting': 21, 'isMuted': False}
2022-08-12 10:01:41.553 DEBUG (MainThread) [custom_components.alexa_media] Updating media_player volume: {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************9C4'}, 'volumeSetting': 21, 'isMuted': False}
2022-08-12 10:01:41.564 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: No PUSH_AUDIO_PLAYER_STATE/PUSH_MEDIA_CHANGE/PUSH_MEDIA_PROGRESS_CHANGE in dict_keys(['PUSH_VOLUME_CHANGE', 'PUSH_EQUALIZER_STATE_CHANGE', 'PUSH_ACTIVITY']);forcing refresh
2022-08-12 10:01:41.565 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Bedroom Echo Dot Left: standby>
2022-08-12 10:01:41.565 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Bedroom Echo Dot Left: Last_called check: self: G************9C4 reported: G************6SA
2022-08-12 10:01:41.579 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Bedroom Echo Dot Left volume updated: 21
2022-08-12 10:01:41.579 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: No PUSH_AUDIO_PLAYER_STATE/PUSH_MEDIA_CHANGE/PUSH_MEDIA_PROGRESS_CHANGE in dict_keys(['PUSH_VOLUME_CHANGE', 'PUSH_EQUALIZER_STATE_CHANGE', 'PUSH_ACTIVITY']);forcing refresh
2022-08-12 10:01:42.556 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1660323702501, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1660323702474#A2H4LV5GIZ1JFT#G091ET10051402M6'}}
2022-08-12 10:01:44.069 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated 21 notifications for 8 devices at 2022-08-12 10:01:44.068909-07:00
2022-08-12 10:01:44.273 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1660323703171, 'summary': 'good morning'}
2022-08-12 10:01:44.274 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: last_called changed: {'serialNumber': 'G************6SA', 'timestamp': 1660318230651, 'summary': 'what is on my calendar today'} to {'serialNumber': 'G************2M6', 'timestamp': 1660323703171, 'summary': 'good morning'}
2022-08-12 10:01:44.288 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Left: Locale en-us timezone Canada/Pacific
2022-08-12 10:01:44.288 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Left: DND False
2022-08-12 10:01:44.289 DEBUG (MainThread) [custom_components.alexa_media] Garage Echo Dot: Locale en-us timezone America/Los_Angeles
2022-08-12 10:01:44.289 DEBUG (MainThread) [custom_components.alexa_media] Garage Echo Dot: DND False
2022-08-12 10:01:44.290 DEBUG (MainThread) [custom_components.alexa_media] Daniel's Echo Auto: Locale en-us timezone None
2022-08-12 10:01:44.290 DEBUG (MainThread) [custom_components.alexa_media] Daniel's Echo Auto: DND False
2022-08-12 10:01:44.291 DEBUG (MainThread) [custom_components.alexa_media] Toshiba Fire TV: Locale en-ca timezone America/Vancouver
2022-08-12 10:01:44.291 DEBUG (MainThread) [custom_components.alexa_media] Toshiba Fire TV: DND False
2022-08-12 10:01:44.291 DEBUG (MainThread) [custom_components.alexa_media] Office: DND False
2022-08-12 10:01:44.292 DEBUG (MainThread) [custom_components.alexa_media] Living Room: DND False
2022-08-12 10:01:44.292 DEBUG (MainThread) [custom_components.alexa_media] Kitchen Echo Dot: Locale en-us timezone America/Vancouver
2022-08-12 10:01:44.293 DEBUG (MainThread) [custom_components.alexa_media] Kitchen Echo Dot: DND False
2022-08-12 10:01:44.293 DEBUG (MainThread) [custom_components.alexa_media] Main floor: DND False
2022-08-12 10:01:44.294 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Right: Locale en-us timezone Canada/Pacific
2022-08-12 10:01:44.294 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Right: DND False
2022-08-12 10:01:44.294 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Left: Locale en-us timezone America/Vancouver
2022-08-12 10:01:44.295 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Left: DND False
2022-08-12 10:01:44.295 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Right: Locale en-us timezone America/Vancouver
2022-08-12 10:01:44.295 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Right: DND False
2022-08-12 10:01:44.296 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Left: Locale en-us timezone America/Vancouver
2022-08-12 10:01:44.296 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Left: DND False
2022-08-12 10:01:44.297 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Right: Locale en-us timezone America/Vancouver
2022-08-12 10:01:44.297 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Right: DND False
2022-08-12 10:01:44.297 DEBUG (MainThread) [custom_components.alexa_media] Sound System: DND False
2022-08-12 10:01:44.298 DEBUG (MainThread) [custom_components.alexa_media] This Device: Locale en-ca timezone America/Vancouver
2022-08-12 10:01:44.298 DEBUG (MainThread) [custom_components.alexa_media] This Device: DND False
2022-08-12 10:01:44.299 DEBUG (MainThread) [custom_components.alexa_media] Excluding Daniel's Alexa Apps for lacking capability
2022-08-12 10:01:44.299 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Existing: [<Entity Living Room Echo Dot Left: standby>, <Entity Garage Echo Dot: standby>, <Entity Daniel's Echo Auto: standby>, <Entity Toshiba Fire TV: standby>, <Entity Office: standby>, <Entity Living Room: standby>, <Entity Kitchen Echo Dot: standby>, <Entity Main floor: standby>, <Entity Living Room Echo Dot Right: standby>, <Entity Office Echo Dot Left: standby>, <Entity Office Echo Dot Right: standby>, <Entity Bedroom Echo Dot Left: standby>, <Entity Bedroom Echo Dot Right: standby>, <Entity Sound System: standby>, <Entity This Device: standby>] New: []; Filtered out by not being in include: [] or in exclude: []
2022-08-12 10:01:44.309 DEBUG (MainThread) [custom_components.alexa_media] Finished fetching alexa_media data in 1.751 seconds (success: True)
2022-08-12 10:01:44.317 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: <Entity Bedroom Echo Dot Right: standby> is last_called: G************2M6
2022-08-12 10:01:44.319 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing notify targets
2022-08-12 10:01:44.319 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:44.320 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:44.320 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:44.321 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:44.534 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1660323703171, 'summary': 'good morning'}
2022-08-12 10:01:44.539 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1660323702639, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1660323702617#A2H4LV5GIZ1JFT#G091ET10051409C4'}}
2022-08-12 10:01:44.741 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1660323703171, 'summary': 'good morning'}
2022-08-12 10:01:44.748 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1660323703488, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1660323703171#A2H4LV5GIZ1JFT#G091ET10051402M6'}}
2022-08-12 10:01:44.934 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1660323703171, 'summary': 'good morning'}
2022-08-12 10:01:44.938 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_VOLUME_CHANGE : {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************9C4'}, 'volumeSetting': 15, 'isMuted': False}
2022-08-12 10:01:44.939 DEBUG (MainThread) [custom_components.alexa_media] Updating media_player volume: {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************9C4'}, 'volumeSetting': 15, 'isMuted': False}
2022-08-12 10:01:44.944 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_VOLUME_CHANGE : {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************2M6'}, 'volumeSetting': 15, 'isMuted': False}
2022-08-12 10:01:44.944 DEBUG (MainThread) [custom_components.alexa_media] Updating media_player volume: {'destinationUserId': 'A**********F5L', 'dopplerId': {'deviceType': 'A2H4LV5GIZ1JFT', 'deviceSerialNumber': 'G************2M6'}, 'volumeSetting': 15, 'isMuted': False}
2022-08-12 10:01:44.949 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Bedroom Echo Dot Left volume updated: 15
2022-08-12 10:01:44.949 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: No PUSH_AUDIO_PLAYER_STATE/PUSH_MEDIA_CHANGE/PUSH_MEDIA_PROGRESS_CHANGE in dict_keys(['PUSH_VOLUME_CHANGE', 'PUSH_EQUALIZER_STATE_CHANGE', 'PUSH_ACTIVITY']);forcing refresh
2022-08-12 10:01:44.950 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Bedroom Echo Dot Left: standby>
2022-08-12 10:01:44.950 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Bedroom Echo Dot Left: Last_called check: self: G************9C4 reported: G************2M6
2022-08-12 10:01:44.954 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Bedroom Echo Dot Right volume updated: 15
2022-08-12 10:01:44.955 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: No PUSH_AUDIO_PLAYER_STATE/PUSH_MEDIA_CHANGE/PUSH_MEDIA_PROGRESS_CHANGE in dict_keys(['PUSH_VOLUME_CHANGE', 'PUSH_EQUALIZER_STATE_CHANGE', 'PUSH_ACTIVITY']);forcing refresh
2022-08-12 10:01:44.955 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Bedroom Echo Dot Right: standby>
2022-08-12 10:01:44.955 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Bedroom Echo Dot Right: Last_called check: self: G************2M6 reported: G************2M6
2022-08-12 10:01:46.310 DEBUG (MainThread) [custom_components.alexa_media.notify] Message: I'm sorry, It looks like you'll have to make your own coffee, kwargs: {'target': ['G091ET10051402M6'], 'data': {'type': 'tts'}}
2022-08-12 10:01:46.310 DEBUG (MainThread) [custom_components.alexa_media.notify] Target type: <class 'list'>
2022-08-12 10:01:46.310 DEBUG (MainThread) [custom_components.alexa_media.notify] Processing: G091ET10051402M6
2022-08-12 10:01:46.310 DEBUG (MainThread) [custom_components.alexa_media.notify] Processed Target by string: ['G091ET10051402M6']
2022-08-12 10:01:46.312 DEBUG (MainThread) [custom_components.alexa_media.notify] TTS by <Entity Bedroom Echo Dot Right: standby> : I'm sorry, It looks like you'll have to make your own coffee
2022-08-12 10:01:46.325 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Changing notify.targets is not supported by HA version < 2021.2.0; using toggle method
2022-08-12 10:01:48.329 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:48.329 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:48.329 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:48.329 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:48.564 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1660323708512, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1660323708483#A2H4LV5GIZ1JFT#G091ET10051402M6'}}
2022-08-12 10:01:48.780 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1660323708483, 'summary': ''}
2022-08-12 10:01:48.780 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: last_called changed: {'serialNumber': 'G************2M6', 'timestamp': 1660323703171, 'summary': 'good morning'} to {'serialNumber': 'G************2M6', 'timestamp': 1660323708483, 'summary': ''}
2022-08-12 10:01:48.794 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: <Entity Bedroom Echo Dot Right: standby> is last_called: G************2M6
2022-08-12 10:01:48.794 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing notify targets
2022-08-12 10:01:48.795 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:48.795 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:48.795 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:48.795 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:50.797 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Changing notify.targets is not supported by HA version < 2021.2.0; using toggle method
2022-08-12 10:01:52.801 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:52.802 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:52.802 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:52.802 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:55.826 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated 21 notifications for 8 devices at 2022-08-12 10:01:55.825949-07:00
2022-08-12 10:01:56.013 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1660323708483, 'summary': ''}
2022-08-12 10:01:56.014 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Left: Locale en-us timezone Canada/Pacific
2022-08-12 10:01:56.014 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Left: DND False
2022-08-12 10:01:56.014 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Living Room Echo Dot Left: standby>
2022-08-12 10:01:56.014 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Living Room Echo Dot Left: Last_called check: self: G************U5L reported: G************2M6
2022-08-12 10:01:56.015 DEBUG (MainThread) [custom_components.alexa_media] Garage Echo Dot: Locale en-us timezone America/Los_Angeles
2022-08-12 10:01:56.015 DEBUG (MainThread) [custom_components.alexa_media] Garage Echo Dot: DND False
2022-08-12 10:01:56.015 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Garage Echo Dot: standby>
2022-08-12 10:01:56.015 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Garage Echo Dot: Last_called check: self: G************ETC reported: G************2M6
2022-08-12 10:01:56.016 DEBUG (MainThread) [custom_components.alexa_media] Daniel's Echo Auto: Locale en-us timezone None
2022-08-12 10:01:56.016 DEBUG (MainThread) [custom_components.alexa_media] Daniel's Echo Auto: DND False
2022-08-12 10:01:56.016 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Daniel's Echo Auto: standby>
2022-08-12 10:01:56.016 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Daniel's Echo Auto: Last_called check: self: G************62V reported: G************2M6
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media] Toshiba Fire TV: Locale en-ca timezone America/Vancouver
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media] Toshiba Fire TV: DND False
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Toshiba Fire TV: standby>
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Toshiba Fire TV: Last_called check: self: G************1J8 reported: G************2M6
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media] Office: DND False
2022-08-12 10:01:56.018 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Office: standby>
2022-08-12 10:01:56.018 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Office: Last_called check: self: 9****************************ffc reported: G************2M6
2022-08-12 10:01:56.018 DEBUG (MainThread) [custom_components.alexa_media] Living Room: DND False
2022-08-12 10:01:56.019 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Living Room: standby>
2022-08-12 10:01:56.019 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Living Room: Last_called check: self: 9****************************113 reported: G************2M6
2022-08-12 10:01:56.020 DEBUG (MainThread) [custom_components.alexa_media] Kitchen Echo Dot: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.020 DEBUG (MainThread) [custom_components.alexa_media] Kitchen Echo Dot: DND False
2022-08-12 10:01:56.020 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Kitchen Echo Dot: standby>
2022-08-12 10:01:56.020 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Kitchen Echo Dot: Last_called check: self: G************SMU reported: G************2M6
2022-08-12 10:01:56.021 DEBUG (MainThread) [custom_components.alexa_media] Main floor: DND False
2022-08-12 10:01:56.021 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Main floor: standby>
2022-08-12 10:01:56.021 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Main floor: Last_called check: self: 6****************************142 reported: G************2M6
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Right: Locale en-us timezone Canada/Pacific
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Right: DND False
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Living Room Echo Dot Right: standby>
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Living Room Echo Dot Right: Last_called check: self: G************GEW reported: G************2M6
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Left: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Left: DND False
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Office Echo Dot Left: standby>
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Office Echo Dot Left: Last_called check: self: G************6SA reported: G************2M6
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Right: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Right: DND False
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Office Echo Dot Right: standby>
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Office Echo Dot Right: Last_called check: self: G************S0N reported: G************2M6
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Left: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Left: DND False
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Right: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.025 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Right: DND False
2022-08-12 10:01:56.025 DEBUG (MainThread) [custom_components.alexa_media] Sound System: DND False
2022-08-12 10:01:56.025 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Sound System: standby>
2022-08-12 10:01:56.025 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Sound System: Last_called check: self: 2****************************c02 reported: G************2M6
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media] This Device: Locale en-ca timezone America/Vancouver
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media] This Device: DND False
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity This Device: standby>
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: This Device: Last_called check: self: e****************************acf reported: G************2M6
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media] Excluding Daniel's Alexa Apps for lacking capability
2022-08-12 10:01:56.027 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Existing: [<Entity Living Room Echo Dot Left: standby>, <Entity Garage Echo Dot: standby>, <Entity Daniel's Echo Auto: standby>, <Entity Toshiba Fire TV: standby>, <Entity Office: standby>, <Entity Living Room: standby>, <Entity Kitchen Echo Dot: standby>, <Entity Main floor: standby>, <Entity Living Room Echo Dot Right: standby>, <Entity Office Echo Dot Left: standby>, <Entity Office Echo Dot Right: standby>, <Entity Bedroom Echo Dot Left: standby>, <Entity Bedroom Echo Dot Right: standby>, <Entity Sound System: standby>, <Entity This Device: standby>] New: []; Filtered out by not being in include: [] or in exclude: []
2022-08-12 10:01:56.041 DEBUG (MainThread) [custom_components.alexa_media] Finished fetching alexa_media data in 1.713 seconds (success: True)
I think it might be a timing issue:
Executed: August 12, 2022 at 10:01:46 AM
Result:
params:
domain: notify
service: alexa_media_last_called
service_data:
message: I'm sorry, It looks like you'll have to make your own coffee
data:
type: tts
Debug shows this occurring from 10:01:46.312 to 10:01:56.027:
2022-08-12 10:01:46.312 DEBUG (MainThread) [custom_components.alexa_media.notify] TTS by <Entity Bedroom Echo Dot Right: standby> : I'm sorry, It looks like you'll have to make your own coffee
2022-08-12 10:01:46.325 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Changing notify.targets is not supported by HA version < 2021.2.0; using toggle method
2022-08-12 10:01:48.329 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:48.329 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:48.329 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:48.329 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323703171
2022-08-12 10:01:48.564 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Received websocket command: PUSH_ACTIVITY : {'destinationUserId': 'A**********F5L', 'timestamp': 1660323708512, 'key': {'registeredUserId': 'A1UZ93DGNU3F5L', 'entryId': '1660323708483#A2H4LV5GIZ1JFT#G091ET10051402M6'}}
2022-08-12 10:01:48.780 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1660323708483, 'summary': ''}
2022-08-12 10:01:48.780 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: last_called changed: {'serialNumber': 'G************2M6', 'timestamp': 1660323703171, 'summary': 'good morning'} to {'serialNumber': 'G************2M6', 'timestamp': 1660323708483, 'summary': ''}
2022-08-12 10:01:48.794 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: <Entity Bedroom Echo Dot Right: standby> is last_called: G************2M6
2022-08-12 10:01:48.794 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing notify targets
2022-08-12 10:01:48.795 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:48.795 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:48.795 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:48.795 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:50.797 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Changing notify.targets is not supported by HA version < 2021.2.0; using toggle method
2022-08-12 10:01:52.801 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:52.802 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:52.802 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Found last_called <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:52.802 DEBUG (MainThread) [custom_components.alexa_media.notify] d****l@b******a: Creating last_called target last_called using <Entity Bedroom Echo Dot Right: standby> called at 1660323708483
2022-08-12 10:01:55.826 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated 21 notifications for 8 devices at 2022-08-12 10:01:55.825949-07:00
2022-08-12 10:01:56.013 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Updated last_called: {'serialNumber': 'G************2M6', 'timestamp': 1660323708483, 'summary': ''}
2022-08-12 10:01:56.014 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Left: Locale en-us timezone Canada/Pacific
2022-08-12 10:01:56.014 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Left: DND False
2022-08-12 10:01:56.014 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Living Room Echo Dot Left: standby>
2022-08-12 10:01:56.014 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Living Room Echo Dot Left: Last_called check: self: G************U5L reported: G************2M6
2022-08-12 10:01:56.015 DEBUG (MainThread) [custom_components.alexa_media] Garage Echo Dot: Locale en-us timezone America/Los_Angeles
2022-08-12 10:01:56.015 DEBUG (MainThread) [custom_components.alexa_media] Garage Echo Dot: DND False
2022-08-12 10:01:56.015 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Garage Echo Dot: standby>
2022-08-12 10:01:56.015 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Garage Echo Dot: Last_called check: self: G************ETC reported: G************2M6
2022-08-12 10:01:56.016 DEBUG (MainThread) [custom_components.alexa_media] Daniel's Echo Auto: Locale en-us timezone None
2022-08-12 10:01:56.016 DEBUG (MainThread) [custom_components.alexa_media] Daniel's Echo Auto: DND False
2022-08-12 10:01:56.016 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Daniel's Echo Auto: standby>
2022-08-12 10:01:56.016 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Daniel's Echo Auto: Last_called check: self: G************62V reported: G************2M6
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media] Toshiba Fire TV: Locale en-ca timezone America/Vancouver
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media] Toshiba Fire TV: DND False
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Toshiba Fire TV: standby>
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Toshiba Fire TV: Last_called check: self: G************1J8 reported: G************2M6
2022-08-12 10:01:56.017 DEBUG (MainThread) [custom_components.alexa_media] Office: DND False
2022-08-12 10:01:56.018 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Office: standby>
2022-08-12 10:01:56.018 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Office: Last_called check: self: 9****************************ffc reported: G************2M6
2022-08-12 10:01:56.018 DEBUG (MainThread) [custom_components.alexa_media] Living Room: DND False
2022-08-12 10:01:56.019 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Living Room: standby>
2022-08-12 10:01:56.019 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Living Room: Last_called check: self: 9****************************113 reported: G************2M6
2022-08-12 10:01:56.020 DEBUG (MainThread) [custom_components.alexa_media] Kitchen Echo Dot: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.020 DEBUG (MainThread) [custom_components.alexa_media] Kitchen Echo Dot: DND False
2022-08-12 10:01:56.020 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Kitchen Echo Dot: standby>
2022-08-12 10:01:56.020 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Kitchen Echo Dot: Last_called check: self: G************SMU reported: G************2M6
2022-08-12 10:01:56.021 DEBUG (MainThread) [custom_components.alexa_media] Main floor: DND False
2022-08-12 10:01:56.021 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Main floor: standby>
2022-08-12 10:01:56.021 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Main floor: Last_called check: self: 6****************************142 reported: G************2M6
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Right: Locale en-us timezone Canada/Pacific
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media] Living Room Echo Dot Right: DND False
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Living Room Echo Dot Right: standby>
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Living Room Echo Dot Right: Last_called check: self: G************GEW reported: G************2M6
2022-08-12 10:01:56.022 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Left: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Left: DND False
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Office Echo Dot Left: standby>
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Office Echo Dot Left: Last_called check: self: G************6SA reported: G************2M6
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Right: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.023 DEBUG (MainThread) [custom_components.alexa_media] Office Echo Dot Right: DND False
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Office Echo Dot Right: standby>
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Office Echo Dot Right: Last_called check: self: G************S0N reported: G************2M6
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Left: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Left: DND False
2022-08-12 10:01:56.024 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Right: Locale en-us timezone America/Vancouver
2022-08-12 10:01:56.025 DEBUG (MainThread) [custom_components.alexa_media] Bedroom Echo Dot Right: DND False
2022-08-12 10:01:56.025 DEBUG (MainThread) [custom_components.alexa_media] Sound System: DND False
2022-08-12 10:01:56.025 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity Sound System: standby>
2022-08-12 10:01:56.025 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Sound System: Last_called check: self: 2****************************c02 reported: G************2M6
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media] This Device: Locale en-ca timezone America/Vancouver
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media] This Device: DND False
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: Refreshing <Entity This Device: standby>
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media.media_player] d****l@b******a: This Device: Last_called check: self: e****************************acf reported: G************2M6
2022-08-12 10:01:56.026 DEBUG (MainThread) [custom_components.alexa_media] Excluding Daniel's Alexa Apps for lacking capability
2022-08-12 10:01:56.027 DEBUG (MainThread) [custom_components.alexa_media] d****l@b******a: Existing: [<Entity Living Room Echo Dot Left: standby>, <Entity Garage Echo Dot: standby>, <Entity Daniel's Echo Auto: standby>, <Entity Toshiba Fire TV: standby>, <Entity Office: standby>, <Entity Living Room: standby>, <Entity Kitchen Echo Dot: standby>, <Entity Main floor: standby>, <Entity Living Room Echo Dot Right: standby>, <Entity Office Echo Dot Left: standby>, <Entity Office Echo Dot Right: standby>, <Entity Bedroom Echo Dot Left: standby>, <Entity Bedroom Echo Dot Right: standby>, <Entity Sound System: standby>, <Entity This Device: standby>] New: []; Filtered out by not being in include: [] or in exclude: []
2022-08-12 10:01:56.041 DEBUG (MainThread) [custom_components.alexa_media] Finished fetching alexa_media data in 1.713 seconds (success: True)
My next TTS was at 10:01:52 which was during the above where it started refreshing last_called at 10:01:48 to 10:01:56:
Executed: August 12, 2022 at 10:01:52 AM
Result:
params:
domain: notify
service: alexa_media_last_called
service_data:
message: >-
The temperature outside is 22.1 degrees but it feels like 18.1 degrees.
The living room is currently 21.5 degrees and the heat is set to 16
data:
type: tts
I will try changing
service: notify.alexa_media_last_called
to:
service: notify.alexa_media
data:
target: "{{ states('sensor.last_alexa' }}"
to eliminate notify.alexa_media_last_called which to me seems to be the problem.
This bug report has been labelled as help wanted since there has been no activity in the last 3 weeks. It will not be closed automatically.
The issue has received no activity for 60 days and will be closed in a week.
I have not had any trouble since changing to...
service: notify.alexa_media
data:
target: "{{ states('sensor.last_alexa' }}"
The service: notify.alexa_media_last_called
seemed to be a problem for me...
I get the same issue
the automation "Report Temperature in the Lounge" (automation.report_temperature_in_the_lounge) has an action that calls an unknown service: notify.alexa_media_last_called.
I'll try the above change to use the service.alexa_media
This bug report has been labelled as help wanted since there has been no activity in the last 3 weeks. It will not be closed automatically.
I'll try the above change to use the service.alexa_media
Did that solve your issue?
I think the integration is temporarily removing & re-adding its services when it periodically contacts AWS for an update on devices. If your automation/script runs during this re & re then it will fail to find the service. I could be wrong though, have been before! (don't tell my better half I said that!)
I think the integration is temporarily removing & re-adding its services when it periodically contacts AWS for an update on devices. If your automation/script runs during this re & re then it will fail to find the service. I could be wrong though, have been before! (don't tell my better half I said that!)
This is exactly what is happening.
Thank you, Alan, for confirming this. Do you see any possible solution, other than avoiding the cause?
Question: Can services be added/removed individually without disturbing them all?
for each element in current_service_list[]
if not element in new_service_list[]
delete element
for each element in new_service[]
if not element in current_service[]
add element
No. They exist or don't.
So there's no way to tell if the service list provided by Alexa has changed from the last time?
I'm not sure what you mean by service list.