SONOS: Neue Version vom 10.3.2018

Begonnen von Reinerlein, 10 März 2018, 17:30:38

Vorheriges Thema - Nächstes Thema

Reinerlein

Hallo zusammen,

da ich wieder mal ein paar Fehler beseitigen konnte, hier eine neue Version.

Folgendes ist enthalten:

  • Die PlayBase kann nun auch den SPDIF-Eingang aktivieren (wie die PlayBar)
  • Wenn man über Alexa Musik hört, wird das aktuelle Cover nun auch angezeigt.
  • Wenn ein Player nach einem Neustart disabled war, und während des Betriebs enabled wird, wird nun versucht ihn wiederzufinden.
  • Wenn ein Player disabled oder disappeared ist, wird ein Proxy-Cover-Zugriffsversuch auf diesen Player unterbunden.
  • Ein Modify-Befehlsaufruf wird nun am Vorhandensein von $hash->{OLDDEF} erkannt.
  • Bei einigen PERL-Installationen stand im Reading 'currentTrackPositionSimulatedSec' eine Kommazahl (da sie von time() aus berechnet wird). Diese Zahl wird nun gerundet.
Wie immer ab sofort im SVN oder ab Morgen per update...

Grüße
Reinerlein

trinitywhm

Hallo Reinerlein,

ich hatte auch das Problem dass der Player immer wieder auf disappeared ging. Daher hatte ich gehofft das Update schafft Abhilfe. Leider ist dem nicht so.

Wenn der Player dann aber mal verfügbar war, ging der Speak-Befehl nicht mehr. Ein verbose 5 beim SONOS-Device und am Player brachten lediglich diese beiden Logzeilen:
2018.03.11 09:36:14 5: SW: DoWork:undef:refreshProcessAnswer:

2018.03.11 09:36:17 5: SW: DoWork:RINCON_7828CA21913A01400_MR:speak0:30--#--de--#--test


Und nachdem ich den Speak-Befehl ausgeführt hatte (der nicht funktionierte) ging der Player kurz darauf auf disappeared. Keine Ahnung ob das zusammenhängt, aber das fiel mir in diesem Zusammenhang mehrmals auf.

Bis vor ca. zwei Tagen lief der Speak-Befehl zuverlässig.

fhem-challenge

Hallo Zusammen,


player disapered habe ich ebenfalls persistent seit dem Update des Sonos Modul. Ganz gleich was ich mache, es wird mit keinen "Trick" besser. Ich gehe mal auf die Version Anfang Februar zurück und sperre Songs.pm hinsichtlich Update.




Zitat von: trinitywhm am 11 März 2018, 09:43:09
Hallo Reinerlein,

ich hatte auch das Problem dass der Player immer wieder auf disappeared ging. Daher hatte ich gehofft das Update schafft Abhilfe. Leider ist dem nicht so.

Wenn der Player dann aber mal verfügbar war, ging der Speak-Befehl nicht mehr. Ein verbose 5 beim SONOS-Device und am Player brachten lediglich diese beiden Logzeilen:
2018.03.11 09:36:14 5: SW: DoWork:undef:refreshProcessAnswer:

2018.03.11 09:36:17 5: SW: DoWork:RINCON_7828CA21913A01400_MR:speak0:30--#--de--#--test


Und nachdem ich den Speak-Befehl ausgeführt hatte (der nicht funktionierte) ging der Player kurz darauf auf disappeared. Keine Ahnung ob das zusammenhängt, aber das fiel mir in diesem Zusammenhang mehrmals auf.

Bis vor ca. zwei Tagen lief der Speak-Befehl zuverlässig.

OdfFhem

Hallo,

zunächst einmal vielen Dank für das Weiterentwickeln dieses mächtigen Moduls.

1) Das Problem mit dem "Einfrieren" wegen Coverladens scheint weg zu sein  :).

