Squeezebox Modul - erste Version

[TeeVau]

Hi, habe das Modul vorhin ersetzt und mit Reload nachgeladen. Glücklicherweise liegt der Freeze gerade bei ca. 8 Sekunden.

Code Auswählen Erweitern


2016.06.25 15:01:39.424 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 2762 0 2762
2016.06.25 15:01:39.425 1: Perfmon: possible freeze starting at 15:01:37, delay is 2.425
2016.06.25 15:01:46.000 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 6563 0 6563
2016.06.25 15:01:46.119 1: Perfmon: possible freeze starting at 15:01:40, delay is 6.119
2016.06.25 15:06:45.533 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 8777 0 8777
2016.06.25 15:06:45.534 1: Perfmon: possible freeze starting at 15:06:37, delay is 8.534
2016.06.25 15:06:46.642 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 1094 0 1094
2016.06.25 15:11:45.739 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 8724 0 8724
2016.06.25 15:11:45.740 1: Perfmon: possible freeze starting at 15:11:37, delay is 8.739
2016.06.25 15:11:46.838 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 1086 0 1087
2016.06.25 15:16:46.015 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 8946 0 8946
2016.06.25 15:16:46.015 1: Perfmon: possible freeze starting at 15:16:38, delay is 8.015
2016.06.25 15:16:47.125 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 1105 0 1105
2016.06.25 15:18:08.467 3: UWZ Unwetterzentrale: Run.637 Done fetching data
2016.06.25 15:21:46.364 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 8949 0 8949
2016.06.25 15:21:46.365 1: Perfmon: possible freeze starting at 15:21:38, delay is 8.365
2016.06.25 15:21:47.482 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 1103 0 1103
2016.06.25 15:26:46.269 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 8787 0 8787
2016.06.25 15:26:46.269 1: Perfmon: possible freeze starting at 15:26:38, delay is 8.269
2016.06.25 15:26:47.402 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 1116 0 1116


Ich lass das mal so noch laufen und mache keinen Reset. Ist vielleicht ganz praktisch den Fehler gerade so ausgeprägt zu haben

[ChrisD]

Hallo,

Anbei eine neue Version die versucht das Problem weiter einzugrenzen.

Grüße,

ChrisD

[TeeVau]

Hier der erste Output.

Code Auswählen Erweitern


2016.06.25 18:36:49.824 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 9181 9182 nCmds: 15
2016.06.25 18:36:49.824 1: Perfmon: possible freeze starting at 18:36:41, delay is 8.824
2016.06.25 18:36:51.039 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 1200 1200 nCmds: 4
2016.06.25 18:36:51.040 1: Perfmon: possible freeze starting at 18:36:50, delay is 1.04
2016.06.25 18:41:49.868 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 9159 9159 nCmds: 15
2016.06.25 18:41:49.868 1: Perfmon: possible freeze starting at 18:41:41, delay is 8.868
2016.06.25 18:41:51.029 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 1154 1154 nCmds: 2
2016.06.25 18:41:51.029 1: Perfmon: possible freeze starting at 18:41:50, delay is 1.029
2016.06.25 18:46:49.973 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 9130 9130 nCmds: 16
2016.06.25 18:46:49.973 1: Perfmon: possible freeze starting at 18:46:41, delay is 8.973
2016.06.25 18:46:51.110 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 1133 1133 nCmds: 2
2016.06.25 18:46:51.110 1: Perfmon: possible freeze starting at 18:46:50, delay is 1.11


[ChrisD]

Hallo,

Ich hatte gehofft dass bei der Ausgabe die Rückmeldung mit ausgegeben wird welche den Hänger bewirkt. Es sieht aber danach aus als ob die Abarbeitung der einzelnen Statusmeldungen im Moment etwa 500 ms dauert. Wenn wie bei einem StatusRequest viele Rückmeldungen gleichzeitig kommen kommt es zum Hänger.

