search

hass-agent / HASS.Agent

Unofficial development project for the HASS.Agent platform.
https://hass-agent.io
MIT License
414 stars 12 forks source link

Bug: Media Controls Pause/Play/Skip not functioning, sensors/commands not generating entities #109

Open mortNZ opened 5 months ago

mortNZ commented 5 months ago

Describe the bug I have installed the HASS.Agent PC Client, and the HACS HASS.Agent 2 repository and have auto-discovered my PC. The media player entity populates as expected; although only the volume control works, the pause/play/skip features are not working as intended. I have also added a sensor/command into the PC client and these are not populating into HASS correctly, although the quick actions are working. Any suggestions greatly appreciated.

Please check what's applicable (multiple answers possible):

Logs >> Logs after restart of PC client and home assistant.

2024-06-26 21:23:49.672 +12:00 [INF] [SYSTEM] Application shutting down 2024-06-26 21:23:49.683 +12:00 [INF] [MQTT] Disconnected 2024-06-26 21:23:49.809 +12:00 [INF] [SYSTEM] Application shutdown complete 2024-06-26 21:23:51.857 +12:00 [INF] [MAIN] HASS.Agent version: 2.0.1 2024-06-26 21:23:51.868 +12:00 [INF] [SETTINGS] Config storage path: C:\Users\MortVM1\AppData\Local\HASS.Agent\Client\config 2024-06-26 21:23:52.022 +12:00 [INF] [SETTINGS] Configuration loaded 2024-06-26 21:23:52.026 +12:00 [INF] [LOCALIZATION] Selected UI culture: [en] English 2024-06-26 21:23:52.372 +12:00 [INF] [RADIOMGR] Ready 2024-06-26 21:23:54.470 +12:00 [INF] [INTERNALSENSORS] Ready 2024-06-26 21:23:57.929 +12:00 [INF] [VIRTDESKT] Virtual Desktop Manager initialized 2024-06-26 21:23:57.972 +12:00 [INF] [SETTINGS_QUICKACTIONS] Loaded 1 entities 2024-06-26 21:23:57.998 +12:00 [INF] [SETTINGS_COMMANDS] Loaded 1 entities 2024-06-26 21:23:58.015 +12:00 [INF] [SETTINGS_SENSORS] Loaded 1 entities 2024-06-26 21:23:58.023 +12:00 [INF] [HOTKEY] Completed bind for global quickaction hotkey 2024-06-26 21:23:58.024 +12:00 [INF] [HOTKEY] Completed bind for 1 individual quickaction hotkeys 2024-06-26 21:23:58.083 +12:00 [INF] [LOCALAPI] Initializing .. 2024-06-26 21:23:58.138 +12:00 [INF] [MQTT] Identifying as device: tvpc 2024-06-26 21:23:58.255 +12:00 [INF] [MQTT] Connecting .. 2024-06-26 21:23:58.459 +12:00 [INF] [MEDIA] Ready 2024-06-26 21:23:58.567 +12:00 [INF] [HASS_API] Home Assistant version: 2024.6.4 2024-06-26 21:23:58.589 +12:00 [INF] [SERVICE] Local install path: C:\Program Files\HASS.Agent\Service\ 2024-06-26 21:23:58.646 +12:00 [INF] [MQTT] Connected 2024-06-26 21:23:58.772 +12:00 [INF] [HASS_API] System connected with http://homeassistant.local:8123 2024-06-26 21:23:58.840 +12:00 [INF] [NOTIFIER] Ready 2024-06-26 21:24:00.405 +12:00 [INF] [MQTT] Initial registration completed 2024-06-26 21:24:00.561 +12:00 [INF] [LOCALAPI] listening on port 5115 2024-06-26 21:25:59.822 +12:00 [INF] [HASS_API] Server recovered from failed state 2024-06-26 21:25:59.830 +12:00 [WRN] [HASS_API] Server returned 404 (not found) while getting entity state. This can happen after a server reboot, or if you've deleted the entity. If the problem persists, please file a ticket on github. Entity: light.all_living_room_lights Error message: Response status code does not indicate success: 404 (Not Found).

image

mortNZ commented 5 months ago

Update: Sensors/commands were user error, these populated under my MQTT integration rather than the HASS.Agent integration. Media controls (play/pause/next/previous) not working: The commands appear in MQTT Explorer but don't control playing media. From MQTT Explorer: {"command": "pause", "data": null}

The PC client is installed on a Win10 VM running on Unraid

HAOS Integration is 2.1.0-beta1

amadeo-alex commented 5 months ago

Hello, could you please enable extended logging and then invoking the play/pause/next/previous commands?

mortNZ commented 5 months ago

Hello, could you please enable extended logging and then invoking the play/pause/next/previous commands?

