Verbindung zwischen Fhem und Sonos bricht ab

Nobby1805 · 01 Februar 2017, 10:25:21

Hallo Reiner,

danke für die Erläuterungen ... und dass du die Frage gestellt hast, die ich auch hatte: warum wird nach einer Störung nicht versucht, die Verbindung wieder zu erstellen?

Ich warte geduldig auf deine Umbauten

Gruß Nobby

Nobby1805 · 06 Februar 2017, 00:03:13

Hallo Reiner,

es ist gerade wieder genau an der gleichen Stelle passiert ... ich werde da morgen mal einbauen, dass nach ein paar Sekunden Wartezeit es einfach noch einmal probiert wird

Gruß Nobby

Reinerlein · 06 Februar 2017, 10:00:57

Hi Nobby,

das ist vielleicht zum Testen eine Lösung, allerdings wird dann der SubProzess solange blockiert, und kann keinerlei Mitteilungen der Player empfangen, oder Befehle von Fhem senden...

Ich baue da gerade ein, dass zunächst mal der Zustand der Player auf der Oberfläche korrekt ist (disappeared), und dann der Erkennungsvorgang neu angestossen wird (rescanNetwork).
Du könntest mal bei jeder der Renew-Fehlerprüfungen folgendes ersetzen/einbauen:

Code Auswählen


if ($@ =~ m/Can.t connect to/) {
	SONOS_DeleteProxyObjects($udn);
	
	# Player-Informationen aktualisieren...
	SONOS_Client_Data_Refresh('ReadingsSingleUpdateIfChanged', $udn, 'presence', 'disappeared');
	SONOS_Client_Data_Refresh('ReadingsSingleUpdateIfChanged', $udn, 'state', 'disappeared');
	
	# Discovery neu anstarten, falls der Player irgendwie doch noch erreichbar sein sollte...
	$SONOS_Search = $SONOS_Controlpoint->searchByType('urn:schemas-upnp-org:device:ZonePlayer:1', \&SONOS_Discover_Callback);
}

Falls er sofort wieder erreichbar sein sollte, dann ist der Zustand des disappeared nicht lange auf der Oberfläche, ansonsten wird spätestens bei der nächsten Aktualisierungsmeldung durch den Player (der hat seine Subscription noch einen Moment) der Player neu erkannt (dazu ist der Zustand disappeared notwendig)...

Grüße
Reiner

Nobby1805 · 06 Februar 2017, 19:13:32

Hallo Reiner,

ist mir schon klar ... aber ich will erst mal (nur) sehen, ob meine Vermutung stimmt das der Connect schon beim nächsten Versuch nach wenigen Sekunden klappt ... ich habe da einen Verdacht (der außerhalb von FHEM liegt) und der wird dann ggf. bestätigt

Gruß Nobby

Edit 7.2.: Es ist wieder passiert ... und nach 2 Sekunden Wartezeit hat der erneute Versuch dann funktioniert. Mein Verdacht, dass der Auslöser eine andere Anwendung auf dem Server ist hast sich allerdings (bisher) nicht betätigt.

Gruß Nobby

Nobby1805 · 08 Februar 2017, 23:20:50

Es ist wieder passiert ...und mein Workaround hat wieder funktioniert.

Interessanterweise war der Connect nach dem 2 Sekunden warten nicht sofort erfolgreich sondern hat 9 Sekunden gedauert ... irgendetwas, m.M.n. außerhalb von FHEM, stört da den Verbindungsversuch.

Gruß Nobby

Xguide · 09 Februar 2017, 07:56:40

Hallo zusammen,

ich lese hier schon eine Weile, da mich ähnliche Sorgen geplagt haben. Ich betreibe fhem auf einem Raspberry Pi 3 unter Jessie. Nachdem ich Ende letzten Jahres zur bestehenden Play1, die über lange Zeit problemlos funktionierte und immer von fhem ansprechbar war, zwei weitere Play1 für einen weiteren Raum hinzugenommen habe fingen meine Probleme an. Die 3 Play1 sind alle über WiFi verbunden.
Nach einem fhem Neustart funktionierte die ganze Landschaft wieder, allerdings nur für wenige Stunden. Mein fhem ist immer sehr aktuell gehalten (max 1 Woche alt).

Mein letzter Versuch das System wieder zuverlässig ans Laufen zu bringen war:
1. update & upgrade aller Pakete auf dem Pi
2. die Sonos Landschaft komplett aus fhem entfernt und neu definiert

Seit 2 Tagen läuft es nun ohne Abbrüche, ich hoffe das bleibt auch so.
Zu 1. kann ich leider nicht genau sagen ob irgendwelche Abhängigkeiten aktualisiert wurden.

Vielleicht hilft es ja auch dem ein oder anderen.

Viel Erfolg,

Marcel

Reinerlein · 09 Februar 2017, 08:58:45

Hallo Nobby,

ich fürchte, da wird man nur etwas mittels detaillierter Paketuntersuchung feststellen können, und das ist doch sehr aufwändig.
Ich denke, wir sollten hier den pragmatischen Weg weiterverfolgen

Ich überlege mir mal was, ob ich z.B. bei einer IsAlive-Prüfung nicht ab und zu eine Broadcast-Message einbringen kann, die die Player dazu bewegt, sich wieder zu melden.
Das löst natürlich nicht dein ursächliches Problem des verschwindenden Players, aber das Problem ist dann wenigstens selbst-korrigierend, da der Player wieder auftauchen wird, wenn das Netzproblem weg ist...

Grüße
Reiner

Nobby1805 · 09 Februar 2017, 11:26:01

Hallo Reiner,

pragmatisch ist immer gut ...