Ich befürchte dass es ein Problem beim Bearbeiten der Events innerhalb von FHEM gibt.

Kannst du:
- 'set SqueezeBoxServer statusRequest' ausführen und überprüfen ob ein Hänger geloggt wird
- bei allen Playern das Attribut donotnotify auf true setzen
- erneut 'set SqueezeBoxServer statusRequest' ausführen und schauen ob es zum Hänger kommt (auch mehrfach)
- donotnotify wieder zurücksetzen

Grüße,

ChrisD

[TeeVau]

Hallo,

Ich hatte gehofft dass bei der Ausgabe die Rückmeldung mit ausgegeben wird welche den Hänger bewirkt. Es sieht aber danach aus als ob die Abarbeitung der einzelnen Statusmeldungen im Moment etwa 500 ms dauert. Wenn wie bei einem StatusRequest viele Rückmeldungen gleichzeitig kommen kommt es zum Hänger.

Ich befürchte dass es ein Problem beim Bearbeiten der Events innerhalb von FHEM gibt.

Kannst du:
- 'set SqueezeBoxServer statusRequest' ausführen und überprüfen ob ein Hänger geloggt wird
- bei allen Playern das Attribut donotnotify auf true setzen
- erneut 'set SqueezeBoxServer statusRequest' ausführen und schauen ob es zum Hänger kommt (auch mehrfach)
- donotnotify wieder zurücksetzen

Grüße,

ChrisD

Hi ChrisD,

gerne doch!

- 'set SqueezeBoxServer statusRequest' ausführen und überprüfen ob ein Hänger geloggt wird

Code Auswählen Erweitern


2016.06.26 20:34:13.219 0: SB_SERVER_Read(SqueezeBoxServer), time:0 cmd: serverstatus 0 200 lastscan:1450257194 version:7.7.5 uuid:02fdc017-440f-4a64-88ae-b66d800830f9 info total albums:544 info total artists:175 info total genres:68 info total songs:7987 player count:3 playerid:74:da:38:35:71:85 uuid: ip:192.168.178.42:52632 name:Kueche seq_no:0 model:squeezelite power:1 displaytype:none canpoweroff:1 connected:1 isplayer:1 playerid:74:da:38:35:71:7b uuid: ip:192.168.178.44:52136 name:Wohnzimmer seq_no:0 model:squeezelite power:1 displaytype:none canpoweroff:1 connected:1 isplayer:1 playerid:80:1f:02:f2:ee:17 uuid: ip:192.168.178.22:51763 name:Badezimmer seq_no:0 model:squeezelite power:0 displaytype:none canpoweroff:1 connected:1 isplayer:1 sn player count:0 other player count:0
2016.06.26 20:34:13.222 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 4539 4539 nCmds: 4
2016.06.26 20:34:13.222 1: Perfmon: possible freeze starting at 20:34:09, delay is 4.222
2016.06.26 20:34:19.303 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 2885 2886 nCmds: 37
2016.06.26 20:34:19.303 1: Perfmon: possible freeze starting at 20:34:17, delay is 2.303
2016.06.26 20:34:26.186 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 6882 6882 nCmds: 14
2016.06.26 20:34:26.187 1: Perfmon: possible freeze starting at 20:34:20, delay is 6.187


Ein zweiter Versuch:

Code Auswählen Erweitern