Sure thing, extended logging turned on, reset, and input hotkeys which work as intended and the media pause/forward/previous buttons in the HAOS media player entity.

2024-07-04 07:40:10.770 +12:00 [INF] [MAIN] HASS.Agent version: 2.0.1
2024-07-04 07:40:10.775 +12:00 [INF] [MAIN] Extended logging enabled
2024-07-04 07:40:10.779 +12:00 [INF] [SETTINGS] Config storage path: C:\Users\MortVM1\AppData\Local\HASS.Agent\Client\config
2024-07-04 07:40:10.935 +12:00 [INF] [SETTINGS] Configuration loaded
2024-07-04 07:40:10.939 +12:00 [INF] [LOCALIZATION] Selected UI culture: [en] English
2024-07-04 07:40:11.790 +12:00 [INF] [RADIOMGR] Ready
2024-07-04 07:40:13.864 +12:00 [INF] [INTERNALSENSORS] Ready
2024-07-04 07:40:16.527 +12:00 [FTL] [PROGRAM] FirstChanceException: Access to the path 'Global\Access_ISABUS.HTP.Method' is denied.
System.UnauthorizedAccessException: Access to the path 'Global\Access_ISABUS.HTP.Method' is denied.
   at System.Threading.Mutex.CreateMutexCore(Boolean initiallyOwned, String name, Boolean& createdNew)
   at System.Threading.Mutex..ctor(Boolean initiallyOwned, String name)
   at LibreHardwareMonitor.Hardware.Ring0.TryCreateOrOpenExistingMutex(String name, Mutex& mutex)
2024-07-04 07:40:16.527 +12:00 [FTL] [PROGRAM] FirstChanceException: Access to the path 'Global\Access_ISABUS.HTP.Method' is denied.
System.UnauthorizedAccessException: Access to the path 'Global\Access_ISABUS.HTP.Method' is denied.
   at System.Threading.Mutex.OpenExistingWorker(String name, Mutex& result)
   at System.Threading.Mutex.OpenExisting(String name)
   at LibreHardwareMonitor.Hardware.Ring0.TryCreateOrOpenExistingMutex(String name, Mutex& mutex)
2024-07-04 07:40:16.528 +12:00 [FTL] [PROGRAM] FirstChanceException: Access to the path 'Global\Access_PCI' is denied.
System.UnauthorizedAccessException: Access to the path 'Global\Access_PCI' is denied.
   at System.Threading.Mutex.CreateMutexCore(Boolean initiallyOwned, String name, Boolean& createdNew)
   at System.Threading.Mutex..ctor(Boolean initiallyOwned, String name)
   at LibreHardwareMonitor.Hardware.Ring0.TryCreateOrOpenExistingMutex(String name, Mutex& mutex)
2024-07-04 07:40:16.528 +12:00 [FTL] [PROGRAM] FirstChanceException: Access to the path 'Global\Access_PCI' is denied.
System.UnauthorizedAccessException: Access to the path 'Global\Access_PCI' is denied.
   at System.Threading.Mutex.OpenExistingWorker(String name, Mutex& result)
   at System.Threading.Mutex.OpenExisting(String name)
   at LibreHardwareMonitor.Hardware.Ring0.TryCreateOrOpenExistingMutex(String name, Mutex& mutex)
2024-07-04 07:40:16.528 +12:00 [FTL] [PROGRAM] FirstChanceException: Access to the path 'Global\Access_EC' is denied.
System.UnauthorizedAccessException: Access to the path 'Global\Access_EC' is denied.
   at System.Threading.Mutex.CreateMutexCore(Boolean initiallyOwned, String name, Boolean& createdNew)
   at System.Threading.Mutex..ctor(Boolean initiallyOwned, String name)
   at LibreHardwareMonitor.Hardware.Ring0.TryCreateOrOpenExistingMutex(String name, Mutex& mutex)
2024-07-04 07:40:16.528 +12:00 [FTL] [PROGRAM] FirstChanceException: Access to the path 'Global\Access_EC' is denied.
System.UnauthorizedAccessException: Access to the path 'Global\Access_EC' is denied.
   at System.Threading.Mutex.OpenExistingWorker(String name, Mutex& result)
   at System.Threading.Mutex.OpenExisting(String name)
   at LibreHardwareMonitor.Hardware.Ring0.TryCreateOrOpenExistingMutex(String name, Mutex& mutex)