Ich habe jetzt noch andere Fehlermeldungen gefunden ... als diese vor ein paar Tagen erstmals auftraten (bzw. sie mir aufgefallen sind) dachte ich noch, sie hätten etwas mit meinen dauernden Restarts für den Einbau neuen Loggings zu tun... aber jetzt sind sie diese Nacht, als alles schlief, wieder gekommen ... irgendwelche Auswirkungen habe ich bisher nicht feststellen können

Code Auswählen

265 2017.02.09 01:17:11.613 2: SONOS1: Error during UPnP-Handling, restarting handling: Bad arg length for Socket::unpack_sockaddr_in, length is 0, should be 16 at FHEM/lib/UPnP/ControlPoint.pm line 434, <$client> line 6.

Code Auswählen

480 2017.02.09 03:08:10.238 2: SONOS1: Error during UPnP-Handling, restarting handling: Bad arg length for Socket::unpack_sockaddr_in, length is 0, should be 16 at FHEM/lib/UPnP/ControlPoint.pm line 434, <$client> line 6.

Code Auswählen

787 2017.02.09 06:20:56.357 2: SONOS1: Error during UPnP-Handling, restarting handling: Bad arg length for Socket::unpack_sockaddr_in, length is 0, should be 16 at FHEM/lib/UPnP/ControlPoint.pm line 434, <$client> line 6.

Ich habe mal ein bisschen im Code gesucht und vermute das kommt von hier