2016.06.26 20:35:23.799 0: SB_SERVER_Read(SqueezeBoxServer), time:0 cmd: serverstatus 0 200 lastscan:1450257194 version:7.7.5 uuid:02fdc017-440f-4a64-88ae-b66d800830f9 info total albums:544 info total artists:175 info total genres:68 info total songs:7987 player count:3 playerid:74:da:38:35:71:85 uuid: ip:192.168.178.42:52632 name:Kueche seq_no:0 model:squeezelite power:1 displaytype:none canpoweroff:1 connected:1 isplayer:1 playerid:74:da:38:35:71:7b uuid: ip:192.168.178.44:52136 name:Wohnzimmer seq_no:0 model:squeezelite power:1 displaytype:none canpoweroff:1 connected:1 isplayer:1 playerid:80:1f:02:f2:ee:17 uuid: ip:192.168.178.22:51763 name:Badezimmer seq_no:0 model:squeezelite power:0 displaytype:none canpoweroff:1 connected:1 isplayer:1 sn player count:0 other player count:0
2016.06.26 20:35:23.802 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 4678 4678 nCmds: 3
2016.06.26 20:35:23.803 1: Perfmon: possible freeze starting at 20:35:20, delay is 3.803
2016.06.26 20:35:29.940 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 2879 2880 nCmds: 36
2016.06.26 20:35:29.941 1: Perfmon: possible freeze starting at 20:35:27, delay is 2.94
2016.06.26 20:35:36.905 0: SB_SERVER_Read(SqueezeBoxServer), times: 0 0 0 6960 6960 nCmds: 14
2016.06.26 20:35:36.905 1: Perfmon: possible freeze starting at 20:35:30, delay is 6.905


Bei dem donotnotify ist mir aufgefallen, dass es einmal ein attr "donotnotify" und "do_not_notify" gibt. Muss ich da was spezielles bei beachten?
Schalte ich "donotnotify" auf true, kommt bei "set xxx statusRequest" kein freeze mehr. Hab mit ca. 10 statusRequest getestet.
Interessant ist vielleicht, jetzt wo ich wieder alle player (3 Stück) auf "donotnotify false" gesetzt habe, gibt es keinen freeze bei statusRequest.

Grüße, Tobias

[FHEMAN]

Hallo ChrisD,

darf ich mit zwei Fragen einmal kurz zwischenfragen:

1. SayText / talk funktioniert bei mir bei beim ersten Aufruf nicht. Ab dem zweiten Mal ohne Probleme. Das kann ich reproduzieren, wenn ich den Player wechsele. Hast Du eine Idee?
2. Könntest Du und man selbst eine Art "SayTextNotif" implementieren, der den gesprochenen Text pro Player immer in einer vorher definierten Lautstärke abspielt? (vorher Volume setzen funktionierte bei mir nicht, da der Wert es anscheinend zu spät gesetzt wird) Hintergrund ist: in Küche und Flur ist die Musiklautstärke teilw. so leise eingestellt, dass der Text nicht verständlich ist

Einen schöne Abend
Ronny

[ChrisD]

Hallo,

@Ronny:
1. Kannst du das Attribut 'ttsOptions' beim Player auf 'debug,debugsaverestore' setzen, die Ausgabe starten und die Zeilen aus dem Log posten die mit '0: SB_PLAYER_SetTTSState' und '0: SB_PLAYER_Save' beginnen ?

2. Wie setzt du die Lautstärke ? Über einen getrennten Befehl oder über das Attribut ttsVolume ?

@Tobias:

Bei dem donotnotify ist mir aufgefallen, dass es einmal ein attr "donotnotify" und "do_not_notify" gibt. Muss ich da was spezielles bei beachten?

"donotnotify" wird vom Modul intern verarbeitet. Ich kenne leider nicht den genauen Grund wieso es eingeführt wurde. Beide Attribute haben eine ähnliche Funktion und "donotnotify" ist eigentlich überflüssig geworden. Aus Kompatibilitätsgründen habe ich es aber (noch) nicht entfernt. Bei "do_not_notify" kann man übrigens den Wert 0 oder 1 angeben, es scheint aber keine Rolle zu spielen welchen Wert das Attribut hat, sobald es existiert werden keine Events mehr erzeugt.

Wenn donotnotify auf true steht werden keine Events mehr vom Modul erzeugt, wodurch auch keine notify, filelog, ... von FHEM ausgeführt werden und die Verarbeitungszeit auf fast 0 zurückgeht.