2) Beim Start des SONOS-Device wird disabled berücksichtigt  :).
     Ein eingeschalteter SONOSPLAYER mit disabled-1-Attribut hat den Zustand "disappeared" und presence "appeared".
     Allerdings ist ein solcher SONOSPLAYER von FHEM aus voll bedienbar ... also nicht wirklich disabled  :-\.
     Ein ausgeschalteter SONOSPLAYER hat den Zustand "disappeared" und presence "disappeared".

3) Umsetzen auf disable-0-Attribut bei einem eingeschalteten SONOSPLAYER führt zum Zustand "STOPPED" und presence "appeared"  :).
     Schaltet man einen ausgeschalteten SONOSPLAYER ein und setzt das disabled-Attribut auf 0 um, führt dies nach einer kurzen "Aufwärmzeit" des Lautsprechers zum Zustand "STOPPED" und presence "appeared".
     Sowohl der bereits eingeschaltete als auch der neu eingeschaltete SONOSPLAYER sind von FHEM aus voll bedienbar.
     Die "disable 1"-"disable 0"-Methode am SONOS-Device scheint in diesem Szenario nicht mehr notwendig.

4) Umsetzen auf disable-1-Attribut hat scheinbar keine direkten Auswirkungen  :(.
    Sowohl der noch eingeschaltete als auch der schon ausgeschaltete SONOSPLAYER bleiben im Zustand "STOPPED" und presence "appeared".
    Auch ein RescanNetwork ändert nichts an diesem Zustand.
    Behoben wird dieser fehlerhafte Zustand im Moment nur mit der bewährten "disable 1"-"disable 0"-Methode am SONOS-Device.

5) Aufgefallen ist mir noch, dass zeitweise der Log von Discover-Ereignissen "geflutet" wird. Ob richtig oder falsch - keine Ahnung.

