Spotify Connect Binding

HaKuNa · October 22, 2019, 5:18pm

I also think the “Weak cipher” messages are from the Spotify binding. Perhaps it’s related to the platform where openHAB is running.
I have exactly the same behaviour as @rkrisi.
My environment:
CentOS Linux release 7.7.1908 (Core)
openjdk version “1.8.0_222”
openHAB 2.5.0.M4

rkrisi · October 22, 2019, 8:53pm

I’m on openHabian on a Raspberry, so totally different platforms. But I also assume this. I’ll try to remove the binding and see if these warnings are gone.

rkrisi · October 24, 2019, 11:19am

@hilbrand
Now I’m sure that spotify binding causes these chiper warnings. Fresh install, right after authenticating spotify I get these warnings. None of them before

hilbrand · October 24, 2019, 8:43pm

Maybe it’s a combination of the java environment and updated library jetty version in core? Did this all happen after an update of openHAB? I haven’t seen the error on my systems, soI don’t know directly why this is triggered or how to fix this. The binding itself doesn’t do anything special with https. It just uses methods from core.

rkrisi · October 24, 2019, 8:48pm

Yes, @J-N-K mentioned in another thread that the jetty got updated in 2.5M2 if I remember it right.

Jonas88 · November 4, 2019, 8:40pm

Hello there!

I have some serious issues with this binding.
I cannot tell you anything further by now but i will be happy to help investigating.
After two month of investigating what is the cause for my huge cpu load after 1-2 days I finally broke it down to the Spotify Binding. My issue is written down here: Huge CPU Load - Zulu8 on Mac OS X - sporadically
I had the issue 4 days ago for the last time. then I uninstalled the Spotify binding, deleted the .things-file and the cache and now it is running like charm for 4 days. Before i had to reboot daily to get rid of the cpu load issue.

I still don’t know why i am having this issue. maybe it’s a mac os x thing. I tried installing OH 2.4 and setting up OH 2.5M3/4 on my Macbook as well and still got the issue.

Could you tell me what i should do to help you debug? For example the name for log:set “???” debug to investigate this issue?

Here is my .things and .items file.
Do i have to many things maybe?

Regards Jonas

Bridge spotify:player:Jonas "Spotify Jonas" [clientId="nope", clientSecret="nope"] {
  Things:
    device WoZi_Thing_Echo_Spotify          "Spotify Echo Wohnzimmer"       @ "Wohnzimmer"      [deviceName="Wohnzimmer"]
    device Keller_Thing_Echo_Spotify        "Spotify Echo Keller"           @ "Keller"          [deviceName="Keller"]
    device Bad_Thing_Echo_Spotify           "Spotify Echo Bad"              @ "Bad"             [deviceName="Bad"]
    device SchlaZi_Thing_Echo_Spotify       "Spotify Echo Schlafzimmer"     @ "Schlafzimmer"    [deviceName="Schlafzimmer"]
    device Kueche_Thing_Echo_Spotify        "Spotify Echo Küche"            @ "Küche"           [deviceName="Küche"]
    device KiZi_Thing_Echo_Spotify          "Spotify Echo Kinderzimmer"     @ "Kinderzimmer"    [deviceName="Kinderzimmer"]
    device WoKue_Thing_Echo_Spotify         "Spotify Echo Wohnküche"        @ "Küche"           [deviceName="Wohnküche"]
    device Wohnung_Thing_Echo_Spotify       "Spotify Echo Wohnung"          @ "Wohnung"         [deviceName="Wohnung"]
  //device Mobil_Thing_iPhone_Jonas_Spotify "Spotify iPhone Jonas"          @ "Mobil"           [deviceName="Jonass iPhone"]
}