Interessant ist vielleicht, jetzt wo ich wieder alle player (3 Stück) auf "donotnotify false" gesetzt habe, gibt es keinen freeze bei statusRequest.

Das ist eine gute Nachricht. Ich befürchte aber dass das Problem wieder auftreten wird.

Verwendest du das Attribut 'event-on-change-reading' bei den Playern ?

Grüße,

ChrisD

[TeeVau]

Verwendest du das Attribut 'event-on-change-reading' bei den Playern ?

ja, bei den Playern verwende ich "event-on-change-reading .*"

[Tedious]

MoinMoin,

kurze Frage - soweit läuft alles zufriedenstellend, aber mein Log läuft ziemlich voll mit folgenden Meldungen:

Code Auswählen Erweitern

2016.06.29 08:41:19 1: 192.168.192.33:9090 disconnected, waiting to reappear (Logitech_Server)
2016.06.29 08:41:19 1: 192.168.192.33:9090 reappeared (Logitech_Server)
2016.06.29 08:45:19 1: 192.168.192.33:9090 disconnected, waiting to reappear (Logitech_Server)
2016.06.29 08:45:19 1: 192.168.192.33:9090 reappeared (Logitech_Server)
2016.06.29 08:49:19 1: 192.168.192.33:9090 disconnected, waiting to reappear (Logitech_Server)
2016.06.29 08:49:19 1: 192.168.192.33:9090 reappeared (Logitech_Server)
2016.06.29 08:53:19 1: 192.168.192.33:9090 disconnected, waiting to reappear (Logitech_Server)
2016.06.29 08:53:19 1: 192.168.192.33:9090 reappeared (Logitech_Server)
2016.06.29 08:57:19 1: 192.168.192.33:9090 disconnected, waiting to reappear (Logitech_Server)
2016.06.29 08:57:19 1: 192.168.192.33:9090 reappeared (Logitech_Server)

Gesetzt sind die folgenden Parameter:

Code Auswählen Erweitern

alivetimer 120
doalivecheck true
httpport 9000
maxcmdstack 200
maxfavorites 30
room Multimedia
verbose 1

Liegt das am alivetimer (Standardwert)?

[FHEMAN]

Hallo ChrisD,

@Ronny:
1. Kannst du das Attribut 'ttsOptions' beim Player auf 'debug,debugsaverestore' setzen, die Ausgabe starten und die Zeilen aus dem Log posten die mit '0: SB_PLAYER_SetTTSState' und '0: SB_PLAYER_Save' beginnen ?

gerne:

Code Auswählen Erweitern