2018.03.11 17:13:12 2: SONOS1: Discover Sonosplayer 'Wohnzimmer' (S13) Software Revision 8.4 with ID 'RINCON_WWWWWWWWWWWW01400_MR'
2018.03.11 17:13:13 2: SONOS1: SonosPlayer 'Wohnzimmer' (S13) with ID 'RINCON_WWWWWWWWWWWW01400_MR' is already defined (as 'Sonos_Wohnzimmer') and will only be updated
2018.03.11 17:13:13 2: SONOS1: SonosPlayer 'Wohnzimmer' is now updated
2018.03.11 17:13:13 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000015
2018.03.11 17:13:13 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000016
2018.03.11 17:13:13 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000017
2018.03.11 17:13:13 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000018
2018.03.11 17:13:13 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000019
2018.03.11 17:13:13 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000020
2018.03.11 17:13:13 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000021
2018.03.11 17:13:13 2: SONOS1: MusicServices-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000022
2018.03.11 17:13:13 2: SONOS1: Discover Sonosplayer 'Wohnzimmer' (S13) Software Revision 8.4 with ID 'RINCON_WWWWWWWWWWWW01400_MR'
2018.03.11 17:13:13 2: SONOS1: SonosPlayer 'Wohnzimmer' (S13) with ID 'RINCON_WWWWWWWWWWWW01400_MR' is already defined (as 'Sonos_Wohnzimmer') and will only be updated
2018.03.11 17:13:13 2: SONOS1: SonosPlayer 'Wohnzimmer' is now updated
2018.03.11 17:13:13 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000023
2018.03.11 17:13:13 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000024
2018.03.11 17:13:13 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000025
2018.03.11 17:13:13 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000026
2018.03.11 17:13:13 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000027
2018.03.11 17:13:13 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000028
2018.03.11 17:13:13 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000029
2018.03.11 17:13:13 2: SONOS1: MusicServices-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000030
2018.03.11 17:13:13 2: SONOS1: Discover Sonosplayer 'Wohnzimmer' (S13) Software Revision 8.4 with ID 'RINCON_WWWWWWWWWWWW01400_MR'
2018.03.11 17:13:13 2: SONOS1: SonosPlayer 'Wohnzimmer' (S13) with ID 'RINCON_WWWWWWWWWWWW01400_MR' is already defined (as 'Sonos_Wohnzimmer') and will only be updated
2018.03.11 17:13:13 2: SONOS1: SonosPlayer 'Wohnzimmer' is now updated
2018.03.11 17:13:13 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000031
2018.03.11 17:13:13 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000032
2018.03.11 17:13:13 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000033
2018.03.11 17:13:13 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000034
2018.03.11 17:13:13 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000035
2018.03.11 17:13:13 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000036
2018.03.11 17:13:13 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000037
2018.03.11 17:13:13 2: SONOS1: MusicServices-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000038
2018.03.11 17:13:14 2: SONOS1: Discover Sonosplayer 'Wohnzimmer' (S13) Software Revision 8.4 with ID 'RINCON_WWWWWWWWWWWW01400_MR'
2018.03.11 17:13:14 2: SONOS1: SonosPlayer 'Wohnzimmer' (S13) with ID 'RINCON_WWWWWWWWWWWW01400_MR' is already defined (as 'Sonos_Wohnzimmer') and will only be updated
2018.03.11 17:13:14 2: SONOS1: SonosPlayer 'Wohnzimmer' is now updated
2018.03.11 17:13:14 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000042
2018.03.11 17:13:14 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000043
2018.03.11 17:13:14 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000044
2018.03.11 17:13:14 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000045
2018.03.11 17:13:14 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000046
2018.03.11 17:13:14 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000047
2018.03.11 17:13:14 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000048
2018.03.11 17:13:14 2: SONOS1: MusicServices-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000049
2018.03.11 17:13:14 2: SONOS1: Discover Sonosplayer 'Wohnzimmer' (S13) Software Revision 8.4 with ID 'RINCON_WWWWWWWWWWWW01400_MR'
2018.03.11 17:13:15 2: SONOS1: SonosPlayer 'Wohnzimmer' (S13) with ID 'RINCON_WWWWWWWWWWWW01400_MR' is already defined (as 'Sonos_Wohnzimmer') and will only be updated
2018.03.11 17:13:15 2: SONOS1: SonosPlayer 'Wohnzimmer' is now updated
2018.03.11 17:13:15 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000050
2018.03.11 17:13:15 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000051
2018.03.11 17:13:15 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000052
2018.03.11 17:13:15 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000053
2018.03.11 17:13:15 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000054
2018.03.11 17:13:15 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000055
2018.03.11 17:13:15 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000056
2018.03.11 17:13:15 2: SONOS1: MusicServices-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000057
2018.03.11 17:13:15 2: SONOS1: Discover Sonosplayer 'Wohnzimmer' (S13) Software Revision 8.4 with ID 'RINCON_WWWWWWWWWWWW01400_MR'
2018.03.11 17:13:15 2: SONOS1: SonosPlayer 'Wohnzimmer' (S13) with ID 'RINCON_WWWWWWWWWWWW01400_MR' is already defined (as 'Sonos_Wohnzimmer') and will only be updated
2018.03.11 17:13:15 2: SONOS1: SonosPlayer 'Wohnzimmer' is now updated
2018.03.11 17:13:15 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000058
2018.03.11 17:13:15 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000059
2018.03.11 17:13:15 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000060
2018.03.11 17:13:15 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000061
2018.03.11 17:13:15 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000062
2018.03.11 17:13:15 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000063
2018.03.11 17:13:15 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000064
2018.03.11 17:13:15 2: SONOS1: MusicServices-Service-subscribing successful with SID=uuid:RINCON_WWWWWWWWWWWW01400_sub0000000065


6) Noch eine Frage zur Unterstützung der Sonos One: Wird buttonEvents unterstützt? Wenn ja, wie?

Viele Grüße

Reinerlein

Hi,

@trinitywhm und fhem-challenge: Um da irgendeine Aussage treffen zu können, sind Logdateien notwendig.

