Songpal? errors spamming log

[sparkydave (🇦🇺 Dave)]

I just updated my HassIO to the latest version 0.65.6 and now have bulk errors in my log that keep repeating… something I’ve never seen before to do with songpal…?

does anyone know what this is all about?

8-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for avContent: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for avContent: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for avContent: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for browser: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for cec: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for contentshare: Bad status code: 404
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for encryption: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘addSchedule’, ‘versions’: [{‘version’: ‘1.1’, ‘authLevel’: ‘generic’}, {‘version’: ‘1.0’, ‘authLevel’: ‘generic’}, {‘version’: ‘1.2’, ‘authLevel’: ‘generic’}]}, using the first one
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘getScheduleList’, ‘versions’: [{‘version’: ‘1.1’, ‘authLevel’: ‘private’}, {‘version’: ‘1.0’, ‘authLevel’: ‘private’}]}, using the first one
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘deleteSchedule’, ‘versions’: [{‘version’: ‘1.1’, ‘authLevel’: ‘generic’}, {‘version’: ‘1.0’, ‘authLevel’: ‘generic’}]}, using the first one
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for recording: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for recording: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘getCurrentTime’, ‘versions’: [{‘version’: ‘1.1’}, {‘version’: ‘1.0’}]}, using the first one
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for system: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for system: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for videoScreen: Bad status code: 501
2018-03-29 14:33:45 WARNING (MainThread) [songpal.service] Invalid handshake for videoScreen: Bad status code: 501
2018-03-29 14:33:45 ERROR (MainThread) [homeassistant.components.media_player.songpal] Unable to get methods from songpal: <Service system: 0 methods, 0 protocols, 0 notifications does not contain method getSystemInformation
2018-03-29 14:33:45 WARNING (MainThread) [homeassistant.components.media_player] Platform songpal not ready yet. Retrying in 180 seconds.

[sparkydave (🇦🇺 Dave)]

Is anyone else getting this error at all? My log is constantly being spammed with this error and I dont know why. Everything seems to be working ok…

2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘getContentCount’, ‘versions’: [{‘version’: ‘1.1’, ‘authLevel’: ‘private’}, {‘version’: ‘1.0’, ‘authLevel’: ‘private’}]}, using the first one
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘setPlayTvContent’, ‘versions’: [{‘version’: ‘1.1’, ‘authLevel’: ‘generic’}, {‘version’: ‘1.0’, ‘authLevel’: ‘generic’}]}, using the first one
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘getCurrentExternalInputsStatus’, ‘versions’: [{‘version’: ‘1.1’}, {‘version’: ‘1.0’}]}, using the first one
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘getContentList’, ‘versions’: [{‘version’: ‘1.5’, ‘authLevel’: ‘private’}, {‘version’: ‘1.0’, ‘authLevel’: ‘private’}, {‘version’: ‘1.2’, ‘authLevel’: ‘private’}]}, using the first one
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for avContent: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for avContent: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for avContent: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for browser: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for cec: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for contentshare: Bad status code: 404
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for encryption: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘addSchedule’, ‘versions’: [{‘version’: ‘1.1’, ‘authLevel’: ‘generic’}, {‘version’: ‘1.0’, ‘authLevel’: ‘generic’}, {‘version’: ‘1.2’, ‘authLevel’: ‘generic’}]}, using the first one
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘getScheduleList’, ‘versions’: [{‘version’: ‘1.1’, ‘authLevel’: ‘private’}, {‘version’: ‘1.0’, ‘authLevel’: ‘private’}]}, using the first one
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘deleteSchedule’, ‘versions’: [{‘version’: ‘1.1’, ‘authLevel’: ‘generic’}, {‘version’: ‘1.0’, ‘authLevel’: ‘generic’}]}, using the first one
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for recording: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for recording: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] More than on version for {‘name’: ‘getCurrentTime’, ‘versions’: [{‘version’: ‘1.1’}, {‘version’: ‘1.0’}]}, using the first one
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for system: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for system: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for videoScreen: Bad status code: 501
2018-04-19 07:25:36 WARNING (MainThread) [songpal.service] Invalid handshake for videoScreen: Bad status code: 501
2018-04-19 07:25:36 ERROR (MainThread) [homeassistant.components.media_player.songpal] Unable to get methods from songpal: <Service system: 0 methods, 0 protocols, 0 notifications does not contain method getSystemInformation
2018-04-19 07:25:36 WARNING (MainThread) [homeassistant.components.media_player] Platform songpal not ready yet. Retrying in 180 seconds.

now on HassIO 0.66.1

[phairplay]

I also noticed my log are being filled with songbird errors.

I have a Sony ct800 soundbar

[damylen]

Same here, lots of errors like:

Got unknowns for Volume: {‘target’: ‘headphone’} - please create an issue!
10:38 PM components/media_player/songpal.py (WARNING)

[phairplay]

Today my log is being spammed again

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:16 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:16 PM util/async_.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:16 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:16 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:16 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:16 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:16 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:15 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:15 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:15 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:15 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:14 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:14 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:14 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:14 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:14 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:14 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:13 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:13 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:13 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:13 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:13 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:13 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:12 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:12 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:12 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:12 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:11 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:11 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:11 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:11 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:11 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:11 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:10 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:10 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:10 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:10 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:10 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:10 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:09 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:09 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:09 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:09 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:08 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:08 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:08 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:08 PM util/async_.py (WARNING)

Updating songpal media_player took longer than the scheduled update interval 0:00:10

12:08 PM helpers/entity_platform.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:08 PM util/async_.py (WARNING)

Update of media_player.living_room_speaker is taking over 10 seconds

12:08 PM util/async_.py (WARNING)