2016.06.29 13:19:40 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: save state
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: saving {helper}{playlistIds}: -89939240
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: power off
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: playlistCurrentTrack 0
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: playStatus playing
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: repeat off
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: volumeStraight 30
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: playlist MDR_INFO_900_Wecker
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: favorite -
2016.06.29 13:19:40 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: load playlist
2016.06.29 13:19:40 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for play
2016.06.29 13:19:41 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: playing
2016.06.29 13:19:43 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: stopped
2016.06.29 13:19:43 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: restore state
2016.06.29 13:19:43 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:19:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for power on
2016.06.29 13:19:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: save state
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: saving {helper}{playlistIds}: -85193368
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: power off
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: playlistCurrentTrack 0
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: playStatus stopped
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: repeat off
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: volumeStraight 30
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: playlist MDR_INFO_900_Wecker
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: favorite -
2016.06.29 13:19:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: load playlist
2016.06.29 13:19:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for play
2016.06.29 13:19:54 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: playing
2016.06.29 13:19:58 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: stopped
2016.06.29 13:19:59 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: restore state
2016.06.29 13:19:59 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:20:09 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: active
2016.06.29 13:20:10 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:20:21 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: active
2016.06.29 13:20:25 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:20:33 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for power on
2016.06.29 13:20:33 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: save state
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: saving {helper}{playlistIds}: -85193368
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: power off
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: playlistCurrentTrack 0
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: playStatus stopped
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: repeat off
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: volumeStraight 30
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: playlist MDR_INFO_900_Wecker
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: favorite -
2016.06.29 13:20:33 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: load playlist
2016.06.29 13:20:34 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for play
2016.06.29 13:20:34 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: playing
2016.06.29 13:20:35 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: stopped
2016.06.29 13:20:35 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: restore state
2016.06.29 13:20:35 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:20:47 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for power on
2016.06.29 13:20:47 0: SB_PLAYER_Set: SB.Essen: add to ttsqueue: http://api.voicerss.org/?key=XXX&src=hihozwei.&hl=de-de&f=48khz_16bit_stereo
2016.06.29 13:20:48 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: save state
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: saving {helper}{playlistIds}: -89939240
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: power off
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: playlistCurrentTrack 0
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: playStatus stopped
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: repeat off
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: volumeStraight 30
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: playlist MDR_INFO_900_Wecker
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: favorite -
2016.06.29 13:20:48 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: load playlist
2016.06.29 13:20:48 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for play
2016.06.29 13:20:48 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: playing
2016.06.29 13:20:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: stopped
2016.06.29 13:20:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: restore state
2016.06.29 13:20:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle


Und noch einmal vollständig:

Code Auswählen Erweitern