Player Haus_Spotify_Player                    "Spotify Player"                  {channel="spotify:player:Jonas:trackPlayer"}
String Haus_Spotify_Geraete                   "Geräte: [%s]"                    {channel="spotify:player:Jonas:devices"}
String Haus_Spotify_Akt_Geraet                "Aktuelles Gerät: [%s]"           {channel="spotify:player:Jonas:deviceName"}
Dimmer Haus_Spotify_Volume                    "Spotify Lautsärke Global"        {channel="spotify:player:Jonas:deviceVolume"}
Switch Haus_Spotify_Shuffle                   "Shuffle"                         {channel="spotify:player:Jonas:deviceShuffle"}
String Haus_Spotify_Wiederholungsmodus        "Wiederholungsmodus: [%s]"        {channel="spotify:player:Jonas:trackRepeat"}
String Haus_Spotify_Fortschritt               "Fortschritt: [%s]"               {channel="spotify:player:Jonas:trackProgress"}
Number Haus_Spotify_Fortschritt_Millis        "Fortschritt: [%s]"               {channel="spotify:player:Jonas:trackProgressMs"}
String Haus_Spotify_Laenge                    "Länge: [%s]"                     {channel="spotify:player:Jonas:tackDuration"}
Number Haus_Spotify_Laenge_Millis             "Länge in ms: [%s]"               {channel="spotify:player:Jonas:trackDurationMs"}
String Haus_Spotify_Titel                     "Titel: [%s]"                     {channel="spotify:player:Jonas:trackName"}
String Haus_Spotify_Album                     "Album: [%s]"                     {channel="spotify:player:Jonas:albumName"}
String Haus_Spotify_Interpret                 "Interpret: [%s]"                 {channel="spotify:player:Jonas:artistName"}
Image  Haus_Spotify_Cover                     "Cover"                           {channel="spotify:player:Jonas:albumImage"}
String Haus_Spotify_Playlists                 "Playlists [%s]"                  {channel="spotify:player:Jonas:playlists"}
String Haus_Spotify_Playlist_Name             "Playlist [%s]"                   {channel="spotify:player:Jonas:playlistName"}

String WoZi_Spotify_Name                      "Spotify Name Wohnzimmer"         {channel="spotify:device:Jonas:WoZi_Thing_Echo_Spotify:deviceName"}
Player WoZi_Spotify_Player                    "Spotify Player Wohnzimmer"       {channel="spotify:device:Jonas:WoZi_Thing_Echo_Spotify:devicePlayer"}
Dimmer WoZi_Spotify_Volume                    "Spotify Lautsärke Wohnzimmer"    {channel="spotify:device:Jonas:WoZi_Thing_Echo_Spotify:deviceVolume"}
Switch WoZi_Spotify_Shuffle                   "Spotify Shuffle Wohnzimmer"      {channel="spotify:device:Jonas:WoZi_Thing_Echo_Spotify:deviceShuffle"}

String Keller_Spotify_Name                    "Spotify Name Keller"             {channel="spotify:device:Jonas:Keller_Thing_Echo_Spotify:deviceName"}
Player Keller_Spotify_Player                  "Spotify Player Keller"           {channel="spotify:device:Jonas:Keller_Thing_Echo_Spotify:devicePlayer"}
Dimmer Keller_Spotify_Volume                  "Spotify Lautsärke Keller"        {channel="spotify:device:Jonas:Keller_Thing_Echo_Spotify:deviceVolume"}
Switch Keller_Spotify_Shuffle                 "Spotify Shuffle Keller"          {channel="spotify:device:Jonas:Keller_Thing_Echo_Spotify:deviceShuffle"}

String Bad_Spotify_Name                       "Spotify Name Bad"                {channel="spotify:device:Jonas:Bad_Thing_Echo_Spotify:deviceName"}
Player Bad_Spotify_Player                     "Spotify Player Bad"              {channel="spotify:device:Jonas:Bad_Thing_Echo_Spotify:devicePlayer"}
Dimmer Bad_Spotify_Volume                     "Spotify Lautsärke Bad"           {channel="spotify:device:Jonas:Bad_Thing_Echo_Spotify:deviceVolume"}
Switch Bad_Spotify_Shuffle                    "Spotify Shuffle Bad"             {channel="spotify:device:Jonas:Bad_Thing_Echo_Spotify:deviceShuffle"}

String SchlaZi_Spotify_Name                   "Spotify Name Schlafzimmer"       {channel="spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify:deviceName"}
Player SchlaZi_Spotify_Player                 "Spotify Player Schlafzimmer"     {channel="spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify:devicePlayer"}
Dimmer SchlaZi_Spotify_Volume                 "Spotify Lautsärke Schlafzimmer"  {channel="spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify:deviceVolume"}
Switch SchlaZi_Spotify_Shuffle                "Spotify Shuffle Schlafzimmer"    {channel="spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify:deviceShuffle"}

