Sonos Steuerung extrem langsam

[Tobbi]

Hallo zusammen,

seit kurzem bin ich auch User der FHEM Software und bin bisher sehr begeistert.
Mit viel lesen hier im Forum und in den Dokumentationen habe ich auch schon eine ganze Menge umgesetzte.

Nun hänge ich allerdings an einem Problem, bei dem ich nicht weiterkomme.
Ich nutze das Sonos Modul mit 2 Play-1 Playern. Dies hat zu Beginn (Sonntag Abend) auch einwandfrei funktioniert.
Ca. 20min nach dem Einrichten, wurde die ganze Steuerungsgeschichte der Sonos jedoch extrem langsam.
Play/Pause/Lauter/Leiser dauert mehrere Minuten, bis es am Gerät ankommt.
Hierbei ist es egal welches Gerät es ist, was darauf läuft oder ob es in Gruppen läuft.

In der Logfile konnte ich gerade folgendes finden, als ich den Fehler wieder hatte.

Code Auswählen Erweitern

2015.12.22 14:03:27 3: SONOS0: Connection accepted from localhost:54541
2015.12.22 14:04:00 3: SONOS0: Connection accepted from localhost:54545
2015.12.22 14:04:30 3: SONOS0: Connection accepted from localhost:54548
2015.12.22 14:05:03 3: SONOS0: Connection accepted from localhost:54552
Loading device description failed with error: 500 Can't connect to 10.1.34.237:49152 at ./FHEM/00_SONOS.pm line 3707 thread 1.
Odd number of elements in hash assignment at /usr/share/perl/5.20/IO/Socket/IP.pm line 109, <$client> line 5.

Ein paar Minuten nach dem Umstellen kommt dann folgender Teil.

Code Auswählen Erweitern

2015.12.22 14:11:11 3: SONOS1: Error! Rendering-Subscription for ZonePlayer "RINCON_B8E937841B1A01400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3453 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! GroupRendering-Subscription for ZonePlayer "RINCON_B8E937841B1A01400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3469 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! ContentDirectory-Subscription for ZonePlayer "RINCON_B8E937841B1A01400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3485 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! Alarm-Subscription for ZonePlayer "RINCON_B8E937841B1A01400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3501 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! ZoneGroupTopology-Subscription for ZonePlayer "RINCON_B8E937841B1A01400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3517 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! DeviceProperties-Subscription for ZonePlayer "RINCON_B8E937841B1A01400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3533 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! Transport-Subscription for ZonePlayer "RINCON_B8E937840C9001400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3437 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! Rendering-Subscription for ZonePlayer "RINCON_B8E937840C9001400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3453 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! GroupRendering-Subscription for ZonePlayer "RINCON_B8E937840C9001400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3469 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! ContentDirectory-Subscription for ZonePlayer "RINCON_B8E937840C9001400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3485 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! Alarm-Subscription for ZonePlayer "RINCON_B8E937840C9001400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3501 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! ZoneGroupTopology-Subscription for ZonePlayer "RINCON_B8E937840C9001400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3517 thread 1.

2015.12.22 14:11:11 3: SONOS1: Error! DeviceProperties-Subscription for ZonePlayer "RINCON_B8E937840C9001400_MR" has expired and could not be renewed: Renewal of subscription failed with error: 412 Precondition Failed at ./FHEM/00_SONOS.pm line 3533 thread 1

Kann mir jemand bei dem Thema helfen.
Ich bin gerade ziemlich ratlos, wie ich das Problem weiter eingrenzen kann.
Noch ein Hinweis: Wenn ich die Player in FHEM komplett lösche und wieder neu definiere, klappt es erstmal wieder richtig gut (Ohne merkliche Verzögerungen)

Gruß Tobias

[Benni]

Hallo Tobbi,

das selbe Verhalten konnte ich bei mir auch beobachten.
Das geht bei mir letztendlich sogar soweit, dass die Player-Devices irgendwann anscheinend gar nicht mehr reagieren.

Leider habe ich derzeit auch keine Lösung parat. Da ich wichtigere Baustellen habe, als die SONOS in FHEM einzubinden, habe ich das bei mir erst mal wieder deaktiviert und auf später verschoben.

Ich hänge mich aber interessehalber mal mit an diesen Thread dran

Gruß Benni.

[moustic999]

I have exact same problem here. at startup it wors fine but after some times it become really slow.

[Reinerlein]

Hi Tobias,

ist denn die IP-Adresse 10.1.34.237 eine von deinen Zoneplayern?
Wenn nicht, dann schließe die doch mal mittels ignoredIPs aus...

Kannst du mal einen 5er Log erstellen, und mir den Abschnitt vom Start des Befehls (z.B. Play) bis zur Ausführung (also bis zur Meldung im transportState) hier posten?

Ansonsten kann diese Meldung mit der Precondition schon mal auftreten. Die habe ich auch manchmal, aber dann nicht gehäuft, sondern als Ausnahme...

@moustic999: Do you have any logs according this issue? Anything that shows up, what happened in the moment?

Grüße
Reiner

[moustic999]

I read completely the big topin  about Sonos, and finally understood the problem.
I had 2 IP not from any Sonos apprearing. I put these in IgnoredIps and the problem is gone. Now it is not slow and unresponsive anymore.

[ujaudio]

The new attribut "usedonlyIPs" helped a lot in my configuration, so I really can recommend to use it. Normally we do not change our music environment a lot, do we?

Nevertheless I get some remarks in the log from the Sonos module once in a while. Example:

Code Auswählen Erweitern

Current: "./FHEM/00_SONOS.pm", gPath: ""
I get this even I have verbose = 0 and I have no idea whether I should be concerned or not.

[Reinerlein]

Hi Jürgen,

diese Meldungen bedeuten nur, dass der SubProzess und/oder das Modul gestartet sind. Diese Meldungen kommen statisch beim Starten der Perl-Modul-Datei und zeigen die unterschiedlichen Konfigurationen an. Hier wird ja ein und dieselbe Datei in zwei verschiedenen Kontexten (einmal als Fhem-Modul, und einmal als eigenständiger Perl Prozess) verwendet, und dazu brauchte ich eine Ausgabe, um das zu unterscheiden.

Da zu diesem Zeitpunkt noch keinerlei Verbose-Information vorliegt, kann diese auch nicht berücksichtigt werden. Es sollten aber im Normalfall (zusammen mit der Verbose-Umschaltungsnachricht selbst) die einzigen Zeilen (bei Verbose=0) im Log bleiben...

Grüße
Reiner