2016.06.29 13:19:40 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: save state
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: saving {helper}{playlistIds}: -89939240
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: power off
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: playlistCurrentTrack 0
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: playStatus playing
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: repeat off
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: volumeStraight 30
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: playlist MDR_INFO_900_Wecker
2016.06.29 13:19:40 0: SB_PLAYER_Save: SB.Essen: favorite -
2016.06.29 13:19:40 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: load playlist
2016.06.29 13:19:40 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for play
2016.06.29 13:19:41 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: playing
2016.06.29 13:19:43 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: stopped
2016.06.29 13:19:43 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: restore state
2016.06.29 13:19:43 0: SB_PLAYER_Recall: SB.Essen: restoring...
2016.06.29 13:19:43 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:19:44 0: SB_PLAYER_Parse: SB.Essen: load_done
2016.06.29 13:19:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for power on
2016.06.29 13:19:53 0: SB_PLAYER_Set: SB.Essen: add to ttsqueue: http://api.voicerss.org/?key=XXX&src=Hallihallo.&hl=de-de&f=48khz_16bit_stereo
2016.06.29 13:19:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: save state
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: saving {helper}{playlistIds}: -85193368
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: power off
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: playlistCurrentTrack 0
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: playStatus stopped
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: repeat off
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: volumeStraight 30
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: playlist MDR_INFO_900_Wecker
2016.06.29 13:19:53 0: SB_PLAYER_Save: SB.Essen: favorite -
2016.06.29 13:19:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: load playlist
2016.06.29 13:19:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for play
2016.06.29 13:19:54 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: playing
2016.06.29 13:19:58 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: stopped
2016.06.29 13:19:59 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: restore state
2016.06.29 13:19:59 0: SB_PLAYER_Recall: SB.Essen: restoring...
2016.06.29 13:19:59 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:20:00 0: SB_PLAYER_Parse: SB.Essen: load_done
2016.06.29 13:20:09 0: SB_PLAYER_Parse: SB.Essen: fhemrelay ttsactive b8:27:eb:e1:3c:e9
2016.06.29 13:20:09 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: active
2016.06.29 13:20:10 0: SB_PLAYER_Parse: SB.Essen: fhemrelay ttsidle
2016.06.29 13:20:10 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:20:12 1: SB_PLAYER_Alarms(SB.Schlafzimmer): Unknown data (Wecker.m3u)
2016.06.29 13:20:21 0: SB_PLAYER_Parse: SB.Essen: fhemrelay ttsactive b8:27:eb:e1:3c:e9
2016.06.29 13:20:21 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: active
2016.06.29 13:20:25 0: SB_PLAYER_Parse: SB.Essen: fhemrelay ttsidle
2016.06.29 13:20:25 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:20:33 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for power on
2016.06.29 13:20:33 0: SB_PLAYER_Set: SB.Essen: add to ttsqueue: http://api.voicerss.org/?key=XXX&src=hihoho.&hl=de-de&f=48khz_16bit_stereo
2016.06.29 13:20:33 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: save state
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: saving {helper}{playlistIds}: -85193368
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: power off
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: playlistCurrentTrack 0
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: playStatus stopped
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: repeat off
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: volumeStraight 30
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: playlist MDR_INFO_900_Wecker
2016.06.29 13:20:33 0: SB_PLAYER_Save: SB.Essen: favorite -
2016.06.29 13:20:33 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: load playlist
2016.06.29 13:20:34 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for play
2016.06.29 13:20:34 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: playing
2016.06.29 13:20:34 0: SB_PLAYER_Parse: SB.Essen: fhemrelay ttsstopped
2016.06.29 13:20:34 0: SB_PLAYER_Parse: SB.Essen: fhemrelay ttsstopped
2016.06.29 13:20:35 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: stopped
2016.06.29 13:20:35 0: SB_PLAYER_Parse: SB.Essen: fhemrelay ttsstopped
2016.06.29 13:20:35 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: restore state
2016.06.29 13:20:35 0: SB_PLAYER_Recall: SB.Essen: restoring...
2016.06.29 13:20:35 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:20:35 0: SB_PLAYER_Parse: SB.Essen: mode play
2016.06.29 13:20:36 0: SB_PLAYER_Parse: SB.Essen: load_done
2016.06.29 13:20:47 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for power on
2016.06.29 13:20:47 0: SB_PLAYER_Set: SB.Essen: add to ttsqueue: http://api.voicerss.org/?key=XXX&src=hihozwei.&hl=de-de&f=48khz_16bit_stereo
2016.06.29 13:20:48 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: save state
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: saving {helper}{playlistIds}: -89939240
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: power off
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: playlistCurrentTrack 0
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: playStatus stopped
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: repeat off
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: volumeStraight 30
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: playlist MDR_INFO_900_Wecker
2016.06.29 13:20:48 0: SB_PLAYER_Save: SB.Essen: favorite -
2016.06.29 13:20:48 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: load playlist
2016.06.29 13:20:48 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: wait for play
2016.06.29 13:20:48 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: playing
2016.06.29 13:20:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: stopped
2016.06.29 13:20:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: restore state
2016.06.29 13:20:53 0: SB_PLAYER_Recall: SB.Essen: restoring...
2016.06.29 13:20:53 0: SB_PLAYER_SetTTSState: SB.Essen: ttsstate: idle
2016.06.29 13:20:53 0: SB_PLAYER_Parse: SB.Essen: load_done


2. Wie setzt du die Lautstärke ? Über einen getrennten Befehl oder über das Attribut ttsVolume ?

Code Auswählen Erweitern


my $OldVol = ReadingsVal("PLAYER", "volume", 10);
fhem("set PLAYER volume 30");
fhem("set PLAYER playText Hallo Welt");
fhem("set Player volume $OldVol");

Diese Funktion ist aktuell nicht implementiert (hat also nichts mit Punkt 1 zu tun).
Ich vermute, dass sayText in einem eigenen Thread ausgeführt wird und durch die Onlineabfrage länger dauert, wodurch zu schnell wieder der alte Volume Wert gesetzt wird?

Gibt es evtl. noch eine andere Möglichkeit, nur für sayText die Lautstärke zu setzen? Genial wäre irgendein Parameter.

Gruß
Ronny

[ChrisD]

Hallo,