String Kueche_Spotify_Name                    "Spotify Name Küche"              {channel="spotify:device:Jonas:Kueche_Thing_Echo_Spotify:deviceName"}
Player Kueche_Spotify_Player                  "Spotify Player Küche"            {channel="spotify:device:Jonas:Kueche_Thing_Echo_Spotify:devicePlayer"}
Dimmer Kueche_Spotify_Volume                  "Spotify Lautsärke Küche"         {channel="spotify:device:Jonas:Kueche_Thing_Echo_Spotify:deviceVolume"}
Switch Kueche_Spotify_Shuffle                 "Spotify Shuffle Küche"           {channel="spotify:device:Jonas:Kueche_Thing_Echo_Spotify:deviceShuffle"}

String KiZi_Spotify_Name                      "Spotify Name Kinderzimmer"       {channel="spotify:device:Jonas:KiZi_Thing_Echo_Spotify:deviceName"}
Player KiZi_Spotify_Player                    "Spotify Player Kinderzimmer"     {channel="spotify:device:Jonas:KiZi_Thing_Echo_Spotify:devicePlayer"}
Dimmer KiZi_Spotify_Volume                    "Spotify Lautsärke Kinderzimmer"  {channel="spotify:device:Jonas:KiZi_Thing_Echo_Spotify:deviceVolume"}
Switch KiZi_Spotify_Shuffle                   "Spotify Shuffle Kinderzimmer"    {channel="spotify:device:Jonas:KiZi_Thing_Echo_Spotify:deviceShuffle"}

String WoKue_Spotify_Name                     "Spotify Name Wohnküche"          {channel="spotify:device:Jonas:WoKue_Thing_Echo_Spotify:deviceName"}
Player WoKue_Spotify_Player                   "Spotify Player Wohnküche"        {channel="spotify:device:Jonas:WoKue_Thing_Echo_Spotify:devicePlayer"}
Dimmer WoKue_Spotify_Volume                   "Spotify Lautsärke Wohnküche"     {channel="spotify:device:Jonas:WoKue_Thing_Echo_Spotify:deviceVolume"}
Switch WoKue_Spotify_Shuffle                  "Spotify Shuffle Wohnküche"       {channel="spotify:device:Jonas:WoKue_Thing_Echo_Spotify:deviceShuffle"}

String Wohnung_Spotify_Name                   "Spotify Name Wohnung"            {channel="spotify:device:Jonas:Wohnung_Thing_Echo_Spotify:deviceName"}
Player Wohnung_Spotify_Player                 "Spotify Player Wohnung"          {channel="spotify:device:Jonas:Wohnung_Thing_Echo_Spotify:devicePlayer"}
Dimmer Wohnung_Spotify_Volume                 "Spotify Lautsärke Wohnung"       {channel="spotify:device:Jonas:Wohnung_Thing_Echo_Spotify:deviceVolume"}
Switch Wohnung_Spotify_Shuffle                "Spotify Shuffle Wohnung"         {channel="spotify:device:Jonas:Wohnung_Thing_Echo_Spotify:deviceShuffle"}

String Jonas_iPhone_Spotify_Name              "Spotify Name iPhone Jonas"            {channel="spotify:device:Jonas:Mobil_Thing_iPhone_Jonas_Spotify:deviceName"}
Player Jonas_iPhone_Spotify_Player            "Spotify Player iPhone Jonas"          {channel="spotify:device:Jonas:Mobil_Thing_iPhone_Jonas_Spotify:devicePlayer"}
Dimmer Jonas_iPhone_Spotify_Volume            "Spotify Lautsärke iPhone Jonas"       {channel="spotify:device:Jonas:Mobil_Thing_iPhone_Jonas_Spotify:deviceVolume"}
Switch Jonas_iPhone_Spotify_Shuffle           "Spotify Shuffle iPhone Jonas"         {channel="spotify:device:Jonas:Mobil_Thing_iPhone_Jonas_Spotify:deviceShuffle"}

hilbrand · November 4, 2019, 9:06pm

You can set debug or trace (trace will give a lot of data) with:
log:set DEBUG org.openhab.binding.spotify

