Chromecast Audiogroup with Openhab 2.5.8

Huaba · August 27, 2020, 3:55pm

Hi,

since Openhab 2.5.8 i’ve permanent errors in the log for my chromecast audiogroup (2x Google Home Mini). I’ve seen this errors never before.

2020-08-27 17:40:00.884 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:40:06.036 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:40:10.943 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:40:16.044 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:40:20.994 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:40:26.051 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:40:31.048 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:40:36.056 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:40:41.106 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:40:46.062 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:40:51.164 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:40:56.067 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:41:01.224 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:41:06.073 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:41:11.297 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:41:16.077 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:41:21.343 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:41:26.084 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:41:31.402 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:41:36.090 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:41:41.454 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:41:46.096 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:41:51.514 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:41:56.103 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:42:01.578 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:42:06.120 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:42:11.631 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:42:16.126 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:42:21.685 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:42:26.133 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:42:31.730 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:42:36.136 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:42:41.791 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE
2020-08-27 17:42:46.140 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused)
2020-08-27 17:42:51.851 [hingStatusInfoChangedEvent] - 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' changed from OFFLINE (COMMUNICATION_ERROR): Verbindungsaufbau abgelehnt (Connection refused) to ONLINE

What i’ve checked:

both GHs have static IP adresses.
playback on the audiogroup works
if the audiogroup is active, all items in Openhab shows the correct status (seems to work correct)

Changed items if audiogroup is active:

2020-08-27 17:42:52.588 [vent.ItemStateChangedEvent] - chromecast_audiogroup_b5d0a3e2_98f3_437c_aead_4261e3482921_control changed from NULL to PAUSE
2020-08-27 17:42:52.595 [vent.ItemStateChangedEvent] - chromecast_audiogroup_b5d0a3e2_98f3_437c_aead_4261e3482921_location changed from NULL to UNDEF
2020-08-27 17:42:52.597 [vent.ItemStateChangedEvent] - chromecast_audiogroup_b5d0a3e2_98f3_437c_aead_4261e3482921_artist changed from NULL to UNDEF
2020-08-27 17:42:52.606 [me.event.ThingUpdatedEvent] - Thing 'chromecast:audiogroup:b5d0a3e2-98f3-437c-aead-4261e3482921' has been updated.
2020-08-27 17:42:52.608 [vent.ItemStateChangedEvent] - chromecast_audiogroup_b5d0a3e2_98f3_437c_aead_4261e3482921_title changed from NULL to UNDEF
2020-08-27 17:42:53.665 [vent.ItemStateChangedEvent] - chromecast_audiogroup_b5d0a3e2_98f3_437c_aead_4261e3482921_volume changed from NULL to 64
2020-08-27 17:42:53.667 [vent.ItemStateChangedEvent] - chromecast_audiogroup_b5d0a3e2_98f3_437c_aead_4261e3482921_mute changed from NULL to OFF
2020-08-27 17:49:38.725 [vent.ItemStateChangedEvent] - chromecast_chromecast_20549936b7d4f17e113be7a17241d727_appName changed from UNDEF to TuneIn Free
2020-08-27 17:49:38.745 [vent.ItemStateChangedEvent] - chromecast_chromecast_20549936b7d4f17e113be7a17241d727_appId changed from UNDEF to 531A4F84
2020-08-27 17:49:38.752 [vent.ItemStateChangedEvent] - chromecast_chromecast_20549936b7d4f17e113be7a17241d727_statustext changed from UNDEF to Ready to play
2020-08-27 17:49:38.757 [vent.ItemStateChangedEvent] - chromecast_chromecast_20549936b7d4f17e113be7a17241d727_idling changed from ON to OFF
2020-08-27 17:49:40.610 [vent.ItemStateChangedEvent] - chromecast_chromecast_20549936b7d4f17e113be7a17241d727_statustext changed from Ready to play to Casting: Der beste Rock nonstop
2020-08-27 17:49:44.486 [vent.ItemStateChangedEvent] - chromecast_audiogroup_b5d0a3e2_98f3_437c_aead_4261e3482921_control changed from PAUSE to PLAY
2020-08-27 17:49:44.510 [vent.ItemStateChangedEvent] - chromecast_audiogroup_b5d0a3e2_98f3_437c_aead_4261e3482921_artist changed from UNDEF to ROCK ANTENNE
2020-08-27 17:49:44.514 [vent.ItemStateChangedEvent] - chromecast_audiogroup_b5d0a3e2_98f3_437c_aead_4261e3482921_title changed from UNDEF to Der beste Rock nonstop

Could someone confirm this behavior or have some hints for me?

Thx
Huaba

DaDen · August 12, 2022, 9:53pm

Hey, i have the same issue on openhab 3.3. Did you find out how to fix it?

Huaba · August 16, 2022, 1:05pm

Uh, that was a long time ago.

I’m not quite sure what I did anymore. As far as I can remember I ignored the errors and since the update to Openhab 3 I never saw them again.

rpwong · August 16, 2022, 5:11pm

These log entries have been a known issue for a long time with Chromecasts, but they don’t impact performance at all. I’m surprised you’re seeing them in 3.3, as we changed the default log level for the Chromecast binding to ERROR to filter them out. That was back in January 2021.

Is there any reason that you might have changed the log level manually? If not, then I would check your log4j2.xml file to see if the default is still set per the GitHub link. If it’s there, then we’ll need to see your actual log entries.

DaDen · August 16, 2022, 9:12pm

the system is almost two years old. i have no idea what i changed in this time. i think it is time to take all i learned and create everything new. i hope some of the issues will be gone.

rpwong · August 16, 2022, 10:07pm

It’s possible that you set it up before the filter was modified, but then these Chromecast errors should have been showing for as long as you’ve had a Chromecast.

If it’s not in your log4j2.xml file, you can just add the code as shown in GitHub.

DaDen · August 25, 2022, 6:00pm

i have done a complete new installation of openhab and always get the errors.

2022-08-25 19:55:05.778 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused) to ONLINE
2022-08-25 19:55:16.560 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused)
2022-08-25 19:55:25.983 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused) to ONLINE
2022-08-25 19:55:26.625 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused)
2022-08-25 19:55:36.116 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused) to ONLINE
2022-08-25 19:55:36.656 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused)
2022-08-25 19:55:46.322 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused) to ONLINE
2022-08-25 19:55:46.796 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused)
2022-08-25 19:55:56.535 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused) to ONLINE
2022-08-25 19:55:56.909 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused)
2022-08-25 19:56:06.662 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused) to ONLINE
2022-08-25 19:56:06.992 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused)
2022-08-25 19:56:16.778 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused) to ONLINE
2022-08-25 19:56:17.311 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'chromecast:audiogroup:ca998b60-4b59-4f03-ab74-807bb6a44219' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection refused (Connection refused)

i hope this is not a stupid question but where do i find my log4j2.xml file?

rpwong · August 25, 2022, 7:44pm

You’ll find it here: userdata/etc/log4j2.xml

DaDen · August 26, 2022, 9:32pm

found it. it is in var/lib/openhab/etc