@Tedious: Die Meldungen kommen vom alivetimer. Alle 2 Minuten wird überprüft ob der Rechner auf dem der LMS läuft noch ansprechbar ist. In der Standardeinstellung wird hierfür eine Anfrage an den echo-Port geschickt, wenn 2x keine Antwort kommt wird angenommen dass der Rechner nicht erreichbar ist.

Es gibt mehrere Möglichkeiten die Meldungen abzustellen:
- externen Ping verwenden
- echo-Dienst auf dem Rechner mit LMS starten
- doalivecheck auf false setzen (nur als Notlösung)

Ich bin dabei den alivecheck so zu ändern dass er einerseits nicht mehr alle 2 Minuten aufgerufen wird sondern erst 2 Minuten nachdem die letzten Daten empfangen wurden und andererseits der Ping/Echo-Request optional wird.

Grüße,

ChrisD

[Tedious]

Heyho,

hatte ich so vermutet. Schon mal danke für die Infos!

[aeronaut]

In den letzten Wochen habe ich ein bisschen mit dem Squeezebox-Modul gespielt und bin doch ganz schön begeistert über das gleichzeitige Abspielen von Musik in der Wohnung. Und natürlich von der Steuerung mitels FHEM, zusammen mit TabletUI ist das eine ganz neue Musikerfahrung

Leider habe nun ein Problem mit der FHEM-Integration bekommen. Der Server wird nur noch als disconnected angezeigt und Steuerung ist nicht mehr möglich.

Aus dem Log:

Code Auswählen Erweitern

2016.07.03 15:36:24 1: SB_SERVER(SqueezeBoxServer): invalid username or password ?
2016.07.03 15:38:24 4: SB_SERVER_Alive(SqueezeBoxServer): called
2016.07.03 15:38:24 4: SB_SERVER_Alive(SqueezeBoxServer): using presence.musicbox
2016.07.03 15:38:24 5: SB_SERVER_Alive(SqueezeBoxServer): RCC:off Ping:on
2016.07.03 15:38:24 3: SB_SERVER_Alive(SqueezeBoxServer): overrun SB-Server dead.
2016.07.03 15:38:24 4: SB_SERVER_Broadcast(SqueezeBoxServer): called with SERVER - OFF
2016.07.03 15:40:24 4: SB_SERVER_Alive(SqueezeBoxServer): called
2016.07.03 15:40:24 4: SB_SERVER_Alive(SqueezeBoxServer): using presence.musicbox
2016.07.03 15:40:24 5: SB_SERVER_Alive(SqueezeBoxServer): RCC:off Ping:on
2016.07.03 15:40:24 3: SB_SERVER_Alive(SqueezeBoxServer): SB-Server is back again.
2016.07.03 15:40:34 4: SB_SERVER_Alive(SqueezeBoxServer): called
2016.07.03 15:40:34 4: SB_SERVER_Alive(SqueezeBoxServer): using presence.musicbox
2016.07.03 15:40:34 5: SB_SERVER_Alive(SqueezeBoxServer): RCC:off Ping:on
2016.07.03 15:40:34 5: SW: 6c6f67696e207069616e6f407069616e6f6775792e64652036623379716830760a
2016.07.03 15:40:34 5: SW: 6c697374656e20310a
2016.07.03 15:40:34 5: SW: 7072656620617574686f72697a65203f0a
2016.07.03 15:40:34 5: SW: 76657273696f6e203f0a
2016.07.03 15:40:34 5: SW: 7365727665727374617475732030203230300a
2016.07.03 15:40:34 5: SW: 6661766f7269746573206974656d7320302033302077616e745f75726c3a310a
2016.07.03 15:40:34 5: SW: 706c61796c697374732030203230300a
2016.07.03 15:40:34 5: SW: 616c61726d20706c61796c697374732030203330300a
2016.07.03 15:40:34 5: SW: 6668656d616c697665636865636b0a
2016.07.03 15:40:34 1: SB_SERVER(SqueezeBoxServer): invalid username or password ?
2016.07.03 15:42:34 4: SB_SERVER_Alive(SqueezeBoxServer): called
2016.07.03 15:42:34 4: SB_SERVER_Alive(SqueezeBoxServer): using presence.musicbox
2016.07.03 15:42:34 5: SB_SERVER_Alive(SqueezeBoxServer): RCC:off Ping:on
2016.07.03 15:42:34 3: SB_SERVER_Alive(SqueezeBoxServer): overrun SB-Server dead.
2016.07.03 15:42:34 4: SB_SERVER_Broadcast(SqueezeBoxServer): called with SERVER - OFF