What can be of impact is:

The refresh rate. The refresh rate calls Spotify for play status, playlists and devices each time. So if refresh rate is 5 seconds. It will make 3 calls every 5 seconds and update the channels.
When using the trackProgress or trackProgressMs channels it will update these channels by computing the progress every second. (But the actual status is only updated during the refresh of the previous point).

Jonas88 · November 5, 2019, 6:56am

Thank you I will check that out, set the log level as described and report back once the problem occurs. Thank you for your support.

gytisgreitai · November 5, 2019, 6:54pm

Few weeks ago I think(?) I might have had the same problem. Basically I was messing with my internet connection so my openhab box was cut off for 15-30 minutes. After some period of time the cpu just spiked and could only be resolved by restarting openhab. So is your mac always on?

Jonas88 · November 6, 2019, 11:24am

Hello

I installed it yesterday in the afternoon and tomorrow i had the same problem.
The problem started at around 09:37. I attached the log between 09:36 and 09:39.
I hope you can take any conclusions out of it.

2019-11-06 09:36:12.390 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:36:12.436 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:12.437 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:36:12.484 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:36:12.485 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:36:12.577 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:12.578 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:36:12.578 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:36:12.579 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:36:12.579 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:36:12.580 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:36:12.580 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:36:12.580 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:36:18.532 [DEBUG] [covery.SpotifyDeviceDiscoveryService] - Starting Spotify Device discovery for bridge spotify:player:Jonas
2019-11-06 09:36:18.533 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:36:18.577 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:22.615 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:36:22.659 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:22.660 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:36:22.705 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:36:22.706 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:36:22.805 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:22.806 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:36:22.807 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:36:22.808 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:36:22.808 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:36:22.809 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:36:22.809 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:36:22.809 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:36:32.956 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:36:33.005 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:33.006 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:36:33.046 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:36:33.046 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:36:33.163 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:33.164 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:36:33.165 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:36:33.165 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:36:33.166 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:36:33.166 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:36:33.167 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:36:33.167 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:36:41.321 [WARN ] [calimero.link.192.168.178.40:3671   ] - negative confirmation of 1/0/1: 2e00bde0110e0801090080770b060924292400
2019-11-06 09:36:43.315 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:36:43.366 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:43.367 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:36:43.437 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:36:43.438 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:36:43.521 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:43.522 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:36:43.523 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:36:43.523 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:36:43.524 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:36:43.524 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:36:43.525 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:36:43.525 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:36:53.674 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:36:53.720 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:53.721 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:36:53.764 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:36:53.764 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:36:53.866 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:36:53.867 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:36:53.868 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:36:53.868 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:36:53.869 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:36:53.869 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:36:53.870 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:36:53.870 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:37:03.949 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:37:03.998 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:03.999 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:37:04.048 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:37:04.048 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:37:04.127 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:04.128 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:37:04.129 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:37:04.129 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:37:04.130 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:37:04.130 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:37:04.131 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:37:04.131 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:37:14.282 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:37:14.326 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:14.327 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:37:14.370 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:37:14.370 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:37:14.457 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:14.458 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:37:14.459 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:37:14.459 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:37:14.459 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:37:14.460 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:37:14.479 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:37:14.480 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:37:18.717 [DEBUG] [covery.SpotifyDeviceDiscoveryService] - Starting Spotify Device discovery for bridge spotify:player:Jonas
2019-11-06 09:37:18.718 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:37:18.763 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:24.625 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:37:24.670 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:24.671 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:37:24.713 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:37:24.714 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:37:24.793 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:24.794 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:37:24.794 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:37:24.795 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:37:24.795 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:37:24.796 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:37:24.796 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:37:24.797 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:37:34.928 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:37:34.981 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:34.982 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:37:35.020 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:37:35.020 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:37:35.102 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:35.103 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:37:35.104 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:37:35.104 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:37:35.104 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:37:35.105 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:37:35.105 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:37:35.105 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:37:41.332 [WARN ] [calimero.link.192.168.178.40:3671   ] - negative confirmation of 1/0/1: 2e00bde0110e0801090080770b060925292400
2019-11-06 09:37:45.244 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:37:45.288 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:45.288 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:37:45.332 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:37:45.332 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:37:45.423 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:45.424 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:37:45.425 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:37:45.425 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:37:45.426 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:37:45.426 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:37:45.427 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:37:45.427 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:37:55.562 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:37:55.604 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:55.604 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:37:55.642 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:37:55.642 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:37:55.730 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:37:55.732 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:37:55.732 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:37:55.733 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:37:55.733 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:37:55.734 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:37:55.734 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:37:55.734 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:38:05.878 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:38:05.923 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:05.924 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:38:05.966 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:38:05.966 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:38:06.049 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:06.050 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:38:06.051 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:38:06.051 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:38:06.052 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:38:06.052 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:38:06.053 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:38:06.053 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:38:16.200 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:38:16.244 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:16.244 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:38:16.290 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:38:16.291 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:38:16.374 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:16.375 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:38:16.375 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:38:16.376 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:38:16.376 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:38:16.376 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:38:16.377 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:38:16.377 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:38:18.889 [DEBUG] [covery.SpotifyDeviceDiscoveryService] - Starting Spotify Device discovery for bridge spotify:player:Jonas
2019-11-06 09:38:18.889 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:38:18.930 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:26.513 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:38:26.564 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:26.564 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:38:26.604 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:38:26.604 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:38:26.706 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:26.707 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:38:26.708 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:38:26.708 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:38:26.709 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:38:26.709 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:38:26.709 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:38:26.710 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:38:36.821 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:38:36.863 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:36.863 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:38:36.912 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:38:36.912 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:38:36.991 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:36.992 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:38:36.992 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:38:36.993 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:38:36.993 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:38:36.993 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:38:36.994 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:38:36.994 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:38:41.470 [WARN ] [calimero.link.192.168.178.40:3671   ] - negative confirmation of 1/0/1: 2e00bde0110e0801090080770b060926292400
2019-11-06 09:38:47.133 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:38:47.173 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:47.174 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:38:47.217 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:38:47.218 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:38:47.299 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:47.300 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:38:47.300 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:38:47.301 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:38:47.301 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:38:47.301 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:38:47.302 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:38:47.302 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]
2019-11-06 09:38:57.404 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player/devices - 
2019-11-06 09:38:57.446 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:57.446 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/player - 
2019-11-06 09:38:57.492 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 204
2019-11-06 09:38:57.492 [DEBUG] [ding.spotify.internal.api.SpotifyApi] - Request: (GET) https://api.spotify.com/v1/me/playlists - 
2019-11-06 09:38:57.574 [DEBUG] [potify.internal.api.SpotifyConnector] - Response Code: 200
2019-11-06 09:38:57.575 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoZi_Thing_Echo_Spotify Device [ dea05b28e3648a1335a518496c0e8d5fb3c9c19d Wohnzimmer, Speaker ]
2019-11-06 09:38:57.575 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Keller_Thing_Echo_Spotify Device [ 8e5968efe20e988dde0e32b540bb207b673dfe9d Keller, Speaker ]
2019-11-06 09:38:57.576 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Bad_Thing_Echo_Spotify Device [ 647968f76570544d8e18bfe1675c2845ebbdf738 Bad, Speaker ]
2019-11-06 09:38:57.576 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:SchlaZi_Thing_Echo_Spotify Device [ 7ec0db1aa9ffd8d33abde0ca90eea4c6b7684604 Schlafzimmer, Speaker ]
2019-11-06 09:38:57.576 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Kueche_Thing_Echo_Spotify Device [ c3896c5ce3c3097e5cc8ce12b8ff2ed9c921b5f3 Küche, Speaker ]
2019-11-06 09:38:57.577 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:WoKue_Thing_Echo_Spotify Device [ 0605cc957cd5cde7631ed8acf3adf41d0b7f6ec2 Wohnküche, Speaker ]
2019-11-06 09:38:57.577 [DEBUG] [nternal.handler.SpotifyDeviceHandler] - Updating status of Thing: spotify:device:Jonas:Wohnung_Thing_Echo_Spotify Device [ 16c5126be33e766a3326cc5e85e61782bed61783 Wohnung, Speaker ]