Code Auswählen

	SONOS_LoadBookmarkValues();
	
	my $error;
	do {
		$SONOS_RestartControlPoint = 0;
		
		eval {
			$SONOS_Controlpoint = UPnP::ControlPoint->new(SearchPort => 0, SubscriptionPort => 0, SubscriptionURL => '/fhemmodule', MaxWait => 30, UsedOnlyIP => \%usedonlyIPs, IgnoreIP => \%ignoredIPs);
			$SONOS_Search = $SONOS_Controlpoint->searchByType('urn:schemas-upnp-org:device:ZonePlayer:1', \&SONOS_Discover_Callback);
			$SONOS_Controlpoint->handle;
		};
		$error = $@;
		
		# Nur wenn es der Fehler mit der XML-Struktur ist, dann den UPnP-Handler nochmal anstarten...  
		if (($error =~ m/multiple roots, wrong element '.*?'/si) || ($error =~ m/junk '.*?' after XML element/si) || ($error =~ m/mismatched tag '.*?'/si) || ($error =~ m/no element found/si) || ($error =~ m/500 Can't connect to/si) || ($error =~ m/not properly closed tag '.*?'/si) || ($error =~ m/Bad arg length for Socket::unpack_sockaddr_in/si)) {
			SONOS_Log undef, 2, "Error during UPnP-Handling, restarting handling: $error";
			SONOS_StopControlPoint();
		} else {
			SONOS_Log undef, 2, "Error during UPnP-Handling: $error";
			SONOS_StopControlPoint();
			
			undef($error);
		}
	} while ($error || $SONOS_RestartControlPoint);

Fehler in der XML-Struktur??? Was passiert da zu nachtschlafender Zeit? Was ist der Auslöser? Siehst du einen Zusammenhang mit dem anderen Problem?

Gruß Nobby

Reinerlein · 09 Februar 2017, 11:58:28

Hi Nobby,

das klingt komisch. Da scheint eine SSDP-Antwort (also eine Antwort, dass ein Player sich ab-/anmeldet) nicht vollständig korrekt anzukommen.
Das eigentliche Problem tritt ja in der ControlPoint-UPnP-Library auf, weil dort versucht wird, die IP-Adresse des Absenders herauszufinden. Mir ist nicht ganz klar, wieso das mal fehlschlagen kann...
Ich kann das aber mal anders behandeln, um nicht solche Wellen bei diesem Fehler zu erhalten, bin mir aber nicht sicher, ob das eine so gute Idee ist... Ich denke mal dran rum...

Grüße
Reiner

Nobby1805 · 09 Februar 2017, 12:40:26

Dann poste ich hier mal das Umfeld der Fehlermeldungen ... ist nicht ganz identisch

Code Auswählen

     265 2017.02.09 01:17:11.613 2: SONOS1: Error during UPnP-Handling, restarting handling: Bad arg length for Socket::unpack_sockaddr_in, length is 0, should be 16 at FHEM/lib/UPnP/ControlPoint.pm line 434, <$client> line 6.
     266 2017.02.09 01:17:12.363 2: SONOS1: Discover Sonosplayer 'BRIDGE' (ZB100) Software Revision 7.1 with ID 'RINCON_000E58E3988A01400_MR'
     267 2017.02.09 01:17:14.223 2: SONOS1: SonosPlayer 'BRIDGE' (ZB100) with ID 'RINCON_000E58E3988A01400_MR' is already defined (as 'Sonos_BRIDGE') and will only be updated
     268 2017.02.09 01:17:14.225 2: SONOS1: SonosPlayer 'BRIDGE' is now updated
     269 2017.02.09 01:17:14.244 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_000E58E3988A01400_sub0000001609
     270 2017.02.09 01:17:14.351 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_000E58E3988A01400_sub0000001610
     271 2017.02.09 01:17:14.352 3: SONOS1: Discover: End of discover-event for "BRIDGE".
     272 2017.02.09 01:17:14.457 2: SONOS1: Discover Sonosplayer 'Wohnzimmer' (ZP90) Software Revision 7.1 with ID 'RINCON_000E58A376D201400_MR'
     273 2017.02.09 01:17:15.410 2: SONOS1: SonosPlayer 'Wohnzimmer' (ZP90) with ID 'RINCON_000E58A376D201400_MR' is already defined (as 'Sonos_Wohnzimmer') and will only be updated
     274 2017.02.09 01:17:15.611 2: SONOS1: SonosPlayer 'Wohnzimmer' is now updated
     275 2017.02.09 01:17:15.634 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000614
     276 2017.02.09 01:17:15.647 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000615
     277 2017.02.09 01:17:15.664 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000616
     278 2017.02.09 01:17:15.676 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000617
     279 2017.02.09 01:17:15.689 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000618
     280 2017.02.09 01:17:15.703 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000619
     281 2017.02.09 01:17:15.715 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000620
     282 2017.02.09 01:17:15.729 2: SONOS1: AudioIn-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000621
     283 2017.02.09 01:17:15.729 3: SONOS1: Discover: End of discover-event for "Wohnzimmer".
     284 2017.02.09 01:17:15.803 2: SONOS1: Discover Sonosplayer 'Schlafzimmer' (S1) Software Revision 7.1 with ID 'RINCON_000E58C1DE0A01400_MR'
     285 2017.02.09 01:17:16.817 2: SONOS1: SonosPlayer 'Schlafzimmer' (S1) with ID 'RINCON_000E58C1DE0A01400_MR' is already defined (as 'Sonos_Schlafzimmer') and will only be updated
     286 2017.02.09 01:17:16.978 2: SONOS1: SonosPlayer 'Schlafzimmer' is now updated
     287 2017.02.09 01:17:17.001 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000775
     288 2017.02.09 01:17:17.017 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000776
     289 2017.02.09 01:17:17.029 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000777
     290 2017.02.09 01:17:17.042 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000778
     291 2017.02.09 01:17:17.056 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000779
     292 2017.02.09 01:17:17.084 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000780
     293 2017.02.09 01:17:17.099 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000781
     294 2017.02.09 01:17:17.099 3: SONOS1: Discover: End of discover-event for "Schlafzimmer".
     295 2017.02.09 01:17:17.104 3: SONOS1: Event: Received ZoneGroupTopology-Event for Zone "Sonos_BRIDGE".
     296 2017.02.09 01:17:17.111 3: SONOS1: Event: End of ZoneGroupTopology-Event for Zone "Sonos_BRIDGE".
     297 2017.02.09 01:17:17.116 3: SONOS1: Event: Received Transport-Event for Zone "Sonos_Wohnzimmer".
     298 2017.02.09 01:17:17.248 3: SONOS1: Event: End of Transport-Event for Zone "Sonos_Wohnzimmer".
     299 2017.02.09 01:17:17.253 3: SONOS1: Event: Received Transport-Event for Zone "Sonos_Schlafzimmer".
     300 2017.02.09 01:17:17.461 3: SONOS1: Event: End of Transport-Event for Zone "Sonos_Schlafzimmer".
     301 2017.02.09 01:17:17.470 3: SONOS1: Event: Received DeviceProperties-Event for Zone "Sonos_BRIDGE".
     302 2017.02.09 01:17:17.473 3: SONOS1: Event: End of DeviceProperties-Event for Zone "Sonos_BRIDGE".
     303 2017.02.09 01:17:17.479 3: SONOS1: Event: Received GroupRendering-Event for Zone "Sonos_Wohnzimmer".
     304 2017.02.09 01:17:17.479 3: SONOS1: Event: End of GroupRendering-Event for Zone "Sonos_Wohnzimmer".
     305 2017.02.09 01:17:17.488 3: SONOS1: Event: Received GroupRendering-Event for Zone "Sonos_Schlafzimmer".
     306 2017.02.09 01:17:17.490 3: SONOS1: Event: End of GroupRendering-Event for Zone "Sonos_Schlafzimmer".
     307 2017.02.09 01:17:17.504 3: SONOS1: Event: Received Alarm-Event for Zone "Sonos_Wohnzimmer".
     308 2017.02.09 01:17:17.505 3: SONOS1: Event: End of Alarm-Event for Zone "Sonos_Wohnzimmer".
     309 2017.02.09 01:17:17.513 3: SONOS1: Event: Received Alarm-Event for Zone "Sonos_Schlafzimmer".
     310 2017.02.09 01:17:17.514 3: SONOS1: Event: End of Alarm-Event for Zone "Sonos_Schlafzimmer".
     311 2017.02.09 01:17:17.523 3: SONOS1: Event: Received AudioIn-Event for Zone "Sonos_Wohnzimmer".
     312 2017.02.09 01:17:17.524 3: SONOS1: Event: End of AudioIn-Event for Zone "Sonos_Wohnzimmer".
     313 2017.02.09 01:17:17.534 3: SONOS1: Event: Received ZoneGroupTopology-Event for Zone "Sonos_Schlafzimmer".
     314 2017.02.09 01:17:17.541 3: SONOS1: Event: End of ZoneGroupTopology-Event for Zone "Sonos_Schlafzimmer".
     315 2017.02.09 01:17:17.548 3: SONOS1: Event: Received ZoneGroupTopology-Event for Zone "Sonos_Wohnzimmer".
     316 2017.02.09 01:17:17.557 3: SONOS1: Event: End of ZoneGroupTopology-Event for Zone "Sonos_Wohnzimmer".
     317 2017.02.09 01:17:17.570 3: SONOS1: Event: Received DeviceProperties-Event for Zone "Sonos_Schlafzimmer".
     318 2017.02.09 01:17:17.572 3: SONOS1: Event: End of DeviceProperties-Event for Zone "Sonos_Schlafzimmer".
     319 2017.02.09 01:17:17.585 3: SONOS1: Event: Received DeviceProperties-Event for Zone "Sonos_Wohnzimmer".
     320 2017.02.09 01:17:17.587 3: SONOS1: Event: End of DeviceProperties-Event for Zone "Sonos_Wohnzimmer".
     321 2017.02.09 01:17:17.597 3: SONOS1: Event: Received ContentDirectory-Event for Zone "Sonos_Schlafzimmer".
     322 2017.02.09 01:17:17.599 3: SONOS1: Event: End of ContentDirectory-Event for Zone "Sonos_Schlafzimmer".
     323 2017.02.09 01:17:17.608 3: SONOS1: Event: Received ContentDirectory-Event for Zone "Sonos_Wohnzimmer".
     324 2017.02.09 01:17:17.609 3: SONOS1: Event: End of ContentDirectory-Event for Zone "Sonos_Wohnzimmer".
     325 2017.02.09 01:17:17.618 3: SONOS1: Event: Received Rendering-Event for Zone "Sonos_Schlafzimmer".
     326 2017.02.09 01:17:17.632 3: SONOS1: Event: End of Rendering-Event for Zone "Sonos_Schlafzimmer".
     327 2017.02.09 01:17:17.639 3: SONOS1: Event: Received Rendering-Event for Zone "Sonos_Wohnzimmer".
     328 2017.02.09 01:17:17.653 3: SONOS1: Event: End of Rendering-Event for Zone "Sonos_Wohnzimmer".

Code Auswählen

     480 2017.02.09 03:08:10.238 2: SONOS1: Error during UPnP-Handling, restarting handling: Bad arg length for Socket::unpack_sockaddr_in, length is 0, should be 16 at FHEM/lib/UPnP/ControlPoint.pm line 434, <$client> line 6.
     481 2017.02.09 03:08:13.645 2: SONOS1: Discover Sonosplayer 'Schlafzimmer' (S1) Software Revision 7.1 with ID 'RINCON_000E58C1DE0A01400_MR'
     482 2017.02.09 03:08:16.613 2: SONOS1: SonosPlayer 'Schlafzimmer' (S1) with ID 'RINCON_000E58C1DE0A01400_MR' is already defined (as 'Sonos_Schlafzimmer') and will only be updated
     483 2017.02.09 03:08:17.160 2: SONOS1: SonosPlayer 'Schlafzimmer' is now updated
     484 2017.02.09 03:08:17.192 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000796
     485 2017.02.09 03:08:17.255 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000797
     486 2017.02.09 03:08:17.283 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000798
     487 2017.02.09 03:08:17.318 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000799
     488 2017.02.09 03:08:17.353 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000800
     489 2017.02.09 03:08:17.373 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000801
     490 2017.02.09 03:08:17.388 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000802
     491 2017.02.09 03:08:17.389 3: SONOS1: Discover: End of discover-event for "Schlafzimmer".
     492 2017.02.09 03:08:17.442 2: SONOS1: Discover Sonosplayer 'BRIDGE' (ZB100) Software Revision 7.1 with ID 'RINCON_000E58E3988A01400_MR'
     493 2017.02.09 03:08:17.723 2: SONOS1: SonosPlayer 'BRIDGE' (ZB100) with ID 'RINCON_000E58E3988A01400_MR' is already defined (as 'Sonos_BRIDGE') and will only be updated
     494 2017.02.09 03:08:17.734 2: SONOS1: SonosPlayer 'BRIDGE' is now updated
     495 2017.02.09 03:08:17.750 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_000E58E3988A01400_sub0000001611
     496 2017.02.09 03:08:17.766 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_000E58E3988A01400_sub0000001612
     497 2017.02.09 03:08:17.766 3: SONOS1: Discover: End of discover-event for "BRIDGE".
     498 2017.02.09 03:08:17.793 3: SONOS1: Event: Received Transport-Event for Zone "Sonos_Schlafzimmer".
     499 2017.02.09 03:08:18.140 3: SONOS1: Event: End of Transport-Event for Zone "Sonos_Schlafzimmer".
     500 2017.02.09 03:08:18.223 2: SONOS1: Discover Sonosplayer 'Wohnzimmer' (ZP90) Software Revision 7.1 with ID 'RINCON_000E58A376D201400_MR'
     501 2017.02.09 03:08:19.317 2: SONOS1: SonosPlayer 'Wohnzimmer' (ZP90) with ID 'RINCON_000E58A376D201400_MR' is already defined (as 'Sonos_Wohnzimmer') and will only be updated
     502 2017.02.09 03:08:19.539 2: SONOS1: SonosPlayer 'Wohnzimmer' is now updated
     503 2017.02.09 03:08:19.561 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000638
     504 2017.02.09 03:08:19.575 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000639
     505 2017.02.09 03:08:19.588 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000640
     506 2017.02.09 03:08:19.600 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000641
     507 2017.02.09 03:08:19.616 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000642
     508 2017.02.09 03:08:19.630 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000643
     509 2017.02.09 03:08:19.643 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000644
     510 2017.02.09 03:08:19.656 2: SONOS1: AudioIn-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000645
     511 2017.02.09 03:08:19.656 3: SONOS1: Discover: End of discover-event for "Wohnzimmer".
     512 2017.02.09 03:08:19.669 3: SONOS1: Event: Received ZoneGroupTopology-Event for Zone "Sonos_BRIDGE".
     513 2017.02.09 03:08:19.696 3: SONOS1: Event: End of ZoneGroupTopology-Event for Zone "Sonos_BRIDGE".
     514 2017.02.09 03:08:19.713 3: SONOS1: Event: Received GroupRendering-Event for Zone "Sonos_Schlafzimmer".
     515 2017.02.09 03:08:19.726 3: SONOS1: Event: End of GroupRendering-Event for Zone "Sonos_Schlafzimmer".
     516 2017.02.09 03:08:19.742 3: SONOS1: Event: Received Transport-Event for Zone "Sonos_Wohnzimmer".
     517 2017.02.09 03:08:19.886 3: SONOS1: Event: End of Transport-Event for Zone "Sonos_Wohnzimmer".
     518 2017.02.09 03:08:19.900 3: SONOS1: Event: Received DeviceProperties-Event for Zone "Sonos_BRIDGE".
     519 2017.02.09 03:08:19.910 3: SONOS1: Event: End of DeviceProperties-Event for Zone "Sonos_BRIDGE".
     520 2017.02.09 03:08:19.943 3: SONOS1: Event: Received Alarm-Event for Zone "Sonos_Schlafzimmer".
     521 2017.02.09 03:08:19.944 3: SONOS1: Event: End of Alarm-Event for Zone "Sonos_Schlafzimmer".
     522 2017.02.09 03:08:19.950 3: SONOS1: Event: Received GroupRendering-Event for Zone "Sonos_Wohnzimmer".
     523 2017.02.09 03:08:19.950 3: SONOS1: Event: End of GroupRendering-Event for Zone "Sonos_Wohnzimmer".
     524 2017.02.09 03:08:19.957 3: SONOS1: Event: Received ZoneGroupTopology-Event for Zone "Sonos_Schlafzimmer".
     525 2017.02.09 03:08:19.969 3: SONOS1: Event: End of ZoneGroupTopology-Event for Zone "Sonos_Schlafzimmer".
     526 2017.02.09 03:08:19.975 3: SONOS1: Event: Received Alarm-Event for Zone "Sonos_Wohnzimmer".
     527 2017.02.09 03:08:19.976 3: SONOS1: Event: End of Alarm-Event for Zone "Sonos_Wohnzimmer".
     528 2017.02.09 03:08:19.989 3: SONOS1: Event: Received DeviceProperties-Event for Zone "Sonos_Schlafzimmer".
     529 2017.02.09 03:08:20.002 3: SONOS1: Event: End of DeviceProperties-Event for Zone "Sonos_Schlafzimmer".
     530 2017.02.09 03:08:20.010 3: SONOS1: Event: Received AudioIn-Event for Zone "Sonos_Wohnzimmer".
     531 2017.02.09 03:08:20.011 3: SONOS1: Event: End of AudioIn-Event for Zone "Sonos_Wohnzimmer".
     532 2017.02.09 03:08:20.019 3: SONOS1: Event: Received ContentDirectory-Event for Zone "Sonos_Schlafzimmer".
     533 2017.02.09 03:08:20.023 3: SONOS1: Event: End of ContentDirectory-Event for Zone "Sonos_Schlafzimmer".
     534 2017.02.09 03:08:20.053 3: SONOS1: Event: Received Rendering-Event for Zone "Sonos_Schlafzimmer".
     535 2017.02.09 03:08:20.122 3: SONOS1: Event: End of Rendering-Event for Zone "Sonos_Schlafzimmer".
     536 2017.02.09 03:08:22.442 3: SONOS1: Event: Received ZoneGroupTopology-Event for Zone "Sonos_Wohnzimmer".
     537 2017.02.09 03:08:22.469 3: SONOS1: Event: End of ZoneGroupTopology-Event for Zone "Sonos_Wohnzimmer".
     538 2017.02.09 03:08:22.489 3: SONOS1: Event: Received DeviceProperties-Event for Zone "Sonos_Wohnzimmer".
     539 2017.02.09 03:08:22.492 3: SONOS1: Event: End of DeviceProperties-Event for Zone "Sonos_Wohnzimmer".
     540 2017.02.09 03:08:22.506 3: SONOS1: Event: Received ContentDirectory-Event for Zone "Sonos_Wohnzimmer".
     541 2017.02.09 03:08:22.507 3: SONOS1: Event: End of ContentDirectory-Event for Zone "Sonos_Wohnzimmer".
     542 2017.02.09 03:08:22.522 3: SONOS1: Event: Received Rendering-Event for Zone "Sonos_Wohnzimmer".
     543 2017.02.09 03:08:22.532 3: SONOS1: Event: End of Rendering-Event for Zone "Sonos_Wohnzimmer".

Code Auswählen

    787 2017.02.09 06:20:56.357 2: SONOS1: Error during UPnP-Handling, restarting handling: Bad arg length for Socket::unpack_sockaddr_in, length is 0, should be 16 at FHEM/lib/UPnP/ControlPoint.pm line 434, <$client> line 6.

     788 2017.02.09 06:20:57.935 2: SONOS1: Discover Sonosplayer 'Schlafzimmer' (S1) Software Revision 7.1 with ID 'RINCON_000E58C1DE0A01400_MR'
     789 2017.02.09 06:20:59.467 2: SONOS1: SonosPlayer 'Schlafzimmer' (S1) with ID 'RINCON_000E58C1DE0A01400_MR' is already defined (as 'Sonos_Schlafzimmer') and will only be updated
     790 2017.02.09 06:20:59.732 2: SONOS1: SonosPlayer 'Schlafzimmer' is now updated
     791 2017.02.09 06:20:59.759 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000817
     792 2017.02.09 06:20:59.787 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000818
     793 2017.02.09 06:20:59.805 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000819
     794 2017.02.09 06:20:59.820 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000820
     795 2017.02.09 06:20:59.834 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000821
     796 2017.02.09 06:20:59.850 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000822
     797 2017.02.09 06:20:59.868 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_000E58C1DE0A01400_sub0000000823
     798 2017.02.09 06:20:59.868 3: SONOS1: Discover: End of discover-event for "Schlafzimmer".
     799 2017.02.09 06:21:00.013 2: SONOS1: Discover Sonosplayer 'Wohnzimmer' (ZP90) Software Revision 7.1 with ID 'RINCON_000E58A376D201400_MR'
     800 2017.02.09 06:21:02.013 2: SONOS1: SonosPlayer 'Wohnzimmer' (ZP90) with ID 'RINCON_000E58A376D201400_MR' is already defined (as 'Sonos_Wohnzimmer') and will only be updated
     801 2017.02.09 06:21:02.592 2: SONOS1: SonosPlayer 'Wohnzimmer' is now updated
     802 2017.02.09 06:21:02.687 2: SONOS1: Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000662
     803 2017.02.09 06:21:02.737 2: SONOS1: Rendering-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000663
     804 2017.02.09 06:21:02.755 2: SONOS1: GroupRendering-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000664
     805 2017.02.09 06:21:02.785 2: SONOS1: ContentDirectory-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000665
     806 2017.02.09 06:21:02.816 2: SONOS1: Alarm-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000666
     807 2017.02.09 06:21:02.839 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000667
     808 2017.02.09 06:21:02.871 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000668
     809 2017.02.09 06:21:02.892 2: SONOS1: AudioIn-Service-subscribing successful with SID=uuid:RINCON_000E58A376D201400_sub0000000669
     810 2017.02.09 06:21:02.892 3: SONOS1: Discover: End of discover-event for "Wohnzimmer".
     811 2017.02.09 06:21:02.900 3: SONOS1: Event: Received Transport-Event for Zone "Sonos_Schlafzimmer".
     812 2017.02.09 06:21:03.240 3: SONOS1: Event: End of Transport-Event for Zone "Sonos_Schlafzimmer".
     813 2017.02.09 06:21:03.310 2: SONOS1: Discover Sonosplayer 'BRIDGE' (ZB100) Software Revision 7.1 with ID 'RINCON_000E58E3988A01400_MR'
     814 2017.02.09 06:21:03.601 2: SONOS1: SonosPlayer 'BRIDGE' (ZB100) with ID 'RINCON_000E58E3988A01400_MR' is already defined (as 'Sonos_BRIDGE') and will only be updated
     815 2017.02.09 06:21:03.605 2: SONOS1: SonosPlayer 'BRIDGE' is now updated
     816 2017.02.09 06:21:03.635 2: SONOS1: ZoneGroupTopology-Service-subscribing successful with SID=uuid:RINCON_000E58E3988A01400_sub0000001613
     817 2017.02.09 06:21:03.653 2: SONOS1: DeviceProperties-Service-subscribing successful with SID=uuid:RINCON_000E58E3988A01400_sub0000001614
     818 2017.02.09 06:21:03.654 3: SONOS1: Discover: End of discover-event for "BRIDGE".
     819 2017.02.09 06:21:03.662 3: SONOS1: Event: Received Transport-Event for Zone "Sonos_Wohnzimmer".
     820 2017.02.09 06:21:03.895 3: SONOS1: Event: End of Transport-Event for Zone "Sonos_Wohnzimmer".
     821 2017.02.09 06:21:03.905 3: SONOS1: Event: Received GroupRendering-Event for Zone "Sonos_Schlafzimmer".
     822 2017.02.09 06:21:03.906 3: SONOS1: Event: End of GroupRendering-Event for Zone "Sonos_Schlafzimmer".
     823 2017.02.09 06:21:03.919 3: SONOS1: Event: Received ZoneGroupTopology-Event for Zone "Sonos_BRIDGE".
     824 2017.02.09 06:21:03.929 3: SONOS1: Event: End of ZoneGroupTopology-Event for Zone "Sonos_BRIDGE".
     825 2017.02.09 06:21:03.940 3: SONOS1: Event: Received GroupRendering-Event for Zone "Sonos_Wohnzimmer".
     826 2017.02.09 06:21:03.941 3: SONOS1: Event: End of GroupRendering-Event for Zone "Sonos_Wohnzimmer".
     827 2017.02.09 06:21:03.954 3: SONOS1: Event: Received Alarm-Event for Zone "Sonos_Schlafzimmer".
     828 2017.02.09 06:21:03.955 3: SONOS1: Event: End of Alarm-Event for Zone "Sonos_Schlafzimmer".
     829 2017.02.09 06:21:03.968 3: SONOS1: Event: Received Alarm-Event for Zone "Sonos_Wohnzimmer".
     830 2017.02.09 06:21:03.969 3: SONOS1: Event: End of Alarm-Event for Zone "Sonos_Wohnzimmer".
     831 2017.02.09 06:21:03.984 3: SONOS1: Event: Received DeviceProperties-Event for Zone "Sonos_BRIDGE".
     832 2017.02.09 06:21:03.986 3: SONOS1: Event: End of DeviceProperties-Event for Zone "Sonos_BRIDGE".
     833 2017.02.09 06:21:03.999 3: SONOS1: Event: Received ZoneGroupTopology-Event for Zone "Sonos_Schlafzimmer".
     834 2017.02.09 06:21:04.009 3: SONOS1: Event: End of ZoneGroupTopology-Event for Zone "Sonos_Schlafzimmer".
     835 2017.02.09 06:21:04.025 3: SONOS1: Event: Received AudioIn-Event for Zone "Sonos_Wohnzimmer".
     836 2017.02.09 06:21:04.026 3: SONOS1: Event: End of AudioIn-Event for Zone "Sonos_Wohnzimmer".
     837 2017.02.09 06:21:04.043 3: SONOS1: Event: Received DeviceProperties-Event for Zone "Sonos_Schlafzimmer".
     838 2017.02.09 06:21:04.045 3: SONOS1: Event: End of DeviceProperties-Event for Zone "Sonos_Schlafzimmer".
     839 2017.02.09 06:21:04.056 3: SONOS1: Event: Received ZoneGroupTopology-Event for Zone "Sonos_Wohnzimmer".
     840 2017.02.09 06:21:04.067 3: SONOS1: Event: End of ZoneGroupTopology-Event for Zone "Sonos_Wohnzimmer".
     841 2017.02.09 06:21:04.080 3: SONOS1: Event: Received ContentDirectory-Event for Zone "Sonos_Schlafzimmer".
     842 2017.02.09 06:21:04.082 3: SONOS1: Event: End of ContentDirectory-Event for Zone "Sonos_Schlafzimmer".
     843 2017.02.09 06:21:04.101 3: SONOS1: Event: Received DeviceProperties-Event for Zone "Sonos_Wohnzimmer".
     844 2017.02.09 06:21:04.103 3: SONOS1: Event: End of DeviceProperties-Event for Zone "Sonos_Wohnzimmer".
     845 2017.02.09 06:21:04.112 3: SONOS1: Event: Received Rendering-Event for Zone "Sonos_Schlafzimmer".
     846 2017.02.09 06:21:04.127 3: SONOS1: Event: End of Rendering-Event for Zone "Sonos_Schlafzimmer".
     847 2017.02.09 06:21:04.138 3: SONOS1: Event: Received ContentDirectory-Event for Zone "Sonos_Wohnzimmer".
     848 2017.02.09 06:21:04.140 3: SONOS1: Event: End of ContentDirectory-Event for Zone "Sonos_Wohnzimmer".
     849 2017.02.09 06:21:04.165 3: SONOS1: Event: Received Rendering-Event for Zone "Sonos_Wohnzimmer".
     850 2017.02.09 06:21:04.206 3: SONOS1: Event: End of Rendering-Event for Zone "Sonos_Wohnzimmer".

Falls du mehr Logging aus dem Umfeld dieser Meldungen möchtest ... oder ich irgendwo noch etwas einbauen soll dann melde dich

Gruß Nobby

Nobby1805 · 12 Februar 2017, 14:39:13

Hallo Reiner,

heute ist wieder der Loop (siehe #7) aufgetreten ... der Logfile ist diesmal 890 MByte groß

Ich hatte ja in den Loop weitere Logs eingebaut weil es nicht ersichtlich war, warum überhaupt ohne Pause geloopt wird

So sieht es normalerweise alle 30 Sekunden aus

Code Auswählen

  739692 2017.02.12 13:00:24.497 3: SONOS0: vor foreach-loop
  739693 2017.02.12 13:00:24.515 3: SONOS0: nach can_read GLOB(0x198019c)
  739694 2017.02.12 13:00:24.515 3: SONOS0: vor foreach-loop
  739695 2017.02.12 13:00:24.515 3: SONOS0: foreach-loop GLOB(0x198019c) GLOB(0x198019c)
  739696 2017.02.12 13:00:24.516 3: SONOS0: Connection accepted from HomeServer:2540
  739697 2017.02.12 13:00:24.517 3: SONOS0: nach can_read GLOB(0x1c5395c)
  739698 2017.02.12 13:00:24.517 3: SONOS0: vor foreach-loop
  739699 2017.02.12 13:00:24.517 3: SONOS0: foreach-loop GLOB(0x1c5395c) GLOB(0x198019c)
  739700 2017.02.12 13:00:24.517 1: SONOS0: Received: 'hello'
  739701 2017.02.12 13:00:24.517 3: SONOS0: nach can_read GLOB(0x1c5395c)
  739702 2017.02.12 13:00:24.518 3: SONOS0: vor foreach-loop
  739703 2017.02.12 13:00:24.518 3: SONOS0: foreach-loop GLOB(0x1c5395c) GLOB(0x198019c)
  739704 2017.02.12 13:00:24.518 1: SONOS0: Received: 'goaway'
  739705 2017.02.12 13:00:25.512 3: SONOS0: nach can_read 
  739706 2017.02.12 13:00:25.512 3: SONOS0: vor foreach-loop

aber um 13:02 beginnt dann der Loop

Code Auswählen

  739832 2017.02.12 13:01:22.528 3: SONOS0: vor foreach-loop
  739833 2017.02.12 13:01:23.528 3: SONOS0: nach can_read 
  739834 2017.02.12 13:01:23.528 3: SONOS0: vor foreach-loop
    5411 2017.02.12 13:01:24.200 3: SONOS1: Event: Received Transport-Event for Zone "Sonos_Wohnzimmer".
  739835 2017.02.12 13:01:24.528 3: SONOS0: nach can_read 
  739836 2017.02.12 13:01:24.528 3: SONOS0: vor foreach-loop
  739837 2017.02.12 13:01:24.551 3: SONOS0: nach can_read GLOB(0x198019c)
  739838 2017.02.12 13:01:24.552 3: SONOS0: vor foreach-loop
  739839 2017.02.12 13:01:24.553 3: SONOS0: foreach-loop GLOB(0x198019c) GLOB(0x198019c)
  739840 2017.02.12 13:01:24.554 3: SONOS0: Connection accepted from HomeServer:2591
  739841 2017.02.12 13:01:24.555 3: SONOS0: nach can_read GLOB(0x1c4abfc)
  739842 2017.02.12 13:01:24.555 3: SONOS0: vor foreach-loop
  739843 2017.02.12 13:01:24.555 3: SONOS0: foreach-loop GLOB(0x1c4abfc) GLOB(0x198019c)
  739844 2017.02.12 13:01:24.556 1: SONOS0: Received: 'hello'
    5412 2017.02.12 13:01:24.545 3: SONOS1: Event: End of Transport-Event for Zone "Sonos_Wohnzimmer".
  739845 2017.02.12 13:01:24.558 3: SONOS0: nach can_read GLOB(0x1c4abfc)
  739846 2017.02.12 13:01:24.569 3: SONOS0: vor foreach-loop
  739847 2017.02.12 13:01:24.569 3: SONOS0: foreach-loop GLOB(0x1c4abfc) GLOB(0x198019c)
  739848 2017.02.12 13:01:24.569 3: SONOS0: nach can_read GLOB(0x1c4abfc)
  739849 2017.02.12 13:01:24.569 3: SONOS0: vor foreach-loop
  739850 2017.02.12 13:01:24.569 3: SONOS0: foreach-loop GLOB(0x1c4abfc) GLOB(0x198019c)
  739851 2017.02.12 13:01:24.570 3: SONOS0: nach can_read GLOB(0x1c4abfc)
  739852 2017.02.12 13:01:24.570 3: SONOS0: vor foreach-loop
  739853 2017.02.12 13:01:24.570 3: SONOS0: foreach-loop GLOB(0x1c4abfc) GLOB(0x198019c)
  739854 2017.02.12 13:01:24.570 3: SONOS0: nach can_read GLOB(0x1c4abfc)
  739855 2017.02.12 13:01:24.570 3: SONOS0: vor foreach-loop
  739856 2017.02.12 13:01:24.571 3: SONOS0: foreach-loop GLOB(0x1c4abfc) GLOB(0x198019c)
  739857 2017.02.12 13:01:24.571 3: SONOS0: nach can_read GLOB(0x1c4abfc)
  739858 2017.02.12 13:01:24.571 3: SONOS0: vor foreach-loop
  739859 2017.02.12 13:01:24.571 3: SONOS0: foreach-loop GLOB(0x1c4abfc) GLOB(0x198019c)
  739860 2017.02.12 13:01:24.571 3: SONOS0: nach can_read GLOB(0x1c4abfc)
  739861 2017.02.12 13:01:24.572 3: SONOS0: vor foreach-loop
  739862 2017.02.12 13:01:24.572 3: SONOS0: foreach-loop GLOB(0x1c4abfc) GLOB(0x198019c)
  739863 2017.02.12 13:01:24.572 3: SONOS0: nach can_read GLOB(0x1c4abfc)
  739864 2017.02.12 13:01:24.572 3: SONOS0: vor foreach-loop
  739865 2017.02.12 13:01:24.572 3: SONOS0: foreach-loop GLOB(0x1c4abfc) GLOB(0x198019c)
  739866 2017.02.12 13:01:24.573 3: SONOS0: nach can_read GLOB(0x1c4abfc)
  739867 2017.02.12 13:01:24.573 3: SONOS0: vor foreach-loop

Wie man sieht ist hier etwas zeitgleich von Sonos gekommen ... danach wird mit can_read immer wieder dasselbe gelesen und daher wirkt die Verzögerung (bei mir 1 Sekunde) dort nicht

Kannst du dort etwas erkennen ?

Gruß Nobby

Nobby1805 · 12 Februar 2017, 17:09:48

Hallo Reiner,

ich verstehe nicht was da passiert

Code Auswählen

			foreach my $so (@ready) {
		 			SONOS_Log undef, 3, "foreach-loop $so $sock";                                             ####Nobby
		 		if ($so == $sock) { # New Connection read

laut log ist $so ungleich $sock, also geht es im else-Zweig weiter

Code Auswählen

		 		} else { # Existing client calling
		 			my $inp = <$so>;
		 			
		 			if (defined($inp)) {
			 			# Abschließende Zeilenumbrüche abschnippeln
			 			$inp =~ s/[\r\n]*$//;
			 			
			 			# Consume and send evt. reply
			 			SONOS_Log undef, 1, "Received: '$inp'";        ##### Nobby
			 			SONOS_Log undef, 5, "Received: '$inp'";
			 			SONOS_Client_ConsumeMessage($so, $inp);
			 		}
		 		}

und da das Received-logging nicht kommt, kann nur $inp undefined sein ... aber ist das nicht ein Widerspruch wenn can_read meint es sind Daten da aber beim lesen dieser Daten dann nichts kommt?

Gruß Nobby

Reinerlein · 12 Februar 2017, 19:28:59

Hi Nobby,

genau das ist mein Problem... Ich bekomme ganz oft die Mitteilung, dass Daten zum Abholen bereit wären, und es kommen dann keine... Deswegen ständig diese Prüfungen darauf, ob etwas angekommen ist...
Normalerweise wird man nur einmal falsch getriggert, und muss somit nur diesen einen Trigger überstehen (da man den Socket ja auch ausliest), hier passiert es ständig wieder (in der Loop)...

Ich weiß echt nicht, was ich da machen soll...

Grüße
Reiner

Nobby1805 · 12 Februar 2017, 23:42:46

Hallo Reiner,

Ok ... ich lese mich in die Thematik mal ein bisschen tiefer ein

Kurzfristig baue ich erst mal noch ein paar weitere Logs ein ... mal schauen was dann beim nächsten mal angezeigt wird

Gruß Nobby

Nobby1805 · 13 Februar 2017, 22:30:07

Hallo Reiner,

ich habe mal etwas geforscht, kennst du dies?

https://techblog.ralph-schuster.eu/2015/06/19/nonblocking-sockets-and-perls-netdaemon/

http://www.perlmonks.org/bare/?node_id=427056

wenn ich in den Log schaue, dann wird das "Hello" gelesen und bearbeitet, aber das folgende "goaway" führt dann zu dem Problem
und jetzt spekuliere ich mal

Code Auswählen

		$socket->send("hello\n", 0);
		$socket->recv($answer, 500);
		
		$socket->send("goaway\n", 0);
		
		$socket->shutdown(2);
		$socket->close();

Das "goaway" wird gesendet, kann aber nicht gelesen werden weil der Transport-Event im anderen Thread bearbeitet wird ... und dann wird der Sendesocket runtergefahren und geschlossen ... und dann will der Thread lesen, bekommt dann aber eine Fehler weil der socket disconnected ist.

Wenn das so ist, dann würde das helfen was in Ralphs-Blog beschrieben ist ... was meinst du? Oder man baut etwas warten zwischen send-goaway und shutdown ein

Gruß Nobby