2024-07-04 07:40:17.876 +12:00 [INF] [VIRTDESKT] Virtual Desktop Manager initialized
2024-07-04 07:40:17.929 +12:00 [INF] [SETTINGS_QUICKACTIONS] Loaded 6 entities
2024-07-04 07:40:17.967 +12:00 [INF] [SETTINGS_COMMANDS] Loaded 3 entities
2024-07-04 07:40:17.989 +12:00 [INF] [SETTINGS_SENSORS] Loaded 1 entities
2024-07-04 07:40:17.997 +12:00 [INF] [HOTKEY] Completed bind for global quickaction hotkey
2024-07-04 07:40:17.999 +12:00 [INF] [HOTKEY] Completed bind for 6 individual quickaction hotkeys
2024-07-04 07:40:18.064 +12:00 [INF] [LOCALAPI] Initializing ..
2024-07-04 07:40:18.129 +12:00 [INF] [MQTT] Identifying as device: tvpc
2024-07-04 07:40:18.259 +12:00 [INF] [MQTT] Connecting ..
2024-07-04 07:40:18.495 +12:00 [INF] [MEDIA] Ready
2024-07-04 07:40:18.568 +12:00 [INF] [HASS_API] Home Assistant version: 2024.6.4
2024-07-04 07:40:18.609 +12:00 [INF] [SERVICE] Local install path: C:\Program Files\HASS.Agent\Service\
2024-07-04 07:40:18.677 +12:00 [INF] [MQTT] Connected
2024-07-04 07:40:18.719 +12:00 [INF] [MEDIA] Now playing: Ben Rector - Extraordinary Magic
2024-07-04 07:40:18.758 +12:00 [INF] [HASS_API] System connected with http://homeassistant.local:8123
2024-07-04 07:40:18.831 +12:00 [INF] [MEDIA] New state: Playing
2024-07-04 07:40:18.913 +12:00 [INF] [NOTIFIER] Ready
2024-07-04 07:40:20.421 +12:00 [INF] [MQTT] Initial registration completed
2024-07-04 07:40:20.726 +12:00 [INF] [LOCALAPI] listening on port 5115
2024-07-04 07:40:33.937 +12:00 [INF] [HASS_API] [scene.living_room_dim_lights] Performing action: turn_on
2024-07-04 07:40:34.836 +12:00 [INF] [HASS_API] [scene.living_room_dim_lights] Action completed: turn_on
2024-07-04 07:40:35.560 +12:00 [INF] [HASS_API] [scene.living_room_warm_white] Performing action: turn_on
2024-07-04 07:40:35.786 +12:00 [INF] [HASS_API] [scene.living_room_warm_white] Action completed: turn_on
2024-07-04 07:40:38.276 +12:00 [INF] [MEDIA] Command received: Next
2024-07-04 07:40:40.721 +12:00 [INF] [MEDIA] Command received: Pause
2024-07-04 07:40:42.432 +12:00 [INF] [MEDIA] Command received: Previous
amadeo-alex commented 5 months ago

So the HASS.Agent receives the inputs (from the media player entity, not mqtt configured commands) and it works correct? Now if I understand the same doesn't work when you invoke the commands?

mortNZ commented 5 months ago

So the HASS.Agent receives the inputs (from the media player entity, not mqtt configured commands) and it works correct? Now if I understand the same doesn't work when you invoke the commands?

The media player entity play/pause/skip/previous buttons do not control media on the PC (spotify, plex, or youtube), although the inputs are visible in the logs.

I created a skip & pause media command, and the inputs do not work or appear in the HASS.agent logs.

I created an open URL command and it works as intended, but also does not appear in the HASS.agent logs.

amadeo-alex commented 5 months ago

If HASS.Agent receives the request for media constrols (logs for the media player, the commands do not produce the logs) then I think we need to search for a reason on your device - HASS.Agent uses keypress emulation for media controls, for example "MEDIA_PLAY_PAUSE" https://learn.microsoft.com/en-us/windows/win32/inputdev/virtual-key-codes

Do you maybe have a keyboard with media buttons that could allow us to test if they work? You've also mentioned a "although the quick actions are working" - could you elaborate on that?

mortNZ commented 5 months ago

If HASS.Agent receives the request for media constrols (logs for the media player, the commands do not produce the logs) then I think we need to search for a reason on your device - HASS.Agent uses keypress emulation for media controls, for example "MEDIA_PLAY_PAUSE" https://learn.microsoft.com/en-us/windows/win32/inputdev/virtual-key-codes

Do you maybe have a keyboard with media buttons that could allow us to test if they work? You've also mentioned a "although the quick actions are working" - could you elaborate on that?

My keyboard has media control buttons that work as intended, I'm not sure how to check like a key-log etc to check the command inputs from the keyboard to compare it to the inputs from the HASS.Agent log.

I have set up various Quick Actions, which all work correctly. Although I'm getting the sense that this problem relates to the virtual keypresses for media as you've said since media control seems to be an isolated problem.

amadeo-alex commented 4 months ago

Ok, thank you for the confirmation. I'm working on a bug fix that has been introduced in 2.1.0-beta2 that could potentially also be the cause for your issue but you're running 2.0.1 which shouldn't be impacted by it. I'll need to think about it - I can't think of a reason for this behaviour.