hilbrand · November 7, 2019, 8:56pm

The log doesn’t look very strange. But I noticed it give 204, meaning no data is returned. I’ve updated the binding to not update the state when a 204 is given. Also I reduced updating playlist to once per hour (or when explicit refresh command is send to channel). This update is available via the Eclipse IoT Marketplace, accessible when enabled in PaperUI.

Jonas88 · November 8, 2019, 6:01am

I will check it out, thank you!
EDIT: My things won’t come online …
The account is online but my devices show a “unkown” status.

hilbrand · November 9, 2019, 8:55am

I’ll look into it. A check question. If you start playing on your device or click play in openHAB doe they then come online?

P.W · November 9, 2019, 10:40am

Hello, in the last weks i have start woth openhab. So i’m really a beginner
Today i want to implement the spotify bridge. I found the binding and install it. I also crate a Client and secret ID and i enter the Redirect URIs.
Currently i have the problem taht i dont find the binding on the paperui.
I have try to create the binding manuelle in visualcode because im not sure i have configre the connecten correctly. Because i didn’t know where to put a user name and password.

rkrisi · November 9, 2019, 7:37pm

@hilbrand did you find anything related to the ‘Weak chiper suite’ warning?

gytisgreitai · November 9, 2019, 8:12pm

I had to cleanup the openhab cache. After that spotify binding seemed to be uninstalled. Installed it via paper ui (Spotify Binding Beta (market:binding-4326451 - 1.0)) . First it complained that gson is not found Unresolved requirement: Import-Package: com.google.gson; installed it with bundle:install. Not it fails with other class not found errors:

Caused by: java.lang.ClassNotFoundException: org.eclipse.smarthome.core.thing.binding.BaseDynamicStateDescriptionProvider cannot be found by org.openhab.binding.spotify_2.5.0.201911072000

Perhaps this version 2.5.0.201911072000 is meant for openhab 2.5 ?

EDIT: ok, for now compiled from source from https://github.com/Hilbrand/openhab2-addons.git and spotify_2.4.0 branch

hilbrand · November 11, 2019, 9:26pm

@Jonas88 I’ve updated the binding in the Marketplace it now handles devices status better.

If you have the official installation it’s openHAB version 2.4. The binding is not yet available in that version. But you can install it via the MarketPlace. In PaperUI go to Configuration → System → and Enable MarketPlace Select Maturity Level Beta. Then you should be able to install Spotify in Add-ons.

@rkrisi I did look into it. The binding uses a common client initialized by openHAB core. A client can be initialized to just accept any ssl connection, but the default, used, is more strict. I don’t know why it now gives this error or if it is related to the more strict handling. In that case I would expect to see it more often. So I’m not sure how to research this better for now.

I had uploaded a 2.5.0 only version. I’ve fixed that and uploaded a new version. It’s made me realize this can be a real problem for installations. The marketplace has no version history. So when a new version in the marketplace is published it just replaces the existing version and if that is not compatible with the previous version it can break users installations.

rkrisi · November 11, 2019, 9:28pm

Maybe this can help?

github.com/openhab/openhab-core

Fix Jetty warnings by excluding weak cipher suites

openhab:master ← wborn:exclude-weak-ciphers

opened 09:21PM - 08 Jun 19 UTC

wborn

+5 -2

Newer Jetty versions log warnings when weak cipher suites are configured. This …occurs when using Karaf 4.2.6 with Jetty 9.4.18.v20190429. See also: * https://github.com/eclipse/jetty.project/pull/3050 * https://github.com/openhab/openhab-distro/pull/900#issuecomment-495381858 Fixes the following warnings: ``` 22:25:04.336 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_256_CBC_SHA256 enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.338 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.339 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.340 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.340 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.340 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.341 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDH_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.347 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_DHE_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.351 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_DHE_DSS_WITH_AES_256_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.352 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_128_CBC_SHA256 enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.352 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.354 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.355 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.356 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.359 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.359 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDH_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.360 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_DHE_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.361 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_DHE_DSS_WITH_AES_128_CBC_SHA enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.361 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_256_GCM_SHA384 enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.362 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_128_GCM_SHA256 enabled for SslContextFactory@12d643d6[provider=null,keyStore=null,trustStore=null] 22:25:04.379 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_256_CBC_SHA256 enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.379 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.380 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.380 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.381 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.381 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.382 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDH_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.383 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_DHE_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.384 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_DHE_DSS_WITH_AES_256_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.384 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_128_CBC_SHA256 enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.385 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.386 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.386 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.387 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.388 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.389 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDH_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.389 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_DHE_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.390 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_DHE_DSS_WITH_AES_128_CBC_SHA enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.391 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_256_GCM_SHA384 enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] 22:25:04.391 [WARN ] [tty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_RSA_WITH_AES_128_GCM_SHA256 enabled for SslContextFactory@39765aa[provider=null,keyStore=null,trustStore=null] ```