Huch, Passwort falsch? Habs extra nochmal geändert und in LMS und FHEM eingetragen. Der Logeintrag bleibt.

Hier noch die Server-Info:

Code Auswählen Erweitern

Internals:
   ALIVECHECK waiting
   CLICONNECTION on
   CLIPORT    9090
   CMDSTACK   0
   DEF        musicbox.pianonet WOL:pc.musicbox PRESENCE:presence.musicbox USER:xxx@xxx.de PASSWORD:xxx
   DeviceName 127.0.0.1:9090
   IP         127.0.0.1
   LASTANSWER invalid username or password ?
   NAME       SqueezeBoxServer
   NEXT_OPEN  1467553545
   NR         563
   NTFY_ORDER 50-SqueezeBoxServer
   PARTIAL
   PASSWORD   xxx
   PRESENCENAME presence.musicbox
   RCCNAME    none
   STATE      disconnected:on
   TIMEOUT    1
   TYPE       SB_SERVER
   USERNAME   xxx@xxx.de
   WOLNAME    pc.musicbox
   .clientArray:
     SB_PLAYER
   Readings:
     2016-06-29 08:24:51   db_albums       7066
     2016-06-29 08:24:51   db_artists      3112
     2016-06-29 08:24:51   db_genres       351
     2016-06-29 08:24:51   db_songs        45138
     2016-06-29 08:24:51   favoritesname
     2016-06-29 08:24:51   favoritestotal  3
     2016-06-29 08:24:51   players         2
     2016-06-29 08:24:51   players_mysb    0
     2016-06-29 08:24:51   players_other   0
     2016-07-03 15:44:34   power           on
     2016-06-29 08:24:51   scan_last       28-6-2016 11:53:5
     2016-06-29 08:24:51   scandb          ?
     2016-06-29 08:24:51   scanlastfailed  none
     2016-06-29 08:24:51   scanning        no
     2016-06-29 08:24:51   scanprogressdone 0
     2016-06-29 08:24:51   scanprogresstotal 0
     2016-06-29 08:24:51   serversecure    0
     2016-06-29 08:24:51   serverversion   7.7.2
     2016-07-03 15:42:34   state           disconnected
   Helper:
     SB_SERVER_VERSION 0014
     WOLFastReconnectNext 1467551729
     WOLFastReconnectUntil 1467551771
     pingCounter 0
Attributes:
   alivetimer 120
   doalivecheck true
   httpport   9001
   internalPingProtocol tcp
   maxcmdstack 200
   maxfavorites 30
   stateFormat state:power
   verbose    5

Was bedeutet denn dieses RCC off aus dem Log?

lg
aeronaut

[aeronaut]

Problem gelöst!

Scheinbar mag das Modul keine DNS-Namen sondern braucht eine IP-Adresse. Ansonsten wird probiert, mit localhost zu kommunizieren.

Eine Frage zu USER und PASSWORD in der Def habe ich: Wird der MySqueezebox-Account erwartet oder die Zugangsdaten, die man im LMS festlegt?

[FHEm2005]

Hallo Aeronaut,

damit sind die Zugangsdaten zum LMS gemeint. Der Account für mysqueezebox wird ja im LMS selbst hinterlegt.

Gruß Eberhard