@OdfFhem: Prinzipiell ist es nicht möglich einen angeschalteten Player von der Steuerung auszuschließen. Die Events der Player werden gesendet und empfangen, und müssen dann auch Ordnungegmäß verarbeitet werden (können). Sonst gibt es extrem viele Sonderlocken.
Das kannst du nur durch IP-Filterung erreichen, da dabei keinerlei Discover-Events signalisiert werden...

Es wird jetzt bei jedem Disable-Setzen an irgendeinem Sonosplayer-Device ein Rescannetwork (also eine Broadcast-Aufforderung an alle Player im Netz, sich zu melden) durchgeführt. Dabei werden Player, die sich nicht zurückmelden nicht entdeckt. Dafür ist der IsAlive-Mechanismus zuständig.
Ein normaler Sonos-Controller bekommt einen abgeschalteten Player auch erst mit, wenn versucht wird, diesen zu steuern.
Wenn das Sonos-Modul einen abwesenden Player entdeckt, teilt es das dem Sonos-Netz mit, sodass die Controller diesen Player ausblenden können. Selbsttätig kann Sonos das aber nicht.

Ich denke, es läuft so wie geplant :)

Grüße
Reinerlein

trinitywhm

@Reinerlein: Was für ein Log brauchst du?
Beim Speak-Befehl gibt es tatsächlich bei verbose 5 nur die beiden o.g. Zeilen im Log.

Soll ich noch was anderes loggen? An welchem Device? verbose?

Reinerlein

Hi,

das Modul loggt bei einem Player auf Level 5 bestimmt so 1000 Zeilen pro Titelwechsel.
Da sind 2 etwas wenig gegen :)
Da muss mehr kommen...

Verbose immer am Sonos-Device setzen...

Grüße
Reinerlein

trinitywhm

OK, verbose 5 am SONOS-Device, set Speak am SONOSPLAYER-Device Sonos-Wohnzimmer (verbose 3). Das Log ist jetzt so lange bis die SONOSPLAYER-Device wieder auf dissapeared stehen. Achja, und speak wird nicht ausgeführt...
2018.03.11 22:54:33 5: SONOS0: FhemWebCallback: /sonos/cover/tunein_round.png
2018.03.11 22:54:33 5: SONOS0: Cover: /tunein_round.png
2018.03.11 22:55:11 5: SW: DoWork:RINCON_7828CA21913A01400_MR:speak0:10--#--de--#--Test

2018.03.11 22:55:12 5: SONOS0: FhemWebCallback: /sonos/cover/tunein_round.png
2018.03.11 22:55:12 5: SONOS0: Cover: /tunein_round.png
2018.03.11 22:55:29 5: SONOS0: FhemWebCallback: /sonos/cover/tunein_round.png
2018.03.11 22:55:29 5: SONOS0: Cover: /tunein_round.png
2018.03.11 22:55:32 5: SONOS0: FhemWebCallback: /sonos/cover/tunein_round.png
2018.03.11 22:55:32 5: SONOS0: Cover: /tunein_round.png
2018.03.11 22:55:37 5: SONOS0: FhemWebCallback: /sonos/cover/tunein_round.png
2018.03.11 22:55:37 5: SONOS0: Cover: /tunein_round.png
2018.03.11 22:55:53 5: SW: DoWork:undef:refreshProcessAnswer:

2018.03.11 22:56:53 5: SW: DoWork:undef:refreshProcessAnswer:

2018.03.11 22:57:38 5: SONOS0: FhemWebCallback: /sonos/cover/tunein_round.png
2018.03.11 22:57:38 5: SONOS0: Cover: /tunein_round.png
2018.03.11 22:57:53 5: SW: DoWork:undef:refreshProcessAnswer:

2018.03.11 22:58:53 5: SW: DoWork:undef:refreshProcessAnswer:

2018.03.11 22:58:53 2: SONOS0: LastProcessAnswer way too old (Lastanswer: 1520805233 ~ 2018-03-11 22:53:53)... try to restart the process and connection...
2018.03.11 22:58:53 4: SONOS0: ReadingsBeginUpdate from Module for "Sonos"
2018.03.11 22:58:53 4: SONOS0: ReadingsEndUpdate from Module for "Sonos"
2018.03.11 22:58:54 5: SW: disconnect

2018.03.11 22:58:54 4: SONOS0: ReadingsBeginUpdate from Module for "Sonos_Esszimmer"
2018.03.11 22:58:54 4: SONOS0: ReadingsEndUpdate from Module for "Sonos_Esszimmer"
2018.03.11 22:58:54 4: SONOS0: ReadingsBeginUpdate from Module for "Sonos_Wohnzimmer"
2018.03.11 22:58:54 4: SONOS0: ReadingsEndUpdate from Module for "Sonos_Wohnzimmer"


Mehr spricht das Log an der Stelle nicht. Soll ich noch etwas verändert? Brauchst du list vom SONOS-Device oder SONOSPLAYER?

Reinerlein

Hi trinitywhm,

da fehlt der gesamte Teil, den der SubProzess loggt. Das ist nur der Teil des Fhem-Moduls...
Immerhin sieht man, dass er versucht, den SubProzess zu einer Antwort zu bewegen... nur antwortet der nicht, und der Grund steht vielleicht in dem anderen Teil...

Irgendwie scheint der STDOUT-Teil nicht in deinem FHEM-Log zu landen.
Dann setz mal das Attribut "SubProcessLogfileName" am Sonos-Device, und hänge diese Datei dann hier an...

Grüße
Reinerlein

trinitywhm

Log hängt an. Der speak-Teil ist gegen 23:26 Uhr. Habe das log aber weiter laufen lassen bis die Player wieder disappeared waren.

Reinerlein

Hi trinitywhm,

bei dir gibt es irgendwie Thread-Reste.
Kannst du mal Fhem beenden, und dann auf deiner Konsole (also per ssh anmelden) sicherstellen, daß auch alles gestorben ist?

Mit

ps aux | grep perl
findest du noch übrige Perl-Prozesse.

Mit

sudo kill -9 ID
tötest du noch übrige SONOS-Threads (ID durch die vorher gefundene Prozess-Id, die erste Spalte, ersetzen). Für jeden noch vorhandenen einmal...

Grüße
Reinerlein

trinitywhm

Es tut mir leid, aber es gab keine SONOS-Threads die zu killen waren.  :-\

Reinerlein

Hi trinitywhm,

und wie sah das Log des SubProzesses nach dem Start aus?
Du warst in dem angehangenen Log bereits bei Thread 125 als UPnP-Thread (normalerweise bekommt der die 1), und hattest zwei IsAlive-Threads (91 und 127)...

Das sollte jetzt schon mal deutlich anders aussehen...

Grüße
Reinerlein

trinitywhm

OK, ich habe vorhin nach dem stoppen von fhem und suchen nach dem Prozess ein reboot vom raspberry gemacht. Und siehe da, jetzt geht auch speak wieder, und auch nach einer Weile warten, stehen die Player noch immer auf appeared. Soll es das wirklich gewesen sein? Ich habe das die letzten Tage durchaus öfter gemacht (shutdown restart, reboot, ...) Aber bisher hatte es nie diesen Effekt.

Ich bin dir zunächst mal dankbar das du mir hier weitergeholfen hast, werde das aber die nächsten Tage im Auge behalten.

Danke dir und gute Nacht.

Reinerlein

Hi trinitywhm,

der Prozess war der Meinung, dass noch eine Durchsage läuft, und wartet dann auf die Beendigung dieser Durchsage... leider endlos... da muss ich mal schauen, was ich da machen kann...

Das erklärt aber überhaupt nicht, warum überhaupt eine Beendigung des SubProzesses erfolgt ist, das ist ja bei dir durchaus häufiger passiert...
Das Log zu dem ersten Auftreten eines solchen Abbruchs ist das Interessante :)

Grüße
Reinerlein