and my issue:

github.com/openhab/openhab-core

Weak cipher suite warning since 2.5M2

opened 04:08PM - 01 Oct 19 UTC

closed 10:22PM - 25 Nov 19 UTC

radokristof

Dear community! Other also reported that since M2 (and it is still present in… M3), we usually get a long list of warnings: `2019-09-30 03:42:34.055 [WARN ] [ty.util.ssl.SslContextFactory.config] - Weak cipher suite TLS_ECDH_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory@17b090b[provider=null,keyStore=null,trustStore=null]` First I thought it is because of the new mail binding, but others reported that they don't use mail bindings and still gets these warnings. @J-N-K said it might be related to Jetty. I know this is just a warning, but I send a log report for myself daily to quickly identify problems in my setup. This warnings makes it unreadable because I get around 600-700 warnings a day just for this. One temporary solution might be to set logging only for ERROR for the bundle which causes this warning, but I haven't figured it out yet which bundle is causing this.

Lanovar · November 13, 2019, 6:28pm

Hi, first of all: Nice work!

Im running the binding on 2.5M4 and if i try to use any item linked to a devicechannel i get this error:

2019-11-13 19:07:13.421 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.handleCommand()' on 'org.openhab.binding.spotify.internal.handler.SpotifyDeviceHandler@1f33d6a': Client service is closed

org.openhab.binding.spotify.internal.api.exception.SpotifyAuthorizationException: Client service is closed

	at org.openhab.binding.spotify.internal.api.SpotifyApi.request(SpotifyApi.java:289) ~[?:?]

	at org.openhab.binding.spotify.internal.api.SpotifyApi.requestPlayer(SpotifyApi.java:261) ~[?:?]

	at org.openhab.binding.spotify.internal.api.SpotifyApi.requestPlayer(SpotifyApi.java:248) ~[?:?]

	at org.openhab.binding.spotify.internal.api.SpotifyApi.setVolume(SpotifyApi.java:168) ~[?:?]

	at org.openhab.binding.spotify.internal.handler.SpotifyHandleCommands.handleCommand(SpotifyHandleCommands.java:116) ~[?:?]

	at org.openhab.binding.spotify.internal.handler.SpotifyDeviceHandler.handleCommand(SpotifyDeviceHandler.java:68) ~[?:?]

	at sun.reflect.GeneratedMethodAccessor94.invoke(Unknown Source) ~[?:?]

	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [133:org.openhab.core:2.5.0.M4]

	at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [133:org.openhab.core:2.5.0.M4]

	at com.sun.proxy.$Proxy446.handleCommand(Unknown Source) [?:?]

	at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:74) [185:org.openhab.core.thing:2.5.0.M4]

	at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:48) [185:org.openhab.core.thing:2.5.0.M4]

	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]

	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [133:org.openhab.core:2.5.0.M4]

	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:52) [133:org.openhab.core:2.5.0.M4]

	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]

	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]

	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]

	at java.lang.Thread.run(Thread.java:748) [?:?]

Caused by: org.eclipse.smarthome.core.auth.client.oauth2.OAuthException: Client service is closed

	at org.eclipse.smarthome.auth.oauth2client.internal.OAuthClientServiceImpl.getAccessTokenResponse(OAuthClientServiceImpl.java:318) ~[?:?]

	at org.openhab.binding.spotify.internal.api.SpotifyApi.request(SpotifyApi.java:277) ~[?:?]

	... 23 more

If Spotify eg is running on my iPhone i can controll it with the player items. Device items perform the Error.
I noticed that all Device in PaperUI got the Error-Bridge_Offline tag until i for example open the Spotify app on my phone, then theyll show as online.

Jonas88 · November 14, 2019, 7:03pm

Thank you for your effort! I’ll check it